builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0380 starttime: 1493069110.29 results: success (0) buildid: 20170424081926 builduid: 2e64723bad2248bc812a9e6908a27ddb revision: 0bee350a1c5dc4aaece2bd3d2947c45e88d4a045 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-24 14:25:10.292379) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-24 14:25:10.292860) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-24 14:25:10.293217) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-24 14:25:10.321808) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 14:25:10.322298) ========= 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.zOa905mLRS/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.OV69OXjmmV/Listeners TMPDIR=/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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-24 14:25:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.4M=0.001s 2017-04-24 14:25:10 (18.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.137186 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 14:25:10.483133) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 14:25:10.483583) ========= 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.zOa905mLRS/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.OV69OXjmmV/Listeners TMPDIR=/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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.038002 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 14:25:10.545305) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-24 14:25:10.545706) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev 0bee350a1c5dc4aaece2bd3d2947c45e88d4a045 --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 0bee350a1c5dc4aaece2bd3d2947c45e88d4a045 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zOa905mLRS/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.OV69OXjmmV/Listeners TMPDIR=/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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-24 14:25:10,651 truncating revision to first 12 chars 2017-04-24 14:25:10,651 Setting DEBUG logging. 2017-04-24 14:25:10,651 attempt 1/10 2017-04-24 14:25:10,651 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/0bee350a1c5d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-24 14:25:11,327 unpacking tar archive at: pine-0bee350a1c5d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.943257 ========= master_lag: 0.11 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-24 14:25:11.599436) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-24 14:25:11.599798) ========= script_repo_revision: 0bee350a1c5dc4aaece2bd3d2947c45e88d4a045 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-24 14:25:11.600256) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-24 14:25:11.600559) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-24 14:25:11.621652) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-04-24 14:25:11.621976) ========= /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.zOa905mLRS/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.OV69OXjmmV/Listeners TMPDIR=/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 14:25:11 INFO - MultiFileLogger online at 20170424 14:25:11 in /builds/slave/test 14:25:11 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 14:25:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:25:11 INFO - {'append_to_log': False, 14:25:11 INFO - 'base_work_dir': '/builds/slave/test', 14:25:11 INFO - 'blob_upload_branch': 'Pine', 14:25:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:25:11 INFO - 'branch': 'Pine', 14:25:11 INFO - 'buildbot_json_path': 'buildprops.json', 14:25:11 INFO - 'config_files': ('talos/mac_config.py',), 14:25:11 INFO - 'default_actions': ('clobber', 14:25:11 INFO - 'read-buildbot-config', 14:25:11 INFO - 'download-and-extract', 14:25:11 INFO - 'populate-webroot', 14:25:11 INFO - 'create-virtualenv', 14:25:11 INFO - 'install', 14:25:11 INFO - 'run-tests'), 14:25:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:25:11 INFO - 'download_minidump_stackwalk': True, 14:25:11 INFO - 'download_symbols': 'ondemand', 14:25:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:25:11 INFO - 'tooltool.py': '/tools/tooltool.py', 14:25:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:25:11 INFO - '/tools/misc-python/virtualenv.py')}, 14:25:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:25:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:25:11 INFO - 'gecko_profile': False, 14:25:11 INFO - 'gecko_profile_interval': 0, 14:25:11 INFO - 'installer_path': 'installer.exe', 14:25:11 INFO - 'log_level': 'info', 14:25:11 INFO - 'log_name': 'talos', 14:25:11 INFO - 'log_to_console': True, 14:25:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:25:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:25:11 INFO - 'opt_config_files': (), 14:25:11 INFO - 'pip_index': False, 14:25:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:25:11 INFO - 'cmd': ('bash', 14:25:11 INFO - '-c', 14:25:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:25:11 INFO - 'enabled': True, 14:25:11 INFO - 'halt_on_failure': False, 14:25:11 INFO - 'name': 'check_screen_resolution'},), 14:25:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:25:11 INFO - 'cmd': ('bash', 14:25:11 INFO - '-c', 14:25:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:25:11 INFO - 'enabled': True, 14:25:11 INFO - 'halt_on_failure': False, 14:25:11 INFO - 'name': 'check_screen_resolution'},), 14:25:11 INFO - 'run_cmd_checks_enabled': True, 14:25:11 INFO - 'suite': 'dromaeojs-e10s', 14:25:11 INFO - 'system_bits': '32', 14:25:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:25:11 INFO - 'title': 't-yosemite-r7-0380', 14:25:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:25:11 INFO - 'use_talos_json': True, 14:25:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:25:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:25:11 INFO - 'work_dir': 'build'} 14:25:11 INFO - [mozharness: 2017-04-24 21:25:11.835358Z] Running clobber step. 14:25:11 INFO - Running pre-action listener: _resource_record_pre_action 14:25:11 INFO - Running main action method: clobber 14:25:11 INFO - rmtree: /builds/slave/test/build 14:25:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:25:14 INFO - Running post-action listener: _resource_record_post_action 14:25:14 INFO - [mozharness: 2017-04-24 21:25:14.301622Z] Finished clobber step (success) 14:25:14 INFO - [mozharness: 2017-04-24 21:25:14.301755Z] Running read-buildbot-config step. 14:25:14 INFO - Running pre-action listener: _resource_record_pre_action 14:25:14 INFO - Running main action method: read_buildbot_config 14:25:14 INFO - Using buildbot properties: 14:25:14 INFO - { 14:25:14 INFO - "project": "", 14:25:14 INFO - "product": "firefox", 14:25:14 INFO - "script_repo_revision": "default", 14:25:14 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 14:25:14 INFO - "repository": "", 14:25:14 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 14:25:14 INFO - "buildid": "20170424081926", 14:25:14 INFO - "pgo_build": "False", 14:25:14 INFO - "basedir": "/builds/slave/test", 14:25:14 INFO - "buildnumber": 2, 14:25:14 INFO - "slavename": "t-yosemite-r7-0380", 14:25:14 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 14:25:14 INFO - "platform": "yosemite_r7", 14:25:14 INFO - "scheduler": "tests-pine-macosx64-talos", 14:25:14 INFO - "branch": "pine", 14:25:14 INFO - "repo_path": "projects/pine", 14:25:14 INFO - "revision": "0bee350a1c5dc4aaece2bd3d2947c45e88d4a045", 14:25:14 INFO - "stage_platform": "macosx64", 14:25:14 INFO - "builduid": "2e64723bad2248bc812a9e6908a27ddb", 14:25:14 INFO - "slavebuilddir": "test" 14:25:14 INFO - } 14:25:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/E5FG0YYUSHqsPXo0gAcHAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 14:25:14 INFO - Running post-action listener: _resource_record_post_action 14:25:14 INFO - [mozharness: 2017-04-24 21:25:14.305258Z] Finished read-buildbot-config step (success) 14:25:14 INFO - [mozharness: 2017-04-24 21:25:14.305372Z] Running download-and-extract step. 14:25:14 INFO - Running pre-action listener: _resource_record_pre_action 14:25:14 INFO - Running main action method: download_and_extract 14:25:14 INFO - mkdir: /builds/slave/test/build/tests 14:25:14 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')]} 14:25:14 INFO - trying https://queue.taskcluster.net/v1/task/E5FG0YYUSHqsPXo0gAcHAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 14:25:14 INFO - Downloading https://queue.taskcluster.net/v1/task/E5FG0YYUSHqsPXo0gAcHAQ/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 14:25:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E5FG0YYUSHqsPXo0gAcHAQ/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 14:25:14 INFO - Downloaded 1325 bytes. 14:25:14 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 14:25:14 INFO - Using the following test package requirements: 14:25:14 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:25:14 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 14:25:14 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:25:14 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:25:14 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 14:25:14 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:25:14 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 14:25:14 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:25:14 INFO - u'jsshell-mac.zip'], 14:25:14 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:25:14 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 14:25:14 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:25:14 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:25:14 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 14:25:14 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:25:14 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 14:25:14 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:25:14 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 14:25:14 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:25:14 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 14:25:14 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:25:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E5FG0YYUSHqsPXo0gAcHAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:25:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E5FG0YYUSHqsPXo0gAcHAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:25:14 INFO - Fetch https://queue.taskcluster.net/v1/task/E5FG0YYUSHqsPXo0gAcHAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:25:16 INFO - Content-Length response header: 52524681 14:25:16 INFO - Bytes received: 52524681 14:25:32 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 14:25:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E5FG0YYUSHqsPXo0gAcHAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:25:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E5FG0YYUSHqsPXo0gAcHAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:25:32 INFO - Fetch https://queue.taskcluster.net/v1/task/E5FG0YYUSHqsPXo0gAcHAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:25:34 INFO - Content-Length response header: 52524681 14:25:34 INFO - Bytes received: 52524681 14:25:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E5FG0YYUSHqsPXo0gAcHAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 14:25:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E5FG0YYUSHqsPXo0gAcHAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:25:49 INFO - Fetch https://queue.taskcluster.net/v1/task/E5FG0YYUSHqsPXo0gAcHAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 14:25:50 INFO - Content-Length response header: 14408822 14:25:50 INFO - Bytes received: 14408822 14:25:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:50 INFO - trying https://queue.taskcluster.net/v1/task/E5FG0YYUSHqsPXo0gAcHAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:25:50 INFO - Downloading https://queue.taskcluster.net/v1/task/E5FG0YYUSHqsPXo0gAcHAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 14:25:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E5FG0YYUSHqsPXo0gAcHAQ/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 14:25:52 INFO - Downloaded 71175253 bytes. 14:25:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E5FG0YYUSHqsPXo0gAcHAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:25:52 INFO - mkdir: /builds/slave/test/properties 14:25:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:25:52 INFO - Writing to file /builds/slave/test/properties/build_url 14:25:52 INFO - Contents: 14:25:52 INFO - build_url:https://queue.taskcluster.net/v1/task/E5FG0YYUSHqsPXo0gAcHAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:25:53 INFO - Running post-action listener: _resource_record_post_action 14:25:53 INFO - Running post-action listener: set_extra_try_arguments 14:25:53 INFO - [mozharness: 2017-04-24 21:25:53.703751Z] Finished download-and-extract step (success) 14:25:53 INFO - [mozharness: 2017-04-24 21:25:53.703891Z] Running populate-webroot step. 14:25:53 INFO - Running pre-action listener: _resource_record_pre_action 14:25:53 INFO - Running main action method: populate_webroot 14:25:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:25:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:25:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:25:53 INFO - '--tppagecycles', 14:25:53 INFO - '7'], 14:25:53 INFO - 'tests': ['tsvgm']}}, 14:25:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:25:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:25:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:25:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:25:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:25:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:25:53 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:25:53 INFO - 'talos_options': ['--disable-e10s'], 14:25:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:25:53 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:25:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:25:53 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:25:53 INFO - 'talos_options': ['--disable-e10s'], 14:25:53 INFO - 'tests': ['damp', 'tps']}, 14:25:53 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:25:53 INFO - 'tests': ['damp', 'tps']}, 14:25:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:25:53 INFO - 'tests': ['dromaeo_dom']}, 14:25:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:25:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:25:53 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 14:25:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 14:25:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:25:53 INFO - 'tests': ['a11yr', 14:25:53 INFO - 'ts_paint', 14:25:53 INFO - 'tpaint', 14:25:53 INFO - 'sessionrestore', 14:25:53 INFO - 'sessionrestore_no_auto_restore', 14:25:53 INFO - 'tabpaint']}, 14:25:53 INFO - 'other-e10s': {'tests': ['a11yr', 14:25:53 INFO - 'ts_paint', 14:25:53 INFO - 'tpaint', 14:25:53 INFO - 'sessionrestore', 14:25:53 INFO - 'sessionrestore_no_auto_restore', 14:25:53 INFO - 'tabpaint']}, 14:25:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:25:53 INFO - 'ts_paint', 14:25:53 INFO - 'tpaint', 14:25:53 INFO - 'sessionrestore', 14:25:53 INFO - 'sessionrestore_no_auto_restore', 14:25:53 INFO - 'tabpaint']}, 14:25:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:25:53 INFO - 'ts_paint', 14:25:53 INFO - 'tpaint', 14:25:53 INFO - 'sessionrestore', 14:25:53 INFO - 'sessionrestore_no_auto_restore', 14:25:53 INFO - 'tabpaint']}, 14:25:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:25:53 INFO - 'tests': ['a11yr', 14:25:53 INFO - 'ts_paint', 14:25:53 INFO - 'tpaint', 14:25:53 INFO - 'sessionrestore', 14:25:53 INFO - 'sessionrestore_no_auto_restore', 14:25:53 INFO - 'tabpaint']}, 14:25:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:25:53 INFO - 'tests': ['a11yr', 14:25:53 INFO - 'ts_paint', 14:25:53 INFO - 'tpaint', 14:25:53 INFO - 'sessionrestore', 14:25:53 INFO - 'sessionrestore_no_auto_restore', 14:25:53 INFO - 'tabpaint']}, 14:25:53 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 14:25:53 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 14:25:53 INFO - 'bloom_basic_ref']}, 14:25:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:25:53 INFO - 'tests': ['tsvgx', 14:25:53 INFO - 'tsvgr_opacity', 14:25:53 INFO - 'tart', 14:25:53 INFO - 'tscrollx', 14:25:53 INFO - 'cart', 14:25:53 INFO - 'tsvg_static']}, 14:25:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:25:53 INFO - 'tsvgr_opacity', 14:25:53 INFO - 'tart', 14:25:53 INFO - 'tscrollx', 14:25:53 INFO - 'cart', 14:25:53 INFO - 'tsvg_static']}, 14:25:53 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:25:53 INFO - 'talos_options': ['--disable-e10s'], 14:25:53 INFO - 'tests': ['tp5o']}, 14:25:53 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:25:53 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:25:53 INFO - 'talos_options': ['--disable-e10s', 14:25:53 INFO - '--xperf_path', 14:25:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:25:53 INFO - 'tests': ['tp5n']}, 14:25:53 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:25:53 INFO - 'talos_options': ['--xperf_path', 14:25:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:25:53 INFO - 'tests': ['tp5n']}}, 14:25:53 INFO - 'talos.zip': {'path': '', 14:25:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:25:53 INFO - Running post-action listener: _resource_record_post_action 14:25:53 INFO - [mozharness: 2017-04-24 21:25:53.723205Z] Finished populate-webroot step (success) 14:25:53 INFO - [mozharness: 2017-04-24 21:25:53.723336Z] Running create-virtualenv step. 14:25:53 INFO - Running pre-action listener: _resource_record_pre_action 14:25:53 INFO - Running main action method: create_virtualenv 14:25:53 INFO - Creating virtualenv /builds/slave/test/build/venv 14:25:53 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 14:25:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:25:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:25:53 INFO - Using real prefix '/tools/python27' 14:25:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:25:54 INFO - Installing distribute.............................................................................................................................................................................................done. 14:25:57 INFO - Installing pip.................done. 14:25:57 INFO - Return code: 0 14:25:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:25:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:25:57 INFO - Reading from file tmpfile_stdout 14:25:57 INFO - Output received: 14:25:57 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) 14:25:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:25:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:25:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:25:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:25:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:25:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cc60b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc63768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ef0469df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca3ed30>, '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 0x10c964db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca33be8>, '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.zOa905mLRS/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.OV69OXjmmV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 14:25:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:25:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:25:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zOa905mLRS/Render', 14:25:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:25:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:25:57 INFO - 'HOME': '/Users/cltbld', 14:25:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:25:57 INFO - 'LOGNAME': 'cltbld', 14:25:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:25:57 INFO - 'MOZ_NO_REMOTE': '1', 14:25:57 INFO - 'NO_EM_RESTART': '1', 14:25:57 INFO - 'PAGER': '/bin/cat', 14:25:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:25:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:25:57 INFO - 'PWD': '/builds/slave/test', 14:25:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:25:57 INFO - 'SHELL': '/bin/bash', 14:25:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OV69OXjmmV/Listeners', 14:25:57 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 14:25:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:25:57 INFO - 'USER': 'cltbld', 14:25:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:25:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:25:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:25:57 INFO - 'XPC_FLAGS': '0x0', 14:25:57 INFO - 'XPC_SERVICE_NAME': '0', 14:25:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:25:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:25:58 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 14:25:58 INFO - Cleaning up... 14:25:58 INFO - Return code: 0 14:25:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:25:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:25:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:25:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:25:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:25:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cc60b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc63768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ef0469df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca3ed30>, '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 0x10c964db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca33be8>, '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.zOa905mLRS/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.OV69OXjmmV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 14:25:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:25:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:25:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zOa905mLRS/Render', 14:25:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:25:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:25:58 INFO - 'HOME': '/Users/cltbld', 14:25:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:25:58 INFO - 'LOGNAME': 'cltbld', 14:25:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:25:58 INFO - 'MOZ_NO_REMOTE': '1', 14:25:58 INFO - 'NO_EM_RESTART': '1', 14:25:58 INFO - 'PAGER': '/bin/cat', 14:25:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:25:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:25:58 INFO - 'PWD': '/builds/slave/test', 14:25:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:25:58 INFO - 'SHELL': '/bin/bash', 14:25:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OV69OXjmmV/Listeners', 14:25:58 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 14:25:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:25:58 INFO - 'USER': 'cltbld', 14:25:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:25:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:25:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:25:58 INFO - 'XPC_FLAGS': '0x0', 14:25:58 INFO - 'XPC_SERVICE_NAME': '0', 14:25:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:25:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:25:58 INFO - Downloading/unpacking psutil>=3.1.1 14:25:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:25:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:25:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:25:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:25:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:25:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:26:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:26:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:26:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:26:01 INFO - Installing collected packages: psutil 14:26:01 INFO - Running setup.py install for psutil 14:26:02 INFO - building 'psutil._psutil_osx' extension 14:26:02 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 14:26:02 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 14:26:02 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 14:26:02 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 14:26:02 INFO - building 'psutil._psutil_posix' extension 14:26:02 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 14:26:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:26:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:26:02 INFO - ^ 14:26:02 INFO - 1 warning generated. 14:26:02 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 14:26:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:26:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:26:03 INFO - Successfully installed psutil 14:26:03 INFO - Cleaning up... 14:26:03 INFO - Return code: 0 14:26:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:26:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cc60b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc63768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ef0469df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca3ed30>, '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 0x10c964db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca33be8>, '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.zOa905mLRS/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.OV69OXjmmV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 14:26:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:26:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:26:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zOa905mLRS/Render', 14:26:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:03 INFO - 'HOME': '/Users/cltbld', 14:26:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:03 INFO - 'LOGNAME': 'cltbld', 14:26:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:03 INFO - 'MOZ_NO_REMOTE': '1', 14:26:03 INFO - 'NO_EM_RESTART': '1', 14:26:03 INFO - 'PAGER': '/bin/cat', 14:26:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:03 INFO - 'PWD': '/builds/slave/test', 14:26:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:03 INFO - 'SHELL': '/bin/bash', 14:26:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OV69OXjmmV/Listeners', 14:26:03 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 14:26:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:03 INFO - 'USER': 'cltbld', 14:26:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:03 INFO - 'XPC_FLAGS': '0x0', 14:26:03 INFO - 'XPC_SERVICE_NAME': '0', 14:26:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:26:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:26:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:26:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:26:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:26:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:26:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:26:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:26:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:26:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:26:06 INFO - Installing collected packages: mozsystemmonitor 14:26:06 INFO - Running setup.py install for mozsystemmonitor 14:26:06 INFO - Successfully installed mozsystemmonitor 14:26:06 INFO - Cleaning up... 14:26:06 INFO - Return code: 0 14:26:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:26:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:07 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 0x10cc60b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc63768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ef0469df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca3ed30>, '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 0x10c964db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca33be8>, '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.zOa905mLRS/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.OV69OXjmmV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 14:26:07 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 14:26:07 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 14:26:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zOa905mLRS/Render', 14:26:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:07 INFO - 'HOME': '/Users/cltbld', 14:26:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:07 INFO - 'LOGNAME': 'cltbld', 14:26:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:07 INFO - 'MOZ_NO_REMOTE': '1', 14:26:07 INFO - 'NO_EM_RESTART': '1', 14:26:07 INFO - 'PAGER': '/bin/cat', 14:26:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:07 INFO - 'PWD': '/builds/slave/test', 14:26:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:07 INFO - 'SHELL': '/bin/bash', 14:26:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OV69OXjmmV/Listeners', 14:26:07 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 14:26:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:07 INFO - 'USER': 'cltbld', 14:26:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:07 INFO - 'XPC_FLAGS': '0x0', 14:26:07 INFO - 'XPC_SERVICE_NAME': '0', 14:26:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:07 INFO - Downloading/unpacking jsonschema==2.5.1 14:26:07 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 14:26:07 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 14:26:07 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 14:26:07 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 14:26:07 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 14:26:07 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 14:26:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:26:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:26:10 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 14:26:10 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 14:26:10 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 14:26:10 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 14:26:10 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 14:26:10 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 14:26:10 INFO - Downloading functools32-3.2.3-2.tar.gz 14:26:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:26:11 INFO - warning: no files found matching '*.txt' 14:26:11 INFO - no previously-included directories found matching 'build' 14:26:11 INFO - no previously-included directories found matching 'dist' 14:26:11 INFO - no previously-included directories found matching '.git*' 14:26:11 INFO - Installing collected packages: jsonschema, functools32 14:26:11 INFO - Running setup.py install for functools32 14:26:11 INFO - warning: no files found matching '*.txt' 14:26:11 INFO - no previously-included directories found matching 'build' 14:26:11 INFO - no previously-included directories found matching 'dist' 14:26:11 INFO - no previously-included directories found matching '.git*' 14:26:11 INFO - Successfully installed jsonschema functools32 14:26:11 INFO - Cleaning up... 14:26:11 INFO - Return code: 0 14:26:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:26:11 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')]} 14:26:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:11 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')]} 14:26:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:11 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 0x10cc60b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc63768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ef0469df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca3ed30>, '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 0x10c964db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca33be8>, '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.zOa905mLRS/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.OV69OXjmmV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 14:26:11 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 14:26:11 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 14:26:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zOa905mLRS/Render', 14:26:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:11 INFO - 'HOME': '/Users/cltbld', 14:26:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:11 INFO - 'LOGNAME': 'cltbld', 14:26:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:11 INFO - 'MOZ_NO_REMOTE': '1', 14:26:11 INFO - 'NO_EM_RESTART': '1', 14:26:11 INFO - 'PAGER': '/bin/cat', 14:26:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:11 INFO - 'PWD': '/builds/slave/test', 14:26:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:11 INFO - 'SHELL': '/bin/bash', 14:26:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OV69OXjmmV/Listeners', 14:26:11 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 14:26:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:11 INFO - 'USER': 'cltbld', 14:26:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:11 INFO - 'XPC_FLAGS': '0x0', 14:26:11 INFO - 'XPC_SERVICE_NAME': '0', 14:26:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:26:11 INFO - Cleaning up... 14:26:11 INFO - Return code: 0 14:26:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:26:11 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')]} 14:26:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:11 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')]} 14:26:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:11 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 0x10cc60b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc63768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ef0469df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca3ed30>, '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 0x10c964db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca33be8>, '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.zOa905mLRS/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.OV69OXjmmV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 14:26:11 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 14:26:11 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 14:26:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zOa905mLRS/Render', 14:26:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:11 INFO - 'HOME': '/Users/cltbld', 14:26:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:11 INFO - 'LOGNAME': 'cltbld', 14:26:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:11 INFO - 'MOZ_NO_REMOTE': '1', 14:26:11 INFO - 'NO_EM_RESTART': '1', 14:26:11 INFO - 'PAGER': '/bin/cat', 14:26:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:11 INFO - 'PWD': '/builds/slave/test', 14:26:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:11 INFO - 'SHELL': '/bin/bash', 14:26:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OV69OXjmmV/Listeners', 14:26:11 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 14:26:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:11 INFO - 'USER': 'cltbld', 14:26:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:11 INFO - 'XPC_FLAGS': '0x0', 14:26:11 INFO - 'XPC_SERVICE_NAME': '0', 14:26:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:11 INFO - Downloading/unpacking blobuploader==1.2.4 14:26:11 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 14:26:11 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 14:26:11 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 14:26:11 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 14:26:11 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 14:26:11 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 14:26:14 INFO - Downloading blobuploader-1.2.4.tar.gz 14:26:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:26:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:26:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:26:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:26:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:26:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:26:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:26:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:26:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:26:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:26:15 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 14:26:15 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 14:26:15 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 14:26:15 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 14:26:15 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 14:26:15 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 14:26:15 INFO - Downloading docopt-0.6.1.tar.gz 14:26:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:26:15 INFO - Installing collected packages: blobuploader, requests, docopt 14:26:15 INFO - Running setup.py install for blobuploader 14:26:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:26:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:26:16 INFO - Running setup.py install for requests 14:26:16 INFO - Running setup.py install for docopt 14:26:16 INFO - Successfully installed blobuploader requests docopt 14:26:16 INFO - Cleaning up... 14:26:16 INFO - Return code: 0 14:26:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:26:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:16 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 0x10cc60b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc63768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ef0469df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca3ed30>, '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 0x10c964db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca33be8>, '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.zOa905mLRS/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.OV69OXjmmV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 14:26:16 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 14:26:16 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 14:26:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zOa905mLRS/Render', 14:26:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:16 INFO - 'HOME': '/Users/cltbld', 14:26:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:16 INFO - 'LOGNAME': 'cltbld', 14:26:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:16 INFO - 'MOZ_NO_REMOTE': '1', 14:26:16 INFO - 'NO_EM_RESTART': '1', 14:26:16 INFO - 'PAGER': '/bin/cat', 14:26:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:16 INFO - 'PWD': '/builds/slave/test', 14:26:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:16 INFO - 'SHELL': '/bin/bash', 14:26:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OV69OXjmmV/Listeners', 14:26:16 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 14:26:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:16 INFO - 'USER': 'cltbld', 14:26:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:16 INFO - 'XPC_FLAGS': '0x0', 14:26:16 INFO - 'XPC_SERVICE_NAME': '0', 14:26:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:26:17 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-6VunU_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:26:17 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-ws77FC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:26:17 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-fWhXgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:26:17 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-7q3ss8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:26:17 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-VTH7vw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:26:17 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-_JrU7O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:26:17 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-DlgD2B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:26:17 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-HkchLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:26:17 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-hv2esb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:26:18 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-Ivjx6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:26:18 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-1Hlugu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:26:18 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-odzk17-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:26:18 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-j9dT39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:26:18 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-G6yGlx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:26:18 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-NA5dyr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:26:18 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-wdFVEF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:26:18 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-yFyKqr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:26:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:26:18 INFO - Running setup.py install for manifestparser 14:26:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:26:19 INFO - Running setup.py install for mozcrash 14:26:19 INFO - Running setup.py install for mozdebug 14:26:19 INFO - Running setup.py install for mozdevice 14:26:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:26:19 INFO - Running setup.py install for mozfile 14:26:19 INFO - Running setup.py install for mozhttpd 14:26:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:26:19 INFO - Running setup.py install for mozinfo 14:26:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:26:19 INFO - Running setup.py install for mozInstall 14:26:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:26:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:26:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:26:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:26:20 INFO - Running setup.py install for mozleak 14:26:20 INFO - Running setup.py install for mozlog 14:26:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:26:20 INFO - Running setup.py install for moznetwork 14:26:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:26:20 INFO - Running setup.py install for mozprocess 14:26:20 INFO - Running setup.py install for mozprofile 14:26:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:26:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:26:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:26:20 INFO - Running setup.py install for mozrunner 14:26:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:26:21 INFO - Running setup.py install for mozscreenshot 14:26:21 INFO - Running setup.py install for moztest 14:26:21 INFO - Running setup.py install for mozversion 14:26:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:26:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:26:21 INFO - Cleaning up... 14:26:21 INFO - Return code: 0 14:26:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:26:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:21 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 0x10cc60b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc63768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ef0469df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca3ed30>, '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 0x10c964db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca33be8>, '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.zOa905mLRS/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.OV69OXjmmV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 14:26:21 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 14:26:21 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 14:26:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zOa905mLRS/Render', 14:26:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:21 INFO - 'HOME': '/Users/cltbld', 14:26:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:21 INFO - 'LOGNAME': 'cltbld', 14:26:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:21 INFO - 'MOZ_NO_REMOTE': '1', 14:26:21 INFO - 'NO_EM_RESTART': '1', 14:26:21 INFO - 'PAGER': '/bin/cat', 14:26:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:21 INFO - 'PWD': '/builds/slave/test', 14:26:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:21 INFO - 'SHELL': '/bin/bash', 14:26:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OV69OXjmmV/Listeners', 14:26:21 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 14:26:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:21 INFO - 'USER': 'cltbld', 14:26:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:21 INFO - 'XPC_FLAGS': '0x0', 14:26:21 INFO - 'XPC_SERVICE_NAME': '0', 14:26:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:26:21 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-eTmgXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:26:21 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)) 14:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:26:21 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-yZnqAN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:26:21 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)) 14:26:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:26:21 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-Asl9wn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:26:22 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)) 14:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:26:22 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-3H0AT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:26:22 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)) 14:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:26:22 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-_5FkSa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:26:22 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)) 14:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:26:22 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-ZYXXGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:26:22 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)) 14:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:26:22 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-Se64r3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:26:22 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)) 14:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:26:22 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-4V148k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:26:22 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)) 14:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:26:22 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-WuG3Dj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:26:22 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)) 14:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:26:22 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-RR0D6_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:26:22 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)) 14:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:26:22 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-bfS8EV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:26:22 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)) 14:26:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:26:22 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-Q9EGTY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:26:23 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)) 14:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:26:23 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-OZjSR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:26:23 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)) 14:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:26:23 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-rxa6lc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:26:23 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)) 14:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:26:23 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-wFFOXv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:26:23 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)) 14:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:26:23 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-GbthMn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:26:23 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)) 14:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:26:23 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-FLqElB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:26:23 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)) 14:26:23 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)) 14:26:23 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)) 14:26:23 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)) 14:26:23 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)) 14:26:23 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)) 14:26:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:26:23 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 14:26:23 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 14:26:23 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 14:26:23 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 14:26:23 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 14:26:23 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 14:26:26 INFO - Downloading blessings-1.6.tar.gz 14:26:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:26:26 INFO - Installing collected packages: blessings 14:26:26 INFO - Running setup.py install for blessings 14:26:26 INFO - Successfully installed blessings 14:26:26 INFO - Cleaning up... 14:26:26 INFO - Return code: 0 14:26:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:26:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:26:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:26:26 INFO - Reading from file tmpfile_stdout 14:26:26 INFO - Current package versions: 14:26:26 INFO - blessings == 1.6 14:26:26 INFO - blobuploader == 1.2.4 14:26:26 INFO - docopt == 0.6.1 14:26:26 INFO - functools32 == 3.2.3-2 14:26:26 INFO - jsonschema == 2.5.1 14:26:26 INFO - manifestparser == 1.1 14:26:26 INFO - mozInstall == 1.12 14:26:26 INFO - mozcrash == 1.0 14:26:26 INFO - mozdebug == 0.1 14:26:26 INFO - mozdevice == 0.50 14:26:26 INFO - mozfile == 1.2 14:26:26 INFO - mozhttpd == 0.7 14:26:26 INFO - mozinfo == 0.9 14:26:26 INFO - mozleak == 0.1 14:26:26 INFO - mozlog == 3.4 14:26:26 INFO - moznetwork == 0.27 14:26:26 INFO - mozprocess == 0.25 14:26:26 INFO - mozprofile == 0.28 14:26:26 INFO - mozrunner == 6.13 14:26:26 INFO - mozscreenshot == 0.1 14:26:26 INFO - mozsystemmonitor == 0.3 14:26:26 INFO - moztest == 0.8 14:26:26 INFO - mozversion == 1.4 14:26:26 INFO - psutil == 3.1.1 14:26:26 INFO - requests == 1.2.3 14:26:26 INFO - wsgiref == 0.1.2 14:26:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:26:26 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')]} 14:26:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:26 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')]} 14:26:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:26 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 0x10cc60b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc63768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ef0469df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca3ed30>, '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 0x10c964db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca33be8>, '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.zOa905mLRS/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.OV69OXjmmV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 14:26:26 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 14:26:26 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 14:26:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zOa905mLRS/Render', 14:26:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:26 INFO - 'HOME': '/Users/cltbld', 14:26:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:26 INFO - 'LOGNAME': 'cltbld', 14:26:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:26 INFO - 'MOZ_NO_REMOTE': '1', 14:26:26 INFO - 'NO_EM_RESTART': '1', 14:26:26 INFO - 'PAGER': '/bin/cat', 14:26:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:26 INFO - 'PWD': '/builds/slave/test', 14:26:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:26 INFO - 'SHELL': '/bin/bash', 14:26:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OV69OXjmmV/Listeners', 14:26:26 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 14:26:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:26 INFO - 'USER': 'cltbld', 14:26:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:26 INFO - 'XPC_FLAGS': '0x0', 14:26:26 INFO - 'XPC_SERVICE_NAME': '0', 14:26:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:27 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)) 14:26:27 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)) 14:26:27 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)) 14:26:27 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)) 14:26:27 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)) 14:26:27 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)) 14:26:27 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)) 14:26:27 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)) 14:26:27 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)) 14:26:27 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)) 14:26:27 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)) 14:26:27 INFO - Cleaning up... 14:26:27 INFO - Return code: 0 14:26:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:26:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:27 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 0x10cc60b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc63768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ef0469df0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca3ed30>, '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 0x10c964db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca33be8>, '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.zOa905mLRS/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.OV69OXjmmV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 14:26:27 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 14:26:27 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 14:26:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zOa905mLRS/Render', 14:26:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:27 INFO - 'HOME': '/Users/cltbld', 14:26:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:27 INFO - 'LOGNAME': 'cltbld', 14:26:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:27 INFO - 'MOZ_NO_REMOTE': '1', 14:26:27 INFO - 'NO_EM_RESTART': '1', 14:26:27 INFO - 'PAGER': '/bin/cat', 14:26:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:27 INFO - 'PWD': '/builds/slave/test', 14:26:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:27 INFO - 'SHELL': '/bin/bash', 14:26:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OV69OXjmmV/Listeners', 14:26:27 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 14:26:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:27 INFO - 'USER': 'cltbld', 14:26:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:27 INFO - 'XPC_FLAGS': '0x0', 14:26:27 INFO - 'XPC_SERVICE_NAME': '0', 14:26:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:26:27 INFO - Cleaning up... 14:26:27 INFO - Return code: 0 14:26:27 INFO - Running post-action listener: _resource_record_post_action 14:26:27 INFO - Running post-action listener: _start_resource_monitoring 14:26:27 INFO - Starting resource monitoring. 14:26:27 INFO - [mozharness: 2017-04-24 21:26:27.446097Z] Finished create-virtualenv step (success) 14:26:27 INFO - [mozharness: 2017-04-24 21:26:27.447106Z] Running install step. 14:26:27 INFO - Running pre-action listener: _resource_record_pre_action 14:26:27 INFO - Running main action method: install 14:26:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:26:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:26:27 INFO - Reading from file tmpfile_stdout 14:26:27 INFO - Detecting whether we're running mozinstall >=1.0... 14:26:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:26:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:26:27 INFO - Reading from file tmpfile_stdout 14:26:27 INFO - Output received: 14:26:27 INFO - Usage: mozinstall [options] installer 14:26:27 INFO - Options: 14:26:27 INFO - -h, --help show this help message and exit 14:26:27 INFO - -d DEST, --destination=DEST 14:26:27 INFO - Directory to install application into. [default: 14:26:27 INFO - "/builds/slave/test"] 14:26:27 INFO - --app=APP Application being installed. [default: firefox] 14:26:27 INFO - mkdir: /builds/slave/test/build/application 14:26:27 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'] 14:26:27 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 14:26:47 INFO - Reading from file tmpfile_stdout 14:26:47 INFO - Output received: 14:26:47 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:26:47 INFO - Running post-action listener: _resource_record_post_action 14:26:47 INFO - [mozharness: 2017-04-24 21:26:47.505632Z] Finished install step (success) 14:26:47 INFO - [mozharness: 2017-04-24 21:26:47.505743Z] Running run-tests step. 14:26:47 INFO - Running pre-action listener: _resource_record_pre_action 14:26:47 INFO - Running main action method: run_tests 14:26:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:26:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:26:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:26:47 INFO - 2017-04-24 14:26:47.520 screenresolution[1639:7430] starting screenresolution argv=screenresolution get 14:26:47 INFO - 2017-04-24 14:26:47.522 screenresolution[1639:7430] Display 0: 1600x1200x32@60 14:26:47 INFO - 2017-04-24 14:26:47.528 screenresolution[1640:7432] starting screenresolution argv=screenresolution list 14:26:47 INFO - Available Modes on Display 0 14:26:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:26:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:26:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:26:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:26:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:26:47 INFO - Intel Iris: 14:26:47 INFO - Chipset Model: Intel Iris 14:26:47 INFO - Type: GPU 14:26:47 INFO - Bus: Built-In 14:26:47 INFO - VRAM (Dynamic, Max): 1536 MB 14:26:47 INFO - Vendor: Intel (0x8086) 14:26:47 INFO - Device ID: 0x0a2e 14:26:47 INFO - Revision ID: 0x0009 14:26:47 INFO - Displays: 14:26:47 INFO - Display: 14:26:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:26:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:26:47 INFO - Display Serial Number: 1600x1200 60 14:26:47 INFO - Main Display: Yes 14:26:47 INFO - Mirror: Off 14:26:47 INFO - Online: Yes 14:26:47 INFO - Rotation: Supported 14:26:47 INFO - Return code: 0 14:26:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:26:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:26:47 INFO - Python 2.7.3 14:26:47 INFO - Return code: 0 14:26:47 INFO - grabbing minidump binary from tooltool 14:26:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:47 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 0x10ca3ed30>, '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 0x10c964db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca33be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:26:47 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 14:26:47 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 14:26:47 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 14:26:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:26:47 INFO - Return code: 0 14:26:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:26:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:26:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:26:47 INFO - ENV: RUST_BACKTRACE is now 1 14:26:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:26:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:26:47 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/E5FG0YYUSHqsPXo0gAcHAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0380', '--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 14:26:47 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/E5FG0YYUSHqsPXo0gAcHAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0380 --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 14:26:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zOa905mLRS/Render', 14:26:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:47 INFO - 'HOME': '/Users/cltbld', 14:26:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:47 INFO - 'LOGNAME': 'cltbld', 14:26:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:26:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:26:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:47 INFO - 'MOZ_NO_REMOTE': '1', 14:26:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:26:47 INFO - 'NO_EM_RESTART': '1', 14:26:47 INFO - 'PAGER': '/bin/cat', 14:26:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:47 INFO - 'PWD': '/builds/slave/test', 14:26:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:26:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:47 INFO - 'RUST_BACKTRACE': '1', 14:26:47 INFO - 'SHELL': '/bin/bash', 14:26:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OV69OXjmmV/Listeners', 14:26:47 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 14:26:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:47 INFO - 'USER': 'cltbld', 14:26:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:47 INFO - 'XPC_FLAGS': '0x0', 14:26:47 INFO - 'XPC_SERVICE_NAME': '0', 14:26:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:47 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/E5FG0YYUSHqsPXo0gAcHAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0380', '--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 14:26:48 INFO - mozversion application_buildid: 20170424081926 14:26:48 INFO - mozversion application_changeset: 0bee350a1c5dc4aaece2bd3d2947c45e88d4a045 14:26:48 INFO - mozversion application_display_name: Nightly 14:26:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:26:48 INFO - mozversion application_name: Firefox 14:26:48 INFO - mozversion application_remotingname: firefox 14:26:48 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 14:26:48 INFO - mozversion application_vendor: Mozilla 14:26:48 INFO - mozversion application_version: 55.0a1 14:26:48 INFO - mozversion platform_buildid: 20170424081926 14:26:48 INFO - mozversion platform_changeset: 0bee350a1c5dc4aaece2bd3d2947c45e88d4a045 14:26:48 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 14:26:48 INFO - mozversion platform_version: 55.0a1 14:26:48 INFO - using testdate: 1493069208 14:26:48 INFO - actual date: 1493069208 14:26:48 INFO - starting webserver on 'localhost:49241' 14:26:48 INFO - SUITE-START | Running 2 tests 14:26:48 INFO - TEST-START | dromaeo_css 14:26:48 INFO - operating with platform_type : mac_ 14:26:48 INFO - Initialising browser for dromaeo_css test... 14:26:48 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/tmpQ0fBB6/profile http://localhost:49241/getInfo.html) 14:26:51 INFO - PID 1650 | 2017-04-24 14:26:51.010 plugin-container[1653:7623] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c47, name = 'com.apple.tsm.portname' 14:26:51 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:26:51 INFO - PID 1650 | 2017-04-24 14:26:51.012 plugin-container[1653:7623] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a23, name = 'com.apple.CFPasteboardClient' 14:26:51 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:26:51 INFO - PID 1650 | 2017-04-24 14:26:51.012 plugin-container[1653:7623] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:26:51 INFO - PID 1650 | __metrics Screen width/height:1600/1200 14:26:51 INFO - PID 1650 | colorDepth:24 14:26:51 INFO - PID 1650 | Browser inner width/height: 1024/689 14:26:51 INFO - PID 1650 | __metrics 14:26:51 ERROR - PID 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3403: TypeError: this.tabs is undefined 14:26:52 INFO - TEST-INFO | 1650: exit 0 14:26:52 INFO - Browser initialized. 14:26:52 INFO - Running cycle 1/1 for dromaeo_css test... 14:26:52 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/tmpQ0fBB6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:26:54 INFO - PID 1654 | 2017-04-24 14:26:54.647 plugin-container[1655:7758] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923b, name = 'com.apple.tsm.portname' 14:26:54 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:26:54 INFO - PID 1654 | 2017-04-24 14:26:54.648 plugin-container[1655:7758] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4427, name = 'com.apple.CFPasteboardClient' 14:26:54 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:26:54 INFO - PID 1654 | 2017-04-24 14:26:54.648 plugin-container[1655:7758] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:27:04 INFO - PID 1654 | RSS: Main: 237268992 14:27:04 INFO - PID 1654 | 14:30:07 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 14:30:09 INFO - PID 1654 | RSS: Main: 253292544 14:30:09 INFO - PID 1654 | 14:31:52 INFO - PID 1654 | ************************* 14:31:52 INFO - PID 1654 | A coding exception was thrown and uncaught in a Task. 14:31:52 INFO - PID 1654 | 14:31:52 ERROR - PID 1654 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:31:52 INFO - PID 1654 | Full stack: 14:31:52 INFO - PID 1654 | ************************* 14:31:52 INFO - PID 1654 | ************************* 14:31:52 INFO - PID 1654 | A coding exception was thrown and uncaught in a Task. 14:31:52 INFO - PID 1654 | 14:31:52 ERROR - PID 1654 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:31:52 INFO - PID 1654 | Full stack: 14:31:52 INFO - PID 1654 | ************************* 14:31:52 INFO - PID 1654 | ************************* 14:31:52 INFO - PID 1654 | A coding exception was thrown and uncaught in a Task. 14:31:52 INFO - PID 1654 | 14:31:52 ERROR - PID 1654 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:31:52 INFO - PID 1654 | Full stack: 14:31:52 INFO - PID 1654 | ************************* 14:31:52 INFO - PID 1654 | ************************* 14:31:52 INFO - PID 1654 | A coding exception was thrown and uncaught in a Task. 14:31:52 INFO - PID 1654 | 14:31:52 ERROR - PID 1654 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:31:52 INFO - PID 1654 | Full stack: 14:31:52 INFO - PID 1654 | ************************* 14:33:11 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 14:33:12 INFO - PID 1654 | RSS: Main: 257351680 14:33:12 INFO - PID 1654 | 14:36:13 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 14:36:14 INFO - PID 1654 | RSS: Main: 267386880 14:36:14 INFO - PID 1654 | 14:39:16 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 14:39:16 INFO - PID 1654 | RSS: Main: 259964928 14:39:16 INFO - PID 1654 | 14:42:19 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 14:42:20 INFO - PID 1654 | RSS: Main: 270024704 14:42:20 INFO - PID 1654 | 14:45:22 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 14:45:22 INFO - PID 1654 | RSS: Main: 266125312 14:45:22 INFO - PID 1654 | 14:45:22 INFO - PID 1654 | __start_tp_report 14:45:22 INFO - PID 1654 | _x_x_mozilla_page_load 14:45:22 INFO - PID 1654 | _x_x_mozilla_page_load_details 14:45:22 INFO - PID 1654 | |i|pagename|runs| 14:45:22 INFO - PID 1654 | |0;dojo.html;3205.9038228853137;3542.433210686292;3561.1809283864727;3588.336157810805;3605.9459108113374;5064.468230835764;5723.942760572388;6045.425684559971;6140.8464788380315;6148.077788331747;6111.774065234695;6171.969140154304;6173;6243.937560624392;6273.529485288599;5405.594580406466;5407.540359069484;5418.376886658026;5431.131019036955;5451.3458384993655;5401.648892821973;5451.2640793492965;5494.340679118512;5502.80740174094;5551.8889622207535;4468.642508599337;5280.709560974145;5329.840104796862;5476.315460567429;5490.423505531921;5322.095243808523;5333.120035194227;5349.973250133763;5407.3781515125875;5456.181572764078;5305;5313.468653134694;5337.412884582732;5381.569474442035;5411.702356370398;5976.103584462322;6106.053561697944;6211.937880621181;6214.502839772807;6245.156903817971;6335.334789847092;6383.744650214032;6516.9674151629415;6599.703013364362;6604.801855944329;5831.183634291203;6717.932820671828;6738.764143255012;6744.49416293775;6753.763618273337;6194.81415557534;6335.7148928297875;6345.587536810139;6354.650494222769;6356.046593010993;6539.313372095861;6602.603843769293;6748.898766518506;6883.518153729191;6892.517523773287;5126.384833819965;6290.056491526308;6364.109024736541;6440.130582371414;6470.838229044311;6672.466202703772;6702.698378572976;6704.094947182166;6707.262201157869;6708.798736037927;5260.631755777135;6465.676716164267;6558.606483610999;6561.770338038145;6592.307807680125;6219.440250377488;6426.35736426363;6540.247871494744;6563.343665633399;6569;5195.870103247374;5204.245384419238;5208;5243.475652434802;5263.578913686896;4651.581357677757;4674.74288914113;4697.553732395485;4707.482176960531;4722.740249286254;6183.752649894044;6309.400606942333;6351.079093531412;6370.108184854124;6384.361563843579;6403.64779937108;6498.447631951242;6684.766033188815;6701.530892837454;6715.7985260442265;6338.651374174465;6370.235571731422;6371.713272902638;6380.7128679209545;6384.648844313608;5818.5636077294375;5836.095405212199;5853.7365818538265;5854.297484301911;5895.174675545427;6647.169103862017;6822.522423430312;6832.6925288362145;6918.8616227676175;6936.4797640176175;6508.381703738138;6557.278699342973;6611.702473388806;6697.7655782047395;6722;6884.965575172092;6908.3437073477935;6922.93077069243;6929.792106236821;6933.0986971693355;6066.847299013173;6239.5008399327035;6593.571417857824;6616.073749674953;6620.834479137868;6395.136656551492;6442.35576442352;6609.768658096944;6620.867582648224;6643.468522518284;5893.734781934738;6502.804915852532;6509.511786615928;6522.967385163044;6523.608583485006;6311.116443697978;6339.96830015847;6416.294207637063;6506.154199953976;6514.0880276760345;5518.758279387106;6137;6336.841578960563;6419.967900160469;6420.518461115342;5234.764435600483;6472.546921715623;6483.805485835433;6503.414692677682;6547.1161393211305;5253.579713622825;6231.781887633911;6288.0882272069375;6363.681815909279;6394.87210255783 14:45:22 INFO - PID 1654 | |1;ext.html;730.218666027351;750.2534977697192;811.290121143999;1044.650042235851;1245.869183735707;34284.82857585715;34751.30497390051;36941.26117477652;37740.86777396679;37851.81074094633;29527.261818454495;33220.66779332212;34827.16901140879;36933.815330923375;37333;6560.343965603437;7154.749583764569;8190.099089100194;8626.137386261387;8637.870431943526;8574.399792014561;8695;8703;8705.216530512253;8713.390062695615;7124.254557633361;8280.958595207016;8677.826443471127;8687.913120868805;8711.520866352348;8643.524606146659;8665.610047547843;8688.261497772697;8696.695615653454;8701.7824554386;8312.1687831217;8345.95827020867;8358.498490090553;8372.958135209346;8410.537420441873;37381;37397;37525;37588.812055939816;37683;3575.481555174511;3605.152789094561;3621.6559426854406;3622.7826330420257;3623.8912832615065;31183.84408077945;31207;31237.062888113393;31240;31292.843535782176;3128.4212420702015;3151.6533181350032;3152.653208147102;3165.0346644273486;3180.316232010106;229809;233735;282449.17550824437;293230;293622;47313.763431182626;47559.048819024116;47658.285125723145;47706.761466192445;47799.76100119547;49577.0084598306;49657.0068598626;49803.25295120576;49803.75098124559;49855;13887;13956.302184890716;13995.650108747363;14037.298135093204;14053.85946140546;11290.548378064785;11352.772944541228;11477.426128693533;11479.483423245974;11484.94257528707;10867.510962006729;12226.938865305794;12284.447199876027;13457.327133643279;13485.460581576823;5986.281646202397;6070.787522436782;6084.908726369108;6095.299040610298;6162.7226774795245;1002.1381611813835;1018.3126389686931;1021.4331046269243;1047.3925123428546;1052.894710528941;22229;23260;23265.418364541023;24252.87873560621;24387.756122438903;963.5037955452947;1094.8631421072366;1139.4416735799398;1141.19545720267;1143.3082984794246;11960;12269.815952760715;12338.012958963442;12354.629361119181;12411.875881241072;324663;324949;325794;326271;327248.3637581844;51055.744721276154;51334;51430.74284628553;51468.48531514712;51591.74204128955;1445.6313640021726;1497.663025819182;1498.3856618786463;1500.5873384819263;1510.6978604279188;20784.06471708807;20799.1680332785;23976.40058998539;23979.400514987265;24123;30586.541201882435;37315.6939507116;37344.81327593345;37516.43725344176;37552.87341379763;286476;287455;287596;287709;288102;29291.70708292975;32616.53225604901;33382;33914.66085339214;33969.32061358808;44134.77932610316;44696;44763.55236447724;44818.77590612026;44831.32753008642;25012.249632511055;29377.853110734308;29979.70020299769;30248.84875575608;30405.935792247164;29897;30314.84842575773;30354.241143971576;30364;30586;36005;36266;36350;36979;37549;30433.087007389815;30823.383532329677;30961.845190773904;31076.68923310738;31549;21884.45288867791;22698.432539185993;23936.640950386107;24022.87988560046;24475 14:45:22 INFO - PID 1654 | |2;jquery.html;441.44378083614635;444.9532799056099;445.8060743576543;457.2660879288741;467.7614416647511;20843.687344689824;21306.57386852264;22094.337169884908;23038.308850734506;23286;22351.55296894061;22938.85305734715;23190.956406961686;23267.301980940556;23470.530589388243;6027.789027384042;6112.6638034908065;6189.783357582485;6217.036359364306;6285.2771931228035;5144.996725638502;6208.875822483547;6211.6273023618505;6216.564840461169;6219.19150510433;5212.687238765668;5859.355470898197;6214.415324092372;6224.097505861648;6233.3143354231;5193.506616871429;6031.522277042152;6154.076888466721;6182.876342473148;6196.659183744892;5526.751296191641;5531;5544.91682624761;5551.833444996657;5567.359753628303;26648.93404263827;26781.866090669617;27271.909123635032;27486.862565687043;27631.30921726953;2239.6192647249845;2261.3329067925;2277.02088102116;2278.453171238907;2280.429892526868;17231.31074757008;17774.28902843885;18302.176402061938;18342.90828545862;18538;2096.549241912981;2156.827453803692;2167.2089687264192;2178.161407857986;2186.5954798362354;148523.25738371347;156213;157425;158902;159788.20105899512;10228.670272864629;11589.768204636028;11795.941020294844;12075.154739168174;12605.495780168874;16430.26063827107;16560.420385286456;16563.337466501205;16563.420280290375;16575;7360.374368178657;8655.394122411497;8937.776555586162;8943.508107054175;8948.8657670135;6924.4114250289285;8367.372447066495;8372.079071302152;8397.076321604618;8551.187637174458;7904.20957904205;7994.5602991836;8012.716699637743;8058.435909486279;8090.474119182234;2014.2647933504165;2063.7720556268996;2211.8672879627275;2219.2676416782424;2374.062245413052;807.8626633472323;939.3236869453982;994.4132961552718;996.4967691315984;1009.6163457886104;20066.89966550158;24699.62950555743;26125.477490450085;26574;26644.46711065768;774.6397924964919;874.3343929813589;925.7222833150028;1021.9642312519026;1036.8444733290066;7862.52824830512;10511.52698128586;11110.888891111146;11114.610988615521;11130;567561;579560;581075;591740;593583;110054.44972775085;113208;113348.43325783318;125773;127901;1168.6318809574957;1174.6241202815017;1233.272369302116;1233.272369302116;1235.1354052163522;21543.56912861765;21711.565768684854;21749.565008700054;21880.45298867541;22156;45352.59834147349;50722.985540288246;51375.48624513707;51950.74024629853;53132.20301695555;70291.29708702848;75408;76285.71142865723;77485;79149.60425197837;7488.288612581796;8710.82578348442;8811.691590794291;8853.557322133996;9080.228180604723;13199.07606467538;13838.515651952133;13843.169409835442;13915.930420347833;14302.642433939234;11852.525898963944;11888.34614096233;11945.462454189408;12130.636080917586;12146.574869879512;9636.614535418505;9957.651482198089;11272.323660580392;11311.717207069889;11592.6522204334;11930.463129159036;12108.515659373526;12223.20549164337;12359.25844449336;12396.380180990735;8558.871616925626;8740.912590874264;8891.333150013645;8991.505467199368;9016.504092274989;11759.647210583697;11840;11888.28670279786;11942.4028798558;12044.096692748255 14:45:22 INFO - PID 1654 | |3;mootools.html;1194.7729931313052;1424.9501267455641;1426.8430472648013;1458.9932946267081;1461.8802736032756;11602.129840261983;11829.881701182985;12015.03879689625;12067.396630168489;12165.330906800122;11759.941605255526;11945.820812687816;11949.761004779899;11970.401479926013;11986.280823150617;2500.0124950644486;2551.413174969758;2552.4001859562986;2552.412945022646;2553.1957433408415;2405.8556486610773;2443.58459061959;2452;2459.053264493174;2487.955058875277;2475.282168171228;2492.626106084084;2498.487809998946;2504.3113143885485;2516.962245566318;2461.901523939049;2480.8635525046116;2518.5214809186286;2522.066835270943;2525.5201511712717;2413.1192114878118;2498.6376975338476;2513.1204078572537;2513.7611926866916;2529.759672831078;12077.15459917806;12152.453139608739;12184.329861857594;12185.45165467556;12185.695357616041;6652.101966234545;6828.2488926218075;6868.381845633917;6878.3465570770695;6894;4034.677225821927;4210.536840947493;4287.485501739811;4305.440292761952;4469.530699276562;1254.6361555148997;1262.066071107374;1265.4052595280205;1303.1985329022677;1303.4851233762706;16260.674786504203;16800.83199168017;16858.49424517254;16932.23804928781;16932.23804928781;5216.634835561475;5632.098864181711;5789.507891829157;5836.795712150054;5895.292564892155;9683.757906052406;12410.875891240972;12489.93755031219;12497.500099995897;12538.87461125377;6676.799696009128;6678.9332106679285;6687.097241872285;6692.631905245161;6694.364035416627;6046.395360463918;6114.144019837227;6205.162303089115;6267.467265282411;6269.843253918597;4398.516163222043;4497.482789479252;4537.931931021038;4542.88642783933;4626.37543931565;1301.225770666452;1324.7814110671825;1332.1074879830537;1335.5125379236508;1351.7499262636443;785.2422412371974;804.1074407407773;807.5598798654747;816.4366587054913;821.97945051373;6150.3849615039035;6160.7535698571555;6315.336889626615;6324;6397;480.92305231162845;551.503646717957;588.076719550302;594.1414655822296;606.6511755740466;3659.524261845943;3813.4470501777087;3934.960650393459;3965.8017099144927;3988.4615576897327;14424.927875360765;14549.78175327371;14974.401023959137;15029.09825410457;15040.947133700536;14287.714245715028;14988.775168372484;15400;15450.536483905276;15494;8535.914640853513;8567.143285671507;8599.699010534601;8671.609777560025;8743.38796284254;5956.731947062306;6942.166939967236;7100.899360599115;7197.9922810807575;7306.707731690885;13408.530701425403;13462.394192261176;13661.590152295446;13743.862561374259;13785.862141378859;13900.304984750925;13958.720825583634;13969.231692257023;14021.859781402463;14150.363233654716;9485.762855928657;9703.07820757027;9799;9804.313698041069;9826.16477599412;10169.186465082628;10300.484975751333;10358.119559837498;10378.06597406237;10388;7266.273372662691;8797.516136612549;8805.295576354005;8827.293816494537;8898.021217665922;8596.957015214884;8676.696315629175;8700.56497175126;8745.781355466164;8779.56102194875;7829.060512738508;7836.529808211526;7915.525068495908;7923.484973476706;7932.325752310888;5897.262842144732;5908.438698323653;6012.6993650316435;6076.51387888959;6152.169457123231;5499.312585926759;5900.881982360243;5969;6063.545234107548;6126.509879209563 14:45:22 INFO - PID 1654 | |4;prototype.html;332.1496967762528;381.4031041420177;410.13052329062356;410.1816875333709;412.7317243791533;6002.159697642333;6119.847003824907;6198.411150940653;6221.222347206599;6224.719887605057;5955.970220148904;5992.7902523411685;6049.517868122316;6085.756569737206;6087.543434242439;4473.619742321906;4488.304312831517;4489.932651010237;4495;4500.7074540154945;3701.6298370162904;4368.84225680195;4450.154470650582;4501.7524036178;4505.098980203956;4387.495438024636;4395.6483481321575;4413.4262545869;4416.447944006999;4417.757023363713;4362.47650281965;4413.580709832593;4432.047109871377;4436.977815110936;4442.733435993819;3172.841357932094;3738.1776009278187;3851.691864650821;3856.151646637735;3875.3411919973673;5728.513076388512;5737.483626473596;5790.536757059425;5841.474267315962;5842.328132264802;4572.719638501225;5064.949350506485;5291.629585928987;5294.285271488338;5297.973510132463;5696.316442026983;6020.886136064842;6375.968120159416;6387.552871299011;6394.200724909387;3596.7482276240676;4333.414988976511;4344.739315641072;4359.237133501624;4360.476742790854;5930.911036334502;6295.0872123542285;6380.234371875358;6825.795226143224;6940.6529673516125;4369.060651959844;4759.904801903943;4832.44426890905;4850.927236091461;4861.8188358012085;4952.603791696655;4958.826441074545;4959.7272150032095;4964.15609346412;4964.975175124101;4032.6975476838766;5500.559955203574;5525.005499010136;5542.168674698827;5545.944540554624;4640.512746161672;5854.619449735754;6249.25008998923;6478.676066196671;6534.215894092739;1623.2370785730689;1680.4706517446964;1687.6034131978975;1692.636083242109;1695.313398073782;1083.664064140119;1176.452949378543;1183.8046722290899;1186.6677330347516;1188.316717887201;6116.785912493041;6149.815505534841;6163.38366163389;6172.567920245539;6181.598196117328;5621.522170615462;5848.3274423441;5882.852843695438;6637.369449902348;6713.865722685519;5296.841094767163;5439.5376393006245;5669.2346533218315;5869.855378201295;6106.694665266719;2891.5228987216874;3141.1361875484426;3247.3438546341063;3450.9474610243615;3488.4767284907466;5670.7393248881235;6619.437347825488;6790.219124800711;6841.692123854438;6898.827529311808;6550.639714815731;6814.1482314710665;6831.348889244078;6851.9657401712675;6887.552309099892;5429.484199001088;5585;6514.7621951829;6757.121574195323;6813.182418109858;5154.7938082476285;6275.309715931336;6651.866962660816;6699.29657385987;6712.482818942896;5362.276092727588;6270.122182894307;6536.548794202175;6613.735450581923;6624.403803657694;6976.232614412309;6984.511084224258;7011.193712723102;7013.544119632207;7038.894416583653;3750.9872334470087;4451.1765323415275;4472.888177795581;4479.641628669764;4506.324051392186;5585.888282234414;6483.683379628659;6682.291328312833;6687.4984376171005;6779.966100169468;6522.771702990372;6550.886349320532;6572.108272360147;6572.638504882278;6573.375529324707;5066.782593053364;5536.363318218296;6049.909251361321;6246.688195478819;6497.382748638872;5055.823046193513;6291.993281075006;6344.587601805868;6347.365263473615;6353.618782873043;5929.673867937305;6329.430351268408;6374.74501019954;6466.82418290522;6496.837579060372;5632.380438151882;5873.73714651352;6251.68741562911;6346.873062538816;6368.77709280173 14:45:22 INFO - PID 1654 | |5;yui.html;770.5376773935637;815.8816971539127;828.5401602110826;866.2766589897435;868.6351732272446;14717.558473245794;15300.31148598313;16593.58516037098;17065.57336066599;17073.914630426847;14340.42638294467;16946;17508.737368939444;17689.81583315006;17978.64042719148;950.7765675066354;965.8696076029752;970.2529052629471;973.9464329461877;975.512243878061;984.3355734878963;988.8813342398922;989.114742305636;992.7121134870879;994.1351024608565;1046.5499835070916;1047.308776207703;1047.9056884880358;1049.082053203447;1054.1092776603757;1035.29082578434;1037.1132681557235;1038.9064984151426;1041.5156952017273;1041.6406339812752;766.5592284436472;769.4921351907714;769.7498313048281;770.0412985832618;774.3340726974798;16468;16477.83522164775;16875.409360672318;17111.657766844717;17116.229769660396;13872.722545549033;14014.789778153336;14967.027143235764;16665.500034998968;16998.83001169997;13591.184528928296;13955;14181.503647372292;14182.43270269188;14193.503227387095;3398;3411.30068335993;3431.571053618298;3476.1135910342955;3568.6074531801482;113123.86876131297;120409;121140;122268.77731222662;122755;11970.82043769344;13170.539031133865;13311.001674874424;13332.800007999887;13335.466581336637;24771.752282477304;24953.251402457594;25322.74677253204;25742.87128564345;25759.87120064425;6726.562773419712;7457.2542745724995;7610.3150716434675;7688.346490548254;7768.184340644264;3273.8199399032806;3859.864904728321;3892.260470510594;3912.491376121143;3938.547067087264;3530.3292374448774;4264.253755592821;4309.310510318334;4310.89222769427;4375.234333991539;839.7564706235123;859.8882145321069;891.1801142948503;913.5112714697605;924;639.8368416053969;654.9050387693853;658.9044588534636;659.6602749583968;687.0690214759029;10318.690439286833;11170.720731981603;11318.26431281964;11576.76846463066;11823.467943942544;643.9053608864948;644.3073695777058;653.9836504087341;658.6871236162784;672.8687905858311;5336.95929293784;5527.115661494222;5538.390777014525;5567.805126820542;5570.97214513933;100073.49963250138;100856;101278.49360753149;101687.47468787975;103217.48391257996;54736;87755.56122219349;89615;91382.62926056114;93069.5346523263;63013.47946082197;82841.17158828335;84039;84091.57954210312;86656;12085.591329295547;12618.17981831178;12641.620751377473;12655.303958282382;12720.809187862003;38286.85139445821;42056.36915446332;42679.93300167521;43144.56855431406;43253;49202.753986229836;53164;53819.46180538301;54155;54505.45494545004;16126.516204513884;16318.755218671491;16330.755038674666;16521.504354869372;17135.742963855304;18102.90948545249;18612.34856780006;18637.068146592886;18812.811871881655;18941.242350305834;16653.5836604081;17056.829431705522;17237.224324905157;17281.222344994254;17307.2211750469;12005.579804706793;16693.332266709196;16842.747358789875;16869.493915182564;17095;16188;16650.83349166493;16722.58193545171;16729.49811505657;16775.66448671044;9681.564329605044;10828.783424331627;11803.586874459354;12131;12473.376331183586;11453;11936.28382297065;12171.939140304241;13176.077674563072;13338.733225335633 14:45:22 INFO - PID 1654 | __end_tp_report 14:45:22 INFO - PID 1654 | __start_cc_report 14:45:22 INFO - PID 1654 | _x_x_mozilla_cycle_collect,430 14:45:22 INFO - PID 1654 | __end_cc_report 14:45:22 INFO - PID 1654 | __startTimestamp1493070322304__endTimestamp 14:45:22 INFO - PID 1654 | 14:45:22 INFO - PID 1654 | ------- Summary: start ------- 14:45:22 INFO - PID 1654 | Number of tests: 6 14:45:22 INFO - PID 1654 | 14:45:22 INFO - PID 1654 | [#0] dojo.html Cycles:165 Average:6057.97 Median:6339.97 stddev:737.05 (11.6%) stddev-sans-first:704.53 14:45:22 INFO - PID 1654 | Values: 3205.9 3542.4 3561.2 3588.3 3605.9 5064.5 5723.9 6045.4 6140.8 6148.1 6111.8 6172.0 6173.0 6243.9 6273.5 5405.6 5407.5 5418.4 5431.1 5451.3 5401.6 5451.3 5494.3 5502.8 5551.9 4468.6 5280.7 5329.8 5476.3 5490.4 5322.1 5333.1 5350.0 5407.4 5456.2 5305.0 5313.5 5337.4 5381.6 5411.7 5976.1 6106.1 6211.9 6214.5 6245.2 6335.3 6383.7 6517.0 6599.7 6604.8 5831.2 6717.9 6738.8 6744.5 6753.8 6194.8 6335.7 6345.6 6354.7 6356.0 6539.3 6602.6 6748.9 6883.5 6892.5 5126.4 6290.1 6364.1 6440.1 6470.8 6672.5 6702.7 6704.1 6707.3 6708.8 5260.6 6465.7 6558.6 6561.8 6592.3 6219.4 6426.4 6540.2 6563.3 6569.0 5195.9 5204.2 5208.0 5243.5 5263.6 4651.6 4674.7 4697.6 4707.5 4722.7 6183.8 6309.4 6351.1 6370.1 6384.4 6403.6 6498.4 6684.8 6701.5 6715.8 6338.7 6370.2 6371.7 6380.7 6384.6 5818.6 5836.1 5853.7 5854.3 5895.2 6647.2 6822.5 6832.7 6918.9 6936.5 6508.4 6557.3 6611.7 6697.8 6722.0 6885.0 6908.3 6922.9 6929.8 6933.1 6066.8 6239.5 6593.6 6616.1 6620.8 6395.1 6442.4 6609.8 6620.9 6643.5 5893.7 6502.8 6509.5 6523.0 6523.6 6311.1 6340.0 6416.3 6506.2 6514.1 5518.8 6137.0 6336.8 6420.0 6420.5 5234.8 6472.5 6483.8 6503.4 6547.1 5253.6 6231.8 6288.1 6363.7 6394.9 14:45:22 INFO - PID 1654 | 14:45:22 INFO - PID 1654 | [#1] ext.html Cycles:180 Average:44088.95 Median:325371.50 stddev:77325.77 (23.8%) stddev-sans-first:77474.16 14:45:22 INFO - PID 1654 | Values: 730.2 750.3 811.3 1044.7 1245.9 34284.8 34751.3 36941.3 37740.9 37851.8 29527.3 33220.7 34827.2 36933.8 37333.0 6560.3 7154.7 8190.1 8626.1 8637.9 8574.4 8695.0 8703.0 8705.2 8713.4 7124.3 8281.0 8677.8 8687.9 8711.5 8643.5 8665.6 8688.3 8696.7 8701.8 8312.2 8346.0 8358.5 8373.0 8410.5 37381.0 37397.0 37525.0 37588.8 37683.0 3575.5 3605.2 3621.7 3622.8 3623.9 31183.8 31207.0 31237.1 31240.0 31292.8 3128.4 3151.7 3152.7 3165.0 3180.3 229809.0 233735.0 282449.2 293230.0 293622.0 47313.8 47559.0 47658.3 47706.8 47799.8 49577.0 49657.0 49803.3 49803.8 49855.0 13887.0 13956.3 13995.7 14037.3 14053.9 11290.5 11352.8 11477.4 11479.5 11484.9 10867.5 12226.9 12284.4 13457.3 13485.5 5986.3 6070.8 6084.9 6095.3 6162.7 1002.1 1018.3 1021.4 1047.4 1052.9 22229.0 23260.0 23265.4 24252.9 24387.8 963.5 1094.9 1139.4 1141.2 1143.3 11960.0 12269.8 12338.0 12354.6 12411.9 324663.0 324949.0 325794.0 326271.0 327248.4 51055.7 51334.0 51430.7 51468.5 51591.7 1445.6 1497.7 1498.4 1500.6 1510.7 20784.1 20799.2 23976.4 23979.4 24123.0 30586.5 37315.7 37344.8 37516.4 37552.9 286476.0 287455.0 287596.0 287709.0 288102.0 29291.7 32616.5 33382.0 33914.7 33969.3 44134.8 44696.0 44763.6 44818.8 44831.3 25012.2 29377.9 29979.7 30248.8 30405.9 29897.0 30314.8 30354.2 30364.0 30586.0 36005.0 36266.0 36350.0 36979.0 37549.0 30433.1 30823.4 30961.8 31076.7 31549.0 21884.5 22698.4 23936.6 24022.9 24475.0 14:45:22 INFO - PID 1654 | 14:45:22 INFO - PID 1654 | [#2] jquery.html Cycles:180 Average:36145.61 Median:13536.85 stddev:98066.34 (724.4%) stddev-sans-first:98304.80 14:45:22 INFO - PID 1654 | Values: 441.4 445.0 445.8 457.3 467.8 20843.7 21306.6 22094.3 23038.3 23286.0 22351.6 22938.9 23191.0 23267.3 23470.5 6027.8 6112.7 6189.8 6217.0 6285.3 5145.0 6208.9 6211.6 6216.6 6219.2 5212.7 5859.4 6214.4 6224.1 6233.3 5193.5 6031.5 6154.1 6182.9 6196.7 5526.8 5531.0 5544.9 5551.8 5567.4 26648.9 26781.9 27271.9 27486.9 27631.3 2239.6 2261.3 2277.0 2278.5 2280.4 17231.3 17774.3 18302.2 18342.9 18538.0 2096.5 2156.8 2167.2 2178.2 2186.6 148523.3 156213.0 157425.0 158902.0 159788.2 10228.7 11589.8 11795.9 12075.2 12605.5 16430.3 16560.4 16563.3 16563.4 16575.0 7360.4 8655.4 8937.8 8943.5 8948.9 6924.4 8367.4 8372.1 8397.1 8551.2 7904.2 7994.6 8012.7 8058.4 8090.5 2014.3 2063.8 2211.9 2219.3 2374.1 807.9 939.3 994.4 996.5 1009.6 20066.9 24699.6 26125.5 26574.0 26644.5 774.6 874.3 925.7 1022.0 1036.8 7862.5 10511.5 11110.9 11114.6 11130.0 567561.0 579560.0 581075.0 591740.0 593583.0 110054.4 113208.0 113348.4 125773.0 127901.0 1168.6 1174.6 1233.3 1233.3 1235.1 21543.6 21711.6 21749.6 21880.5 22156.0 45352.6 50723.0 51375.5 51950.7 53132.2 70291.3 75408.0 76285.7 77485.0 79149.6 7488.3 8710.8 8811.7 8853.6 9080.2 13199.1 13838.5 13843.2 13915.9 14302.6 11852.5 11888.3 11945.5 12130.6 12146.6 9636.6 9957.7 11272.3 11311.7 11592.7 11930.5 12108.5 12223.2 12359.3 12396.4 8558.9 8740.9 8891.3 8991.5 9016.5 11759.6 11840.0 11888.3 11942.4 12044.1 14:45:22 INFO - PID 1654 | 14:45:22 INFO - PID 1654 | [#3] mootools.html Cycles:180 Average:7138.24 Median:3874.20 stddev:4575.14 (118.1%) stddev-sans-first:4566.18 14:45:22 INFO - PID 1654 | Values: 1194.8 1425.0 1426.8 1459.0 1461.9 11602.1 11829.9 12015.0 12067.4 12165.3 11759.9 11945.8 11949.8 11970.4 11986.3 2500.0 2551.4 2552.4 2552.4 2553.2 2405.9 2443.6 2452.0 2459.1 2488.0 2475.3 2492.6 2498.5 2504.3 2517.0 2461.9 2480.9 2518.5 2522.1 2525.5 2413.1 2498.6 2513.1 2513.8 2529.8 12077.2 12152.5 12184.3 12185.5 12185.7 6652.1 6828.2 6868.4 6878.3 6894.0 4034.7 4210.5 4287.5 4305.4 4469.5 1254.6 1262.1 1265.4 1303.2 1303.5 16260.7 16800.8 16858.5 16932.2 16932.2 5216.6 5632.1 5789.5 5836.8 5895.3 9683.8 12410.9 12489.9 12497.5 12538.9 6676.8 6678.9 6687.1 6692.6 6694.4 6046.4 6114.1 6205.2 6267.5 6269.8 4398.5 4497.5 4537.9 4542.9 4626.4 1301.2 1324.8 1332.1 1335.5 1351.7 785.2 804.1 807.6 816.4 822.0 6150.4 6160.8 6315.3 6324.0 6397.0 480.9 551.5 588.1 594.1 606.7 3659.5 3813.4 3935.0 3965.8 3988.5 14424.9 14549.8 14974.4 15029.1 15040.9 14287.7 14988.8 15400.0 15450.5 15494.0 8535.9 8567.1 8599.7 8671.6 8743.4 5956.7 6942.2 7100.9 7198.0 7306.7 13408.5 13462.4 13661.6 13743.9 13785.9 13900.3 13958.7 13969.2 14021.9 14150.4 9485.8 9703.1 9799.0 9804.3 9826.2 10169.2 10300.5 10358.1 10378.1 10388.0 7266.3 8797.5 8805.3 8827.3 8898.0 8597.0 8676.7 8700.6 8745.8 8779.6 7829.1 7836.5 7915.5 7923.5 7932.3 5897.3 5908.4 6012.7 6076.5 6152.2 5499.3 5900.9 5969.0 6063.5 6126.5 14:45:22 INFO - PID 1654 | 14:45:22 INFO - PID 1654 | [#4] prototype.html Cycles:180 Average:5206.43 Median:5712.41 stddev:1596.38 (27.9%) stddev-sans-first:1558.37 14:45:22 INFO - PID 1654 | Values: 332.1 381.4 410.1 410.2 412.7 6002.2 6119.8 6198.4 6221.2 6224.7 5956.0 5992.8 6049.5 6085.8 6087.5 4473.6 4488.3 4489.9 4495.0 4500.7 3701.6 4368.8 4450.2 4501.8 4505.1 4387.5 4395.6 4413.4 4416.4 4417.8 4362.5 4413.6 4432.0 4437.0 4442.7 3172.8 3738.2 3851.7 3856.2 3875.3 5728.5 5737.5 5790.5 5841.5 5842.3 4572.7 5064.9 5291.6 5294.3 5298.0 5696.3 6020.9 6376.0 6387.6 6394.2 3596.7 4333.4 4344.7 4359.2 4360.5 5930.9 6295.1 6380.2 6825.8 6940.7 4369.1 4759.9 4832.4 4850.9 4861.8 4952.6 4958.8 4959.7 4964.2 4965.0 4032.7 5500.6 5525.0 5542.2 5545.9 4640.5 5854.6 6249.3 6478.7 6534.2 1623.2 1680.5 1687.6 1692.6 1695.3 1083.7 1176.5 1183.8 1186.7 1188.3 6116.8 6149.8 6163.4 6172.6 6181.6 5621.5 5848.3 5882.9 6637.4 6713.9 5296.8 5439.5 5669.2 5869.9 6106.7 2891.5 3141.1 3247.3 3450.9 3488.5 5670.7 6619.4 6790.2 6841.7 6898.8 6550.6 6814.1 6831.3 6852.0 6887.6 5429.5 5585.0 6514.8 6757.1 6813.2 5154.8 6275.3 6651.9 6699.3 6712.5 5362.3 6270.1 6536.5 6613.7 6624.4 6976.2 6984.5 7011.2 7013.5 7038.9 3751.0 4451.2 4472.9 4479.6 4506.3 5585.9 6483.7 6682.3 6687.5 6780.0 6522.8 6550.9 6572.1 6572.6 6573.4 5066.8 5536.4 6049.9 6246.7 6497.4 5055.8 6292.0 6344.6 6347.4 6353.6 5929.7 6329.4 6374.7 6466.8 6496.8 5632.4 5873.7 6251.7 6346.9 6368.8 14:45:22 INFO - PID 1654 | 14:45:22 INFO - PID 1654 | [#5] yui.html Cycles:180 Average:21157.66 Median:18624.71 stddev:29429.24 (158.0%) stddev-sans-first:29471.98 14:45:22 INFO - PID 1654 | Values: 770.5 815.9 828.5 866.3 868.6 14717.6 15300.3 16593.6 17065.6 17073.9 14340.4 16946.0 17508.7 17689.8 17978.6 950.8 965.9 970.3 973.9 975.5 984.3 988.9 989.1 992.7 994.1 1046.5 1047.3 1047.9 1049.1 1054.1 1035.3 1037.1 1038.9 1041.5 1041.6 766.6 769.5 769.7 770.0 774.3 16468.0 16477.8 16875.4 17111.7 17116.2 13872.7 14014.8 14967.0 16665.5 16998.8 13591.2 13955.0 14181.5 14182.4 14193.5 3398.0 3411.3 3431.6 3476.1 3568.6 113123.9 120409.0 121140.0 122268.8 122755.0 11970.8 13170.5 13311.0 13332.8 13335.5 24771.8 24953.3 25322.7 25742.9 25759.9 6726.6 7457.3 7610.3 7688.3 7768.2 3273.8 3859.9 3892.3 3912.5 3938.5 3530.3 4264.3 4309.3 4310.9 4375.2 839.8 859.9 891.2 913.5 924.0 639.8 654.9 658.9 659.7 687.1 10318.7 11170.7 11318.3 11576.8 11823.5 643.9 644.3 654.0 658.7 672.9 5337.0 5527.1 5538.4 5567.8 5571.0 100073.5 100856.0 101278.5 101687.5 103217.5 54736.0 87755.6 89615.0 91382.6 93069.5 63013.5 82841.2 84039.0 84091.6 86656.0 12085.6 12618.2 12641.6 12655.3 12720.8 38286.9 42056.4 42679.9 43144.6 43253.0 49202.8 53164.0 53819.5 54155.0 54505.5 16126.5 16318.8 16330.8 16521.5 17135.7 18102.9 18612.3 18637.1 18812.8 18941.2 16653.6 17056.8 17237.2 17281.2 17307.2 12005.6 16693.3 16842.7 16869.5 17095.0 16188.0 16650.8 16722.6 16729.5 16775.7 9681.6 10828.8 11803.6 12131.0 12473.4 11453.0 11936.3 12171.9 13176.1 13338.7 14:45:22 INFO - PID 1654 | -------- Summary: end -------- 14:45:22 INFO - PID 1654 | 14:45:22 INFO - TEST-INFO | 1654: exit 0 14:45:22 INFO - TEST-OK | dromaeo_css | took 1114813ms 14:45:22 INFO - TEST-START | kraken 14:45:22 INFO - operating with platform_type : mac_ 14:45:22 INFO - Initialising browser for kraken test... 14:45:22 INFO - TEST-INFO | started process 1778 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/tmpBGVmGT/profile http://localhost:49241/getInfo.html) 14:45:25 INFO - PID 1778 | 2017-04-24 14:45:25.453 plugin-container[1779:10479] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 14:45:25 INFO - PID 1778 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:45:25 INFO - PID 1778 | 2017-04-24 14:45:25.459 plugin-container[1779:10479] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471f, name = 'com.apple.CFPasteboardClient' 14:45:25 INFO - PID 1778 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:45:25 INFO - PID 1778 | 2017-04-24 14:45:25.459 plugin-container[1779:10479] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:45:25 INFO - PID 1778 | __metrics Screen width/height:1600/1200 14:45:25 INFO - PID 1778 | colorDepth:24 14:45:25 INFO - PID 1778 | Browser inner width/height: 1024/689 14:45:25 INFO - PID 1778 | __metrics 14:45:25 ERROR - PID 1778 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3403: TypeError: this.tabs is undefined 14:45:26 INFO - TEST-INFO | 1778: exit 0 14:45:26 INFO - Browser initialized. 14:45:26 INFO - Running cycle 1/1 for kraken test... 14:45:26 INFO - TEST-INFO | started process 1780 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/tmpBGVmGT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:45:29 INFO - PID 1780 | 2017-04-24 14:45:29.064 plugin-container[1781:10602] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454f, name = 'com.apple.tsm.portname' 14:45:29 INFO - PID 1780 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:45:29 INFO - PID 1780 | 2017-04-24 14:45:29.064 plugin-container[1781:10602] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4713, name = 'com.apple.CFPasteboardClient' 14:45:29 INFO - PID 1780 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:45:29 INFO - PID 1780 | 2017-04-24 14:45:29.064 plugin-container[1781:10602] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:45:38 INFO - PID 1780 | RSS: Main: 237133824 14:45:38 INFO - PID 1780 | 14:45:41 INFO - PID 1780 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 14:45:42 INFO - PID 1780 | RSS: Main: 250560512 14:45:42 INFO - PID 1780 | 14:45:45 INFO - PID 1780 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 14:45:45 INFO - PID 1780 | RSS: Main: 259043328 14:45:45 INFO - PID 1780 | 14:45:49 INFO - PID 1780 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 14:45:49 INFO - PID 1780 | RSS: Main: 260468736 14:45:49 INFO - PID 1780 | 14:45:51 INFO - PID 1780 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 14:45:52 INFO - PID 1780 | RSS: Main: 262930432 14:45:52 INFO - PID 1780 | 14:45:54 INFO - PID 1780 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:45:54 INFO - PID 1780 | RSS: Main: 263458816 14:45:54 INFO - PID 1780 | 14:45:57 INFO - PID 1780 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 14:45:58 INFO - PID 1780 | RSS: Main: 269205504 14:45:58 INFO - PID 1780 | 14:46:01 INFO - PID 1780 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 14:46:01 INFO - PID 1780 | RSS: Main: 270401536 14:46:01 INFO - PID 1780 | 14:46:04 INFO - PID 1780 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 14:46:05 INFO - PID 1780 | RSS: Main: 270323712 14:46:05 INFO - PID 1780 | 14:46:06 INFO - PID 1780 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:46:07 INFO - PID 1780 | RSS: Main: 269905920 14:46:07 INFO - PID 1780 | 14:46:09 INFO - PID 1780 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:46:09 INFO - PID 1780 | RSS: Main: 269709312 14:46:09 INFO - PID 1780 | 14:46:12 INFO - PID 1780 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:46:12 INFO - PID 1780 | RSS: Main: 269217792 14:46:12 INFO - PID 1780 | 14:46:15 INFO - PID 1780 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:46:16 INFO - PID 1780 | RSS: Main: 269320192 14:46:16 INFO - PID 1780 | 14:46:19 INFO - PID 1780 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:46:20 INFO - PID 1780 | RSS: Main: 269541376 14:46:20 INFO - PID 1780 | 14:46:21 INFO - PID 1780 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 14:46:22 INFO - PID 1780 | RSS: Main: 271290368 14:46:22 INFO - PID 1780 | 14:46:22 INFO - PID 1780 | __start_tp_report 14:46:22 INFO - PID 1780 | _x_x_mozilla_page_load 14:46:22 INFO - PID 1780 | _x_x_mozilla_page_load_details 14:46:22 INFO - PID 1780 | |i|pagename|runs| 14:46:22 INFO - PID 1780 | |0;ai-astar;101;115;113;113;113;113;113;113;123;108 14:46:22 INFO - PID 1780 | |1;audio-beat-detection;145;117;142;170;111;111;111;116;159;98 14:46:22 INFO - PID 1780 | |2;audio-dft;192;198;202;227;251;238;197;202;195;195 14:46:22 INFO - PID 1780 | |3;audio-fft;79;58;59;88;61;58;59;62;78;57 14:46:22 INFO - PID 1780 | |4;audio-oscillator;82;70;84;71;68;69;91;70;71;90 14:46:22 INFO - PID 1780 | |5;imaging-gaussian-blur;88;87;88;87;93;88;89;88;100;88 14:46:22 INFO - PID 1780 | |6;imaging-darkroom;96;96;96;97;120;105;97;95;96;98 14:46:22 INFO - PID 1780 | |7;imaging-desaturate;92;92;91;92;93;91;92;93;91;90 14:46:22 INFO - PID 1780 | |8;json-parse-financial;51;50;53;61;65;52;49;52;50;50 14:46:22 INFO - PID 1780 | |9;json-stringify-tinderbox;57;65;59;69;63;65;60;55;54;57 14:46:22 INFO - PID 1780 | |10;stanford-crypto-aes;99;106;83;84;80;84;74;99;106;75 14:46:22 INFO - PID 1780 | |11;stanford-crypto-ccm;136;137;138;139;138;150;132;139;191;160 14:46:22 INFO - PID 1780 | |12;stanford-crypto-pbkdf2;186;186;190;197;192;188;188;178;209;241 14:46:22 INFO - PID 1780 | |13;stanford-crypto-sha256-iterative;70;69;66;70;71;70;68;66;66;68 14:46:22 INFO - PID 1780 | __end_tp_report 14:46:22 INFO - PID 1780 | __start_cc_report 14:46:22 INFO - PID 1780 | _x_x_mozilla_cycle_collect,1023 14:46:22 INFO - PID 1780 | __end_cc_report 14:46:22 INFO - PID 1780 | __startTimestamp1493070382139__endTimestamp 14:46:22 INFO - PID 1780 | 14:46:22 INFO - PID 1780 | ------- Summary: start ------- 14:46:22 INFO - PID 1780 | Number of tests: 14 14:46:22 INFO - PID 1780 | 14:46:22 INFO - PID 1780 | [#0] ai-astar Cycles:10 Average:112.50 Median:113.00 stddev:5.48 (4.9%) stddev-sans-first:3.93 14:46:22 INFO - PID 1780 | Values: 101.0 115.0 113.0 113.0 113.0 113.0 113.0 113.0 123.0 108.0 14:46:22 INFO - PID 1780 | 14:46:22 INFO - PID 1780 | [#1] audio-beat-detection Cycles:10 Average:128.00 Median:143.50 stddev:24.13 (16.8%) stddev-sans-first:24.80 14:46:22 INFO - PID 1780 | Values: 145.0 117.0 142.0 170.0 111.0 111.0 111.0 116.0 159.0 98.0 14:46:22 INFO - PID 1780 | 14:46:22 INFO - PID 1780 | [#2] audio-dft Cycles:10 Average:209.70 Median:202.00 stddev:21.00 (10.4%) stddev-sans-first:21.27 14:46:22 INFO - PID 1780 | Values: 192.0 198.0 202.0 227.0 251.0 238.0 197.0 202.0 195.0 195.0 14:46:22 INFO - PID 1780 | 14:46:22 INFO - PID 1780 | [#3] audio-fft Cycles:10 Average:65.90 Median:61.50 stddev:11.28 (18.3%) stddev-sans-first:10.92 14:46:22 INFO - PID 1780 | Values: 79.0 58.0 59.0 88.0 61.0 58.0 59.0 62.0 78.0 57.0 14:46:22 INFO - PID 1780 | 14:46:22 INFO - PID 1780 | [#4] audio-oscillator Cycles:10 Average:76.60 Median:76.50 stddev:9.14 (12.0%) stddev-sans-first:9.49 14:46:22 INFO - PID 1780 | Values: 82.0 70.0 84.0 71.0 68.0 69.0 91.0 70.0 71.0 90.0 14:46:22 INFO - PID 1780 | 14:46:22 INFO - PID 1780 | [#5] imaging-gaussian-blur Cycles:10 Average:89.60 Median:88.00 stddev:4.03 (4.6%) stddev-sans-first:4.24 14:46:22 INFO - PID 1780 | Values: 88.0 87.0 88.0 87.0 93.0 88.0 89.0 88.0 100.0 88.0 14:46:22 INFO - PID 1780 | 14:46:22 INFO - PID 1780 | [#6] imaging-darkroom Cycles:10 Average:99.60 Median:96.00 stddev:7.71 (8.0%) stddev-sans-first:8.06 14:46:22 INFO - PID 1780 | Values: 96.0 96.0 96.0 97.0 120.0 105.0 97.0 95.0 96.0 98.0 14:46:22 INFO - PID 1780 | 14:46:22 INFO - PID 1780 | [#7] imaging-desaturate Cycles:10 Average:91.70 Median:92.00 stddev:0.95 (1.0%) stddev-sans-first:1.00 14:46:22 INFO - PID 1780 | Values: 92.0 92.0 91.0 92.0 93.0 91.0 92.0 93.0 91.0 90.0 14:46:22 INFO - PID 1780 | 14:46:22 INFO - PID 1780 | [#8] json-parse-financial Cycles:10 Average:53.30 Median:52.00 stddev:5.33 (10.3%) stddev-sans-first:5.59 14:46:22 INFO - PID 1780 | Values: 51.0 50.0 53.0 61.0 65.0 52.0 49.0 52.0 50.0 50.0 14:46:22 INFO - PID 1780 | 14:46:22 INFO - PID 1780 | [#9] json-stringify-tinderbox Cycles:10 Average:60.40 Median:61.50 stddev:4.93 (8.0%) stddev-sans-first:5.07 14:46:22 INFO - PID 1780 | Values: 57.0 65.0 59.0 69.0 63.0 65.0 60.0 55.0 54.0 57.0 14:46:22 INFO - PID 1780 | 14:46:22 INFO - PID 1780 | [#10] stanford-crypto-aes Cycles:10 Average:89.00 Median:83.50 stddev:12.32 (14.8%) stddev-sans-first:12.52 14:46:22 INFO - PID 1780 | Values: 99.0 106.0 83.0 84.0 80.0 84.0 74.0 99.0 106.0 75.0 14:46:22 INFO - PID 1780 | 14:46:22 INFO - PID 1780 | [#11] stanford-crypto-ccm Cycles:10 Average:146.00 Median:139.00 stddev:17.76 (12.8%) stddev-sans-first:18.47 14:46:22 INFO - PID 1780 | Values: 136.0 137.0 138.0 139.0 138.0 150.0 132.0 139.0 191.0 160.0 14:46:22 INFO - PID 1780 | 14:46:22 INFO - PID 1780 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.50 Median:191.00 stddev:17.94 (9.4%) stddev-sans-first:18.70 14:46:22 INFO - PID 1780 | Values: 186.0 186.0 190.0 197.0 192.0 188.0 188.0 178.0 209.0 241.0 14:46:22 INFO - PID 1780 | 14:46:22 INFO - PID 1780 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:69.50 stddev:1.90 (2.7%) stddev-sans-first:1.92 14:46:22 INFO - PID 1780 | Values: 70.0 69.0 66.0 70.0 71.0 70.0 68.0 66.0 66.0 68.0 14:46:22 INFO - PID 1780 | -------- Summary: end -------- 14:46:22 INFO - PID 1780 | 14:46:23 INFO - TEST-INFO | 1780: exit 0 14:46:23 INFO - TEST-OK | kraken | took 60278ms 14:46:23 INFO - SUITE-END | took 1175s 14:46:23 INFO - Completed test suite (00:19:35) 14:46:23 INFO - javascript benchmark 14:46:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7792.186830752106, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3205.9038228853137, 3542.433210686292, 3561.1809283864727, 3588.336157810805, 3605.9459108113374, 5064.468230835764, 5723.942760572388, 6045.425684559971, 6140.8464788380315, 6148.077788331747, 6111.774065234695, 6171.969140154304, 6173.0, 6243.937560624392, 6273.529485288599, 5405.594580406466, 5407.540359069484, 5418.376886658026, 5431.131019036955, 5451.3458384993655, 5401.648892821973, 5451.2640793492965, 5494.340679118512, 5502.80740174094, 5551.8889622207535, 4468.642508599337, 5280.709560974145, 5329.840104796862, 5476.315460567429, 5490.423505531921, 5322.095243808523, 5333.120035194227, 5349.973250133763, 5407.3781515125875, 5456.181572764078, 5305.0, 5313.468653134694, 5337.412884582732, 5381.569474442035, 5411.702356370398, 5976.103584462322, 6106.053561697944, 6211.937880621181, 6214.502839772807, 6245.156903817971, 6335.334789847092, 6383.744650214032, 6516.9674151629415, 6599.703013364362, 6604.801855944329, 5831.183634291203, 6717.932820671828, 6738.764143255012, 6744.49416293775, 6753.763618273337, 6194.81415557534, 6335.7148928297875, 6345.587536810139, 6354.650494222769, 6356.046593010993, 6539.313372095861, 6602.603843769293, 6748.898766518506, 6883.518153729191, 6892.517523773287, 5126.384833819965, 6290.056491526308, 6364.109024736541, 6440.130582371414, 6470.838229044311, 6672.466202703772, 6702.698378572976, 6704.094947182166, 6707.262201157869, 6708.798736037927, 5260.631755777135, 6465.676716164267, 6558.606483610999, 6561.770338038145, 6592.307807680125, 6219.440250377488, 6426.35736426363, 6540.247871494744, 6563.343665633399, 6569.0, 5195.870103247374, 5204.245384419238, 5208.0, 5243.475652434802, 5263.578913686896, 4651.581357677757, 4674.74288914113, 4697.553732395485, 4707.482176960531, 4722.740249286254, 6183.752649894044, 6309.400606942333, 6351.079093531412, 6370.108184854124, 6384.361563843579, 6403.64779937108, 6498.447631951242, 6684.766033188815, 6701.530892837454, 6715.7985260442265, 6338.651374174465, 6370.235571731422, 6371.713272902638, 6380.7128679209545, 6384.648844313608, 5818.5636077294375, 5836.095405212199, 5853.7365818538265, 5854.297484301911, 5895.174675545427, 6647.169103862017, 6822.522423430312, 6832.6925288362145, 6918.8616227676175, 6936.4797640176175, 6508.381703738138, 6557.278699342973, 6611.702473388806, 6697.7655782047395, 6722.0, 6884.965575172092, 6908.3437073477935, 6922.93077069243, 6929.792106236821, 6933.0986971693355, 6066.847299013173, 6239.5008399327035, 6593.571417857824, 6616.073749674953, 6620.834479137868, 6395.136656551492, 6442.35576442352, 6609.768658096944, 6620.867582648224, 6643.468522518284, 5893.734781934738, 6502.804915852532, 6509.511786615928, 6522.967385163044, 6523.608583485006, 6311.116443697978, 6339.96830015847, 6416.294207637063, 6506.154199953976, 6514.0880276760345, 5518.758279387106, 6137.0, 6336.841578960563, 6419.967900160469, 6420.518461115342, 5234.764435600483, 6472.546921715623, 6483.805485835433, 6503.414692677682, 6547.1161393211305, 5253.579713622825, 6231.781887633911, 6288.0882272069375, 6363.681815909279, 6394.87210255783], "value": 6010.374897657321, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [730.218666027351, 750.2534977697192, 811.290121143999, 1044.650042235851, 1245.869183735707, 34284.82857585715, 34751.30497390051, 36941.26117477652, 37740.86777396679, 37851.81074094633, 29527.261818454495, 33220.66779332212, 34827.16901140879, 36933.815330923375, 37333.0, 6560.343965603437, 7154.749583764569, 8190.099089100194, 8626.137386261387, 8637.870431943526, 8574.399792014561, 8695.0, 8703.0, 8705.216530512253, 8713.390062695615, 7124.254557633361, 8280.958595207016, 8677.826443471127, 8687.913120868805, 8711.520866352348, 8643.524606146659, 8665.610047547843, 8688.261497772697, 8696.695615653454, 8701.7824554386, 8312.1687831217, 8345.95827020867, 8358.498490090553, 8372.958135209346, 8410.537420441873, 37381.0, 37397.0, 37525.0, 37588.812055939816, 37683.0, 3575.481555174511, 3605.152789094561, 3621.6559426854406, 3622.7826330420257, 3623.8912832615065, 31183.84408077945, 31207.0, 31237.062888113393, 31240.0, 31292.843535782176, 3128.4212420702015, 3151.6533181350032, 3152.653208147102, 3165.0346644273486, 3180.316232010106, 229809.0, 233735.0, 282449.17550824437, 293230.0, 293622.0, 47313.763431182626, 47559.048819024116, 47658.285125723145, 47706.761466192445, 47799.76100119547, 49577.0084598306, 49657.0068598626, 49803.25295120576, 49803.75098124559, 49855.0, 13887.0, 13956.302184890716, 13995.650108747363, 14037.298135093204, 14053.85946140546, 11290.548378064785, 11352.772944541228, 11477.426128693533, 11479.483423245974, 11484.94257528707, 10867.510962006729, 12226.938865305794, 12284.447199876027, 13457.327133643279, 13485.460581576823, 5986.281646202397, 6070.787522436782, 6084.908726369108, 6095.299040610298, 6162.7226774795245, 1002.1381611813835, 1018.3126389686931, 1021.4331046269243, 1047.3925123428546, 1052.894710528941, 22229.0, 23260.0, 23265.418364541023, 24252.87873560621, 24387.756122438903, 963.5037955452947, 1094.8631421072366, 1139.4416735799398, 1141.19545720267, 1143.3082984794246, 11960.0, 12269.815952760715, 12338.012958963442, 12354.629361119181, 12411.875881241072, 324663.0, 324949.0, 325794.0, 326271.0, 327248.3637581844, 51055.744721276154, 51334.0, 51430.74284628553, 51468.48531514712, 51591.74204128955, 1445.6313640021726, 1497.663025819182, 1498.3856618786463, 1500.5873384819263, 1510.6978604279188, 20784.06471708807, 20799.1680332785, 23976.40058998539, 23979.400514987265, 24123.0, 30586.541201882435, 37315.6939507116, 37344.81327593345, 37516.43725344176, 37552.87341379763, 286476.0, 287455.0, 287596.0, 287709.0, 288102.0, 29291.70708292975, 32616.53225604901, 33382.0, 33914.66085339214, 33969.32061358808, 44134.77932610316, 44696.0, 44763.55236447724, 44818.77590612026, 44831.32753008642, 25012.249632511055, 29377.853110734308, 29979.70020299769, 30248.84875575608, 30405.935792247164, 29897.0, 30314.84842575773, 30354.241143971576, 30364.0, 30586.0, 36005.0, 36266.0, 36350.0, 36979.0, 37549.0, 30433.087007389815, 30823.383532329677, 30961.845190773904, 31076.68923310738, 31549.0, 21884.45288867791, 22698.432539185993, 23936.640950386107, 24022.87988560046, 24475.0], "value": 17215.12303779684, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [441.44378083614635, 444.9532799056099, 445.8060743576543, 457.2660879288741, 467.7614416647511, 20843.687344689824, 21306.57386852264, 22094.337169884908, 23038.308850734506, 23286.0, 22351.55296894061, 22938.85305734715, 23190.956406961686, 23267.301980940556, 23470.530589388243, 6027.789027384042, 6112.6638034908065, 6189.783357582485, 6217.036359364306, 6285.2771931228035, 5144.996725638502, 6208.875822483547, 6211.6273023618505, 6216.564840461169, 6219.19150510433, 5212.687238765668, 5859.355470898197, 6214.415324092372, 6224.097505861648, 6233.3143354231, 5193.506616871429, 6031.522277042152, 6154.076888466721, 6182.876342473148, 6196.659183744892, 5526.751296191641, 5531.0, 5544.91682624761, 5551.833444996657, 5567.359753628303, 26648.93404263827, 26781.866090669617, 27271.909123635032, 27486.862565687043, 27631.30921726953, 2239.6192647249845, 2261.3329067925, 2277.02088102116, 2278.453171238907, 2280.429892526868, 17231.31074757008, 17774.28902843885, 18302.176402061938, 18342.90828545862, 18538.0, 2096.549241912981, 2156.827453803692, 2167.2089687264192, 2178.161407857986, 2186.5954798362354, 148523.25738371347, 156213.0, 157425.0, 158902.0, 159788.20105899512, 10228.670272864629, 11589.768204636028, 11795.941020294844, 12075.154739168174, 12605.495780168874, 16430.26063827107, 16560.420385286456, 16563.337466501205, 16563.420280290375, 16575.0, 7360.374368178657, 8655.394122411497, 8937.776555586162, 8943.508107054175, 8948.8657670135, 6924.4114250289285, 8367.372447066495, 8372.079071302152, 8397.076321604618, 8551.187637174458, 7904.20957904205, 7994.5602991836, 8012.716699637743, 8058.435909486279, 8090.474119182234, 2014.2647933504165, 2063.7720556268996, 2211.8672879627275, 2219.2676416782424, 2374.062245413052, 807.8626633472323, 939.3236869453982, 994.4132961552718, 996.4967691315984, 1009.6163457886104, 20066.89966550158, 24699.62950555743, 26125.477490450085, 26574.0, 26644.46711065768, 774.6397924964919, 874.3343929813589, 925.7222833150028, 1021.9642312519026, 1036.8444733290066, 7862.52824830512, 10511.52698128586, 11110.888891111146, 11114.610988615521, 11130.0, 567561.0, 579560.0, 581075.0, 591740.0, 593583.0, 110054.44972775085, 113208.0, 113348.43325783318, 125773.0, 127901.0, 1168.6318809574957, 1174.6241202815017, 1233.272369302116, 1233.272369302116, 1235.1354052163522, 21543.56912861765, 21711.565768684854, 21749.565008700054, 21880.45298867541, 22156.0, 45352.59834147349, 50722.985540288246, 51375.48624513707, 51950.74024629853, 53132.20301695555, 70291.29708702848, 75408.0, 76285.71142865723, 77485.0, 79149.60425197837, 7488.288612581796, 8710.82578348442, 8811.691590794291, 8853.557322133996, 9080.228180604723, 13199.07606467538, 13838.515651952133, 13843.169409835442, 13915.930420347833, 14302.642433939234, 11852.525898963944, 11888.34614096233, 11945.462454189408, 12130.636080917586, 12146.574869879512, 9636.614535418505, 9957.651482198089, 11272.323660580392, 11311.717207069889, 11592.6522204334, 11930.463129159036, 12108.515659373526, 12223.20549164337, 12359.25844449336, 12396.380180990735, 8558.871616925626, 8740.912590874264, 8891.333150013645, 8991.505467199368, 9016.504092274989, 11759.647210583697, 11840.0, 11888.28670279786, 11942.4028798558, 12044.096692748255], "value": 10428.692990922335, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1194.7729931313052, 1424.9501267455641, 1426.8430472648013, 1458.9932946267081, 1461.8802736032756, 11602.129840261983, 11829.881701182985, 12015.03879689625, 12067.396630168489, 12165.330906800122, 11759.941605255526, 11945.820812687816, 11949.761004779899, 11970.401479926013, 11986.280823150617, 2500.0124950644486, 2551.413174969758, 2552.4001859562986, 2552.412945022646, 2553.1957433408415, 2405.8556486610773, 2443.58459061959, 2452.0, 2459.053264493174, 2487.955058875277, 2475.282168171228, 2492.626106084084, 2498.487809998946, 2504.3113143885485, 2516.962245566318, 2461.901523939049, 2480.8635525046116, 2518.5214809186286, 2522.066835270943, 2525.5201511712717, 2413.1192114878118, 2498.6376975338476, 2513.1204078572537, 2513.7611926866916, 2529.759672831078, 12077.15459917806, 12152.453139608739, 12184.329861857594, 12185.45165467556, 12185.695357616041, 6652.101966234545, 6828.2488926218075, 6868.381845633917, 6878.3465570770695, 6894.0, 4034.677225821927, 4210.536840947493, 4287.485501739811, 4305.440292761952, 4469.530699276562, 1254.6361555148997, 1262.066071107374, 1265.4052595280205, 1303.1985329022677, 1303.4851233762706, 16260.674786504203, 16800.83199168017, 16858.49424517254, 16932.23804928781, 16932.23804928781, 5216.634835561475, 5632.098864181711, 5789.507891829157, 5836.795712150054, 5895.292564892155, 9683.757906052406, 12410.875891240972, 12489.93755031219, 12497.500099995897, 12538.87461125377, 6676.799696009128, 6678.9332106679285, 6687.097241872285, 6692.631905245161, 6694.364035416627, 6046.395360463918, 6114.144019837227, 6205.162303089115, 6267.467265282411, 6269.843253918597, 4398.516163222043, 4497.482789479252, 4537.931931021038, 4542.88642783933, 4626.37543931565, 1301.225770666452, 1324.7814110671825, 1332.1074879830537, 1335.5125379236508, 1351.7499262636443, 785.2422412371974, 804.1074407407773, 807.5598798654747, 816.4366587054913, 821.97945051373, 6150.3849615039035, 6160.7535698571555, 6315.336889626615, 6324.0, 6397.0, 480.92305231162845, 551.503646717957, 588.076719550302, 594.1414655822296, 606.6511755740466, 3659.524261845943, 3813.4470501777087, 3934.960650393459, 3965.8017099144927, 3988.4615576897327, 14424.927875360765, 14549.78175327371, 14974.401023959137, 15029.09825410457, 15040.947133700536, 14287.714245715028, 14988.775168372484, 15400.0, 15450.536483905276, 15494.0, 8535.914640853513, 8567.143285671507, 8599.699010534601, 8671.609777560025, 8743.38796284254, 5956.731947062306, 6942.166939967236, 7100.899360599115, 7197.9922810807575, 7306.707731690885, 13408.530701425403, 13462.394192261176, 13661.590152295446, 13743.862561374259, 13785.862141378859, 13900.304984750925, 13958.720825583634, 13969.231692257023, 14021.859781402463, 14150.363233654716, 9485.762855928657, 9703.07820757027, 9799.0, 9804.313698041069, 9826.16477599412, 10169.186465082628, 10300.484975751333, 10358.119559837498, 10378.06597406237, 10388.0, 7266.273372662691, 8797.516136612549, 8805.295576354005, 8827.293816494537, 8898.021217665922, 8596.957015214884, 8676.696315629175, 8700.56497175126, 8745.781355466164, 8779.56102194875, 7829.060512738508, 7836.529808211526, 7915.525068495908, 7923.484973476706, 7932.325752310888, 5897.262842144732, 5908.438698323653, 6012.6993650316435, 6076.51387888959, 6152.169457123231, 5499.312585926759, 5900.881982360243, 5969.0, 6063.545234107548, 6126.509879209563], "value": 5328.361195699785, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.1496967762528, 381.4031041420177, 410.13052329062356, 410.1816875333709, 412.7317243791533, 6002.159697642333, 6119.847003824907, 6198.411150940653, 6221.222347206599, 6224.719887605057, 5955.970220148904, 5992.7902523411685, 6049.517868122316, 6085.756569737206, 6087.543434242439, 4473.619742321906, 4488.304312831517, 4489.932651010237, 4495.0, 4500.7074540154945, 3701.6298370162904, 4368.84225680195, 4450.154470650582, 4501.7524036178, 4505.098980203956, 4387.495438024636, 4395.6483481321575, 4413.4262545869, 4416.447944006999, 4417.757023363713, 4362.47650281965, 4413.580709832593, 4432.047109871377, 4436.977815110936, 4442.733435993819, 3172.841357932094, 3738.1776009278187, 3851.691864650821, 3856.151646637735, 3875.3411919973673, 5728.513076388512, 5737.483626473596, 5790.536757059425, 5841.474267315962, 5842.328132264802, 4572.719638501225, 5064.949350506485, 5291.629585928987, 5294.285271488338, 5297.973510132463, 5696.316442026983, 6020.886136064842, 6375.968120159416, 6387.552871299011, 6394.200724909387, 3596.7482276240676, 4333.414988976511, 4344.739315641072, 4359.237133501624, 4360.476742790854, 5930.911036334502, 6295.0872123542285, 6380.234371875358, 6825.795226143224, 6940.6529673516125, 4369.060651959844, 4759.904801903943, 4832.44426890905, 4850.927236091461, 4861.8188358012085, 4952.603791696655, 4958.826441074545, 4959.7272150032095, 4964.15609346412, 4964.975175124101, 4032.6975476838766, 5500.559955203574, 5525.005499010136, 5542.168674698827, 5545.944540554624, 4640.512746161672, 5854.619449735754, 6249.25008998923, 6478.676066196671, 6534.215894092739, 1623.2370785730689, 1680.4706517446964, 1687.6034131978975, 1692.636083242109, 1695.313398073782, 1083.664064140119, 1176.452949378543, 1183.8046722290899, 1186.6677330347516, 1188.316717887201, 6116.785912493041, 6149.815505534841, 6163.38366163389, 6172.567920245539, 6181.598196117328, 5621.522170615462, 5848.3274423441, 5882.852843695438, 6637.369449902348, 6713.865722685519, 5296.841094767163, 5439.5376393006245, 5669.2346533218315, 5869.855378201295, 6106.694665266719, 2891.5228987216874, 3141.1361875484426, 3247.3438546341063, 3450.9474610243615, 3488.4767284907466, 5670.7393248881235, 6619.437347825488, 6790.219124800711, 6841.692123854438, 6898.827529311808, 6550.639714815731, 6814.1482314710665, 6831.348889244078, 6851.9657401712675, 6887.552309099892, 5429.484199001088, 5585.0, 6514.7621951829, 6757.121574195323, 6813.182418109858, 5154.7938082476285, 6275.309715931336, 6651.866962660816, 6699.29657385987, 6712.482818942896, 5362.276092727588, 6270.122182894307, 6536.548794202175, 6613.735450581923, 6624.403803657694, 6976.232614412309, 6984.511084224258, 7011.193712723102, 7013.544119632207, 7038.894416583653, 3750.9872334470087, 4451.1765323415275, 4472.888177795581, 4479.641628669764, 4506.324051392186, 5585.888282234414, 6483.683379628659, 6682.291328312833, 6687.4984376171005, 6779.966100169468, 6522.771702990372, 6550.886349320532, 6572.108272360147, 6572.638504882278, 6573.375529324707, 5066.782593053364, 5536.363318218296, 6049.909251361321, 6246.688195478819, 6497.382748638872, 5055.823046193513, 6291.993281075006, 6344.587601805868, 6347.365263473615, 6353.618782873043, 5929.673867937305, 6329.430351268408, 6374.74501019954, 6466.82418290522, 6496.837579060372, 5632.380438151882, 5873.73714651352, 6251.68741562911, 6346.873062538816, 6368.77709280173], "value": 4726.55245820643, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [770.5376773935637, 815.8816971539127, 828.5401602110826, 866.2766589897435, 868.6351732272446, 14717.558473245794, 15300.31148598313, 16593.58516037098, 17065.57336066599, 17073.914630426847, 14340.42638294467, 16946.0, 17508.737368939444, 17689.81583315006, 17978.64042719148, 950.7765675066354, 965.8696076029752, 970.2529052629471, 973.9464329461877, 975.512243878061, 984.3355734878963, 988.8813342398922, 989.114742305636, 992.7121134870879, 994.1351024608565, 1046.5499835070916, 1047.308776207703, 1047.9056884880358, 1049.082053203447, 1054.1092776603757, 1035.29082578434, 1037.1132681557235, 1038.9064984151426, 1041.5156952017273, 1041.6406339812752, 766.5592284436472, 769.4921351907714, 769.7498313048281, 770.0412985832618, 774.3340726974798, 16468.0, 16477.83522164775, 16875.409360672318, 17111.657766844717, 17116.229769660396, 13872.722545549033, 14014.789778153336, 14967.027143235764, 16665.500034998968, 16998.83001169997, 13591.184528928296, 13955.0, 14181.503647372292, 14182.43270269188, 14193.503227387095, 3398.0, 3411.30068335993, 3431.571053618298, 3476.1135910342955, 3568.6074531801482, 113123.86876131297, 120409.0, 121140.0, 122268.77731222662, 122755.0, 11970.82043769344, 13170.539031133865, 13311.001674874424, 13332.800007999887, 13335.466581336637, 24771.752282477304, 24953.251402457594, 25322.74677253204, 25742.87128564345, 25759.87120064425, 6726.562773419712, 7457.2542745724995, 7610.3150716434675, 7688.346490548254, 7768.184340644264, 3273.8199399032806, 3859.864904728321, 3892.260470510594, 3912.491376121143, 3938.547067087264, 3530.3292374448774, 4264.253755592821, 4309.310510318334, 4310.89222769427, 4375.234333991539, 839.7564706235123, 859.8882145321069, 891.1801142948503, 913.5112714697605, 924.0, 639.8368416053969, 654.9050387693853, 658.9044588534636, 659.6602749583968, 687.0690214759029, 10318.690439286833, 11170.720731981603, 11318.26431281964, 11576.76846463066, 11823.467943942544, 643.9053608864948, 644.3073695777058, 653.9836504087341, 658.6871236162784, 672.8687905858311, 5336.95929293784, 5527.115661494222, 5538.390777014525, 5567.805126820542, 5570.97214513933, 100073.49963250138, 100856.0, 101278.49360753149, 101687.47468787975, 103217.48391257996, 54736.0, 87755.56122219349, 89615.0, 91382.62926056114, 93069.5346523263, 63013.47946082197, 82841.17158828335, 84039.0, 84091.57954210312, 86656.0, 12085.591329295547, 12618.17981831178, 12641.620751377473, 12655.303958282382, 12720.809187862003, 38286.85139445821, 42056.36915446332, 42679.93300167521, 43144.56855431406, 43253.0, 49202.753986229836, 53164.0, 53819.46180538301, 54155.0, 54505.45494545004, 16126.516204513884, 16318.755218671491, 16330.755038674666, 16521.504354869372, 17135.742963855304, 18102.90948545249, 18612.34856780006, 18637.068146592886, 18812.811871881655, 18941.242350305834, 16653.5836604081, 17056.829431705522, 17237.224324905157, 17281.222344994254, 17307.2211750469, 12005.579804706793, 16693.332266709196, 16842.747358789875, 16869.493915182564, 17095.0, 16188.0, 16650.83349166493, 16722.58193545171, 16729.49811505657, 16775.66448671044, 9681.564329605044, 10828.783424331627, 11803.586874459354, 12131.0, 12473.376331183586, 11453.0, 11936.28382297065, 12171.939140304241, 13176.077674563072, 13338.733225335633], "value": 8236.554859799417, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1486.2000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 115.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 123.0, 108.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 117.0, 142.0, 170.0, 111.0, 111.0, 111.0, 116.0, 159.0, 98.0], "value": 128.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 198.0, 202.0, 227.0, 251.0, 238.0, 197.0, 202.0, 195.0, 195.0], "value": 209.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 59.0, 88.0, 61.0, 58.0, 59.0, 62.0, 78.0, 57.0], "value": 65.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 70.0, 84.0, 71.0, 68.0, 69.0, 91.0, 70.0, 71.0, 90.0], "value": 76.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 88.0, 87.0, 93.0, 88.0, 89.0, 88.0, 100.0, 88.0], "value": 89.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 97.0, 120.0, 105.0, 97.0, 95.0, 96.0, 98.0], "value": 99.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 91.0, 92.0, 93.0, 91.0, 92.0, 93.0, 91.0, 90.0], "value": 91.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 53.0, 61.0, 65.0, 52.0, 49.0, 52.0, 50.0, 50.0], "value": 53.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 65.0, 59.0, 69.0, 63.0, 65.0, 60.0, 55.0, 54.0, 57.0], "value": 60.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 106.0, 83.0, 84.0, 80.0, 84.0, 74.0, 99.0, 106.0, 75.0], "value": 89.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 137.0, 138.0, 139.0, 138.0, 150.0, 132.0, 139.0, 191.0, 160.0], "value": 146.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 190.0, 197.0, 192.0, 188.0, 188.0, 178.0, 209.0, 241.0], "value": 195.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 66.0, 70.0, 71.0, 70.0, 68.0, 66.0, 66.0, 68.0], "value": 68.4, "unit": "score"}]}]} 14:46:23 INFO - Return code: 0 14:46:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:46:23 ERROR - # TBPL SUCCESS # 14:46:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:46:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:46:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:46:23 INFO - 2017-04-24 14:46:23.454 screenresolution[1791:10998] starting screenresolution argv=screenresolution get 14:46:23 INFO - 2017-04-24 14:46:23.457 screenresolution[1791:10998] Display 0: 1600x1200x32@60 14:46:23 INFO - 2017-04-24 14:46:23.464 screenresolution[1792:11001] starting screenresolution argv=screenresolution list 14:46:23 INFO - Available Modes on Display 0 14:46:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:46:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:46:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:46:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:46:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:46:23 INFO - Intel Iris: 14:46:23 INFO - Chipset Model: Intel Iris 14:46:23 INFO - Type: GPU 14:46:23 INFO - Bus: Built-In 14:46:23 INFO - VRAM (Dynamic, Max): 1536 MB 14:46:23 INFO - Vendor: Intel (0x8086) 14:46:23 INFO - Device ID: 0x0a2e 14:46:23 INFO - Revision ID: 0x0009 14:46:23 INFO - Displays: 14:46:23 INFO - Display: 14:46:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:46:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:46:23 INFO - Display Serial Number: 1600x1200 60 14:46:23 INFO - Main Display: Yes 14:46:23 INFO - Mirror: Off 14:46:23 INFO - Online: Yes 14:46:23 INFO - Rotation: Supported 14:46:23 INFO - Return code: 0 14:46:23 INFO - Running post-action listener: _resource_record_post_action 14:46:23 INFO - [mozharness: 2017-04-24 21:46:23.709890Z] Finished run-tests step (success) 14:46:23 INFO - Running post-run listener: _resource_record_post_run 14:46:23 INFO - Total resource usage - Wall time: 1195s; CPU: 54.0%; Read bytes: 39823872; Write bytes: 514324992; Read time: 261; Write time: 1668 14:46:23 INFO - TinderboxPrint: CPU usage
54.1% 14:46:23 INFO - TinderboxPrint: I/O read bytes / time
39,823,872 / 261 14:46:23 INFO - TinderboxPrint: I/O write bytes / time
514,324,992 / 1,668 14:46:23 INFO - TinderboxPrint: CPU idle
2,192.5 (45.9%) 14:46:23 INFO - TinderboxPrint: CPU system
965.4 (20.2%) 14:46:23 INFO - TinderboxPrint: CPU user
1,623.7 (34.0%) 14:46:23 INFO - TinderboxPrint: Swap in / out
247,717,888 / 0 14:46:23 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 209928704; Write bytes: 212580352; Read time: 16133; Write time: 460 14:46:23 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 35822080; Write bytes: 300409344; Read time: 230; Write time: 1203 14:46:24 INFO - Running post-run listener: _upload_blobber_files 14:46:24 INFO - Blob upload gear active. 14:46:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:46:24 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:46:24 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'] 14:46:24 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 14:46:24 INFO - (blobuploader) - INFO - Open directory for files ... 14:46:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:46:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:46:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:46:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:46:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:46:25 INFO - (blobuploader) - INFO - Done attempting. 14:46:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:46:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:46:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:46:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:46:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:46:26 INFO - (blobuploader) - INFO - Done attempting. 14:46:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:46:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:46:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:46:27 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:46:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:46:27 INFO - (blobuploader) - INFO - Done attempting. 14:46:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:46:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:46:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:46:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:46:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:46:29 INFO - (blobuploader) - INFO - Done attempting. 14:46:29 INFO - (blobuploader) - INFO - Iteration through files over. 14:46:29 INFO - Return code: 0 14:46:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:46:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:46:29 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/a904357c52f4b984ffe6099624d1170d9062a1a7f94fe0c14b2f68e85fac5a7e1e44612b724f7d69e47b74d3702db372b1143a13bda06f5534b5e25a3dea903c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/da2022e2a83ab3f4809b3ea8c182d3a4bfa0a213b7792d462b0aea378b37af84da646361b9432f0907f1b07c5a3075a62c0f3b23d6a727836028ad6fe52f449c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/92a0333ba335007952230bfccdfcdcf5755104a3a89e0a9281c9d367047fcc52ae5cefb95a87e3ba03654992878dc8e5529e05f63b6ac3db1cb1154bc5c64ef0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/896372311972148c640675ed430d3879975231ea3e48e4b36b9cecf985032677cd8c9ff389e1556d88979b6db702637e8238f9c869ea77a03a7b4ea18162a81f"} 14:46:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:46:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:46:29 INFO - Contents: 14:46:29 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/a904357c52f4b984ffe6099624d1170d9062a1a7f94fe0c14b2f68e85fac5a7e1e44612b724f7d69e47b74d3702db372b1143a13bda06f5534b5e25a3dea903c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/da2022e2a83ab3f4809b3ea8c182d3a4bfa0a213b7792d462b0aea378b37af84da646361b9432f0907f1b07c5a3075a62c0f3b23d6a727836028ad6fe52f449c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/92a0333ba335007952230bfccdfcdcf5755104a3a89e0a9281c9d367047fcc52ae5cefb95a87e3ba03654992878dc8e5529e05f63b6ac3db1cb1154bc5c64ef0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/896372311972148c640675ed430d3879975231ea3e48e4b36b9cecf985032677cd8c9ff389e1556d88979b6db702637e8238f9c869ea77a03a7b4ea18162a81f"} 14:46:29 INFO - Running post-run listener: copy_logs_to_upload_dir 14:46:29 INFO - Copying logs to upload dir... 14:46:29 INFO - mkdir: /builds/slave/test/build/upload/logs 14:46:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.000863 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-04-24 14:46:29.663941) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-24 14:46:29.667620) ========= 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.zOa905mLRS/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.OV69OXjmmV/Listeners TMPDIR=/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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/a904357c52f4b984ffe6099624d1170d9062a1a7f94fe0c14b2f68e85fac5a7e1e44612b724f7d69e47b74d3702db372b1143a13bda06f5534b5e25a3dea903c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/da2022e2a83ab3f4809b3ea8c182d3a4bfa0a213b7792d462b0aea378b37af84da646361b9432f0907f1b07c5a3075a62c0f3b23d6a727836028ad6fe52f449c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/92a0333ba335007952230bfccdfcdcf5755104a3a89e0a9281c9d367047fcc52ae5cefb95a87e3ba03654992878dc8e5529e05f63b6ac3db1cb1154bc5c64ef0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/896372311972148c640675ed430d3879975231ea3e48e4b36b9cecf985032677cd8c9ff389e1556d88979b6db702637e8238f9c869ea77a03a7b4ea18162a81f"} build_url:https://queue.taskcluster.net/v1/task/E5FG0YYUSHqsPXo0gAcHAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011250 build_url: 'https://queue.taskcluster.net/v1/task/E5FG0YYUSHqsPXo0gAcHAQ/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/a904357c52f4b984ffe6099624d1170d9062a1a7f94fe0c14b2f68e85fac5a7e1e44612b724f7d69e47b74d3702db372b1143a13bda06f5534b5e25a3dea903c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/da2022e2a83ab3f4809b3ea8c182d3a4bfa0a213b7792d462b0aea378b37af84da646361b9432f0907f1b07c5a3075a62c0f3b23d6a727836028ad6fe52f449c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/92a0333ba335007952230bfccdfcdcf5755104a3a89e0a9281c9d367047fcc52ae5cefb95a87e3ba03654992878dc8e5529e05f63b6ac3db1cb1154bc5c64ef0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/896372311972148c640675ed430d3879975231ea3e48e4b36b9cecf985032677cd8c9ff389e1556d88979b6db702637e8238f9c869ea77a03a7b4ea18162a81f"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-24 14:46:29.706655) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 14:46:29.707030) ========= 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.zOa905mLRS/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.OV69OXjmmV/Listeners TMPDIR=/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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.005093 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 14:46:29.756147) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-24 14:46:29.756468) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-24 14:46:29.757108) ========= ========= Total master_lag: 0.27 =========