builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0301 starttime: 1492043075.07 results: success (0) revision: 03b9065133d1bdbf7d5aa534c9345444b1729627 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-12 17:24:35.071332) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-12 17:24:35.071830) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-12 17:24:35.072154) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-12 17:24:35.242461) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 17:24:35.242916) ========= 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.JynS31bETa/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.0TtkLHU9qy/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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-12 17:24:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.56M=0.001s 2017-04-12 17:24:35 (8.56 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.260059 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 17:24:35.551934) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 17:24:35.552248) ========= 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.JynS31bETa/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.0TtkLHU9qy/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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.021649 ========= master_lag: 0.10 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 17:24:35.672824) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-12 17:24:35.673189) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev 03b9065133d1bdbf7d5aa534c9345444b1729627 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo projects/pine --rev 03b9065133d1bdbf7d5aa534c9345444b1729627 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JynS31bETa/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.0TtkLHU9qy/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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-12 17:24:35,787 truncating revision to first 12 chars 2017-04-12 17:24:35,787 Setting DEBUG logging. 2017-04-12 17:24:35,787 attempt 1/10 2017-04-12 17:24:35,787 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/03b9065133d1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-12 17:24:37,642 unpacking tar archive at: pine-03b9065133d1/testing/mozharness/ program finished with exit code 0 elapsedTime=2.208387 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-12 17:24:37.932860) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-12 17:24:37.933181) ========= script_repo_revision: 03b9065133d1bdbf7d5aa534c9345444b1729627 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-12 17:24:37.933545) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-12 17:24:37.933827) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-12 17:24:38.061242) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-04-12 17:24:38.061641) ========= /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.JynS31bETa/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.0TtkLHU9qy/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 17:24:38 INFO - MultiFileLogger online at 20170412 17:24:38 in /builds/slave/test 17:24:38 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 17:24:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:24:38 INFO - {'append_to_log': False, 17:24:38 INFO - 'base_work_dir': '/builds/slave/test', 17:24:38 INFO - 'blob_upload_branch': 'Pine', 17:24:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:24:38 INFO - 'branch': 'Pine', 17:24:38 INFO - 'buildbot_json_path': 'buildprops.json', 17:24:38 INFO - 'config_files': ('talos/mac_config.py',), 17:24:38 INFO - 'default_actions': ('clobber', 17:24:38 INFO - 'read-buildbot-config', 17:24:38 INFO - 'download-and-extract', 17:24:38 INFO - 'populate-webroot', 17:24:38 INFO - 'create-virtualenv', 17:24:38 INFO - 'install', 17:24:38 INFO - 'run-tests'), 17:24:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:24:38 INFO - 'download_minidump_stackwalk': True, 17:24:38 INFO - 'download_symbols': 'ondemand', 17:24:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:24:38 INFO - 'tooltool.py': '/tools/tooltool.py', 17:24:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:24:38 INFO - '/tools/misc-python/virtualenv.py')}, 17:24:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:24:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:24:38 INFO - 'gecko_profile': False, 17:24:38 INFO - 'gecko_profile_interval': 0, 17:24:38 INFO - 'installer_path': 'installer.exe', 17:24:38 INFO - 'log_level': 'info', 17:24:38 INFO - 'log_name': 'talos', 17:24:38 INFO - 'log_to_console': True, 17:24:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:24:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:24:38 INFO - 'opt_config_files': (), 17:24:38 INFO - 'pip_index': False, 17:24:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:24:38 INFO - 'cmd': ('bash', 17:24:38 INFO - '-c', 17:24:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:24:38 INFO - 'enabled': True, 17:24:38 INFO - 'halt_on_failure': False, 17:24:38 INFO - 'name': 'check_screen_resolution'},), 17:24:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:24:38 INFO - 'cmd': ('bash', 17:24:38 INFO - '-c', 17:24:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:24:38 INFO - 'enabled': True, 17:24:38 INFO - 'halt_on_failure': False, 17:24:38 INFO - 'name': 'check_screen_resolution'},), 17:24:38 INFO - 'run_cmd_checks_enabled': True, 17:24:38 INFO - 'suite': 'dromaeojs-e10s', 17:24:38 INFO - 'system_bits': '32', 17:24:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:24:38 INFO - 'title': 't-yosemite-r7-0301', 17:24:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:24:38 INFO - 'use_talos_json': True, 17:24:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:24:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:24:38 INFO - 'work_dir': 'build'} 17:24:38 INFO - [mozharness: 2017-04-13 00:24:38.274167Z] Running clobber step. 17:24:38 INFO - Running pre-action listener: _resource_record_pre_action 17:24:38 INFO - Running main action method: clobber 17:24:38 INFO - rmtree: /builds/slave/test/build 17:24:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:24:38 INFO - Running post-action listener: _resource_record_post_action 17:24:38 INFO - [mozharness: 2017-04-13 00:24:38.943480Z] Finished clobber step (success) 17:24:38 INFO - [mozharness: 2017-04-13 00:24:38.943661Z] Running read-buildbot-config step. 17:24:38 INFO - Running pre-action listener: _resource_record_pre_action 17:24:38 INFO - Running main action method: read_buildbot_config 17:24:38 INFO - Using buildbot properties: 17:24:38 INFO - { 17:24:38 INFO - "upload_to_task_id": "OBlEyJpKT6WAro72HVAHsA", 17:24:38 INFO - "project": "", 17:24:38 INFO - "product": "firefox", 17:24:38 INFO - "who": "dmosedale@mozilla.com", 17:24:38 INFO - "script_repo_revision": "default", 17:24:38 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 17:24:38 INFO - "repository": "", 17:24:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 17:24:38 INFO - "stage_platform": "macosx64", 17:24:38 INFO - "basedir": "/builds/slave/test", 17:24:38 INFO - "buildnumber": 0, 17:24:38 INFO - "platform": "yosemite_r7", 17:24:38 INFO - "parent_task_id": "RHDIZci0TiS2HlTXaAyJFA", 17:24:38 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 17:24:38 INFO - "slavebuilddir": "test", 17:24:38 INFO - "taskId": "fNIfRZW7QyCUl4FdSVomBA", 17:24:38 INFO - "branch": "pine", 17:24:38 INFO - "revision": "03b9065133d1bdbf7d5aa534c9345444b1729627", 17:24:38 INFO - "slavename": "t-yosemite-r7-0301", 17:24:38 INFO - "repo_path": "projects/pine" 17:24:38 INFO - } 17:24:38 INFO - Finding installer, test and symbols from parent task. 17:24:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fNIfRZW7QyCUl4FdSVomBA'}, attempt #1 17:24:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fNIfRZW7QyCUl4FdSVomBA'}, attempt #1 17:24:40 INFO - Task dependencies: fNIfRZW7QyCUl4FdSVomBA 17:24:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RHDIZci0TiS2HlTXaAyJFA'}, attempt #1 17:24:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RHDIZci0TiS2HlTXaAyJFA/artifacts/public/build/buildbot_properties.json'}, attempt #1 17:24:41 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/RHDIZci0TiS2HlTXaAyJFA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:24:41 INFO - Set test_url: https://queue.taskcluster.net/v1/task/RHDIZci0TiS2HlTXaAyJFA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 17:24:41 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/RHDIZci0TiS2HlTXaAyJFA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols-full.zip 17:24:41 INFO - Running post-action listener: _resource_record_post_action 17:24:41 INFO - [mozharness: 2017-04-13 00:24:41.242337Z] Finished read-buildbot-config step (success) 17:24:41 INFO - [mozharness: 2017-04-13 00:24:41.242459Z] Running download-and-extract step. 17:24:41 INFO - Running pre-action listener: _resource_record_pre_action 17:24:41 INFO - Running main action method: download_and_extract 17:24:41 INFO - mkdir: /builds/slave/test/build/tests 17:24:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:24:41 INFO - trying https://queue.taskcluster.net/v1/task/RHDIZci0TiS2HlTXaAyJFA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 17:24:41 INFO - Downloading https://queue.taskcluster.net/v1/task/RHDIZci0TiS2HlTXaAyJFA/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 17:24:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RHDIZci0TiS2HlTXaAyJFA/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 17:24:41 INFO - Downloaded 1325 bytes. 17:24:41 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:24:41 INFO - Using the following test package requirements: 17:24:41 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:24:41 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 17:24:41 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:24:41 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:24:41 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 17:24:41 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:24:41 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 17:24:41 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:24:41 INFO - u'jsshell-mac.zip'], 17:24:41 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:24:41 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 17:24:41 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:24:41 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:24:41 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 17:24:41 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:24:41 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 17:24:41 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:24:41 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 17:24:41 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:24:41 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 17:24:41 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:24:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RHDIZci0TiS2HlTXaAyJFA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:24:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RHDIZci0TiS2HlTXaAyJFA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:24:41 INFO - Fetch https://queue.taskcluster.net/v1/task/RHDIZci0TiS2HlTXaAyJFA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:24:43 INFO - Expected file size: 52581308 17:24:43 INFO - Obtained file size: 52581308 17:24:59 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 17:24:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RHDIZci0TiS2HlTXaAyJFA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:24:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RHDIZci0TiS2HlTXaAyJFA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:24:59 INFO - Fetch https://queue.taskcluster.net/v1/task/RHDIZci0TiS2HlTXaAyJFA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:25:01 INFO - Expected file size: 52581308 17:25:01 INFO - Obtained file size: 52581308 17:25:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RHDIZci0TiS2HlTXaAyJFA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 17:25:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RHDIZci0TiS2HlTXaAyJFA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:25:16 INFO - Fetch https://queue.taskcluster.net/v1/task/RHDIZci0TiS2HlTXaAyJFA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 17:25:17 INFO - Expected file size: 14405596 17:25:17 INFO - Obtained file size: 14405596 17:25:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:17 INFO - trying https://queue.taskcluster.net/v1/task/RHDIZci0TiS2HlTXaAyJFA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:25:17 INFO - Downloading https://queue.taskcluster.net/v1/task/RHDIZci0TiS2HlTXaAyJFA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 17:25:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RHDIZci0TiS2HlTXaAyJFA/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 17:25:20 INFO - Downloaded 71001966 bytes. 17:25:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RHDIZci0TiS2HlTXaAyJFA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:25:20 INFO - mkdir: /builds/slave/test/properties 17:25:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:25:20 INFO - Writing to file /builds/slave/test/properties/build_url 17:25:20 INFO - Contents: 17:25:20 INFO - build_url:https://queue.taskcluster.net/v1/task/RHDIZci0TiS2HlTXaAyJFA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:25:20 INFO - Running post-action listener: _resource_record_post_action 17:25:20 INFO - Running post-action listener: set_extra_try_arguments 17:25:20 INFO - [mozharness: 2017-04-13 00:25:20.679423Z] Finished download-and-extract step (success) 17:25:20 INFO - [mozharness: 2017-04-13 00:25:20.679558Z] Running populate-webroot step. 17:25:20 INFO - Running pre-action listener: _resource_record_pre_action 17:25:20 INFO - Running main action method: populate_webroot 17:25:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:25:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:25:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:25:20 INFO - '--tppagecycles', 17:25:20 INFO - '7'], 17:25:20 INFO - 'tests': ['tsvgm']}}, 17:25:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:25:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:25:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:25:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:25:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:25:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:25:20 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:25:20 INFO - 'talos_options': ['--disable-e10s'], 17:25:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:25:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:25:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:25:20 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:25:20 INFO - 'talos_options': ['--disable-e10s'], 17:25:20 INFO - 'tests': ['damp', 'tps']}, 17:25:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:25:20 INFO - 'tests': ['damp', 'tps']}, 17:25:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:25:20 INFO - 'tests': ['dromaeo_dom']}, 17:25:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:25:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:25:20 INFO - 'tests': ['basic_compositor_video']}, 17:25:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:25:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:25:20 INFO - 'tests': ['a11yr', 17:25:20 INFO - 'ts_paint', 17:25:20 INFO - 'tpaint', 17:25:20 INFO - 'sessionrestore', 17:25:20 INFO - 'sessionrestore_no_auto_restore', 17:25:20 INFO - 'tabpaint']}, 17:25:20 INFO - 'other-e10s': {'tests': ['a11yr', 17:25:20 INFO - 'ts_paint', 17:25:20 INFO - 'tpaint', 17:25:20 INFO - 'sessionrestore', 17:25:20 INFO - 'sessionrestore_no_auto_restore', 17:25:20 INFO - 'tabpaint']}, 17:25:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:25:20 INFO - 'ts_paint', 17:25:20 INFO - 'tpaint', 17:25:20 INFO - 'sessionrestore', 17:25:20 INFO - 'sessionrestore_no_auto_restore', 17:25:20 INFO - 'tabpaint']}, 17:25:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:25:20 INFO - 'ts_paint', 17:25:20 INFO - 'tpaint', 17:25:20 INFO - 'sessionrestore', 17:25:20 INFO - 'sessionrestore_no_auto_restore', 17:25:20 INFO - 'tabpaint']}, 17:25:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:25:20 INFO - 'tests': ['a11yr', 17:25:20 INFO - 'ts_paint', 17:25:20 INFO - 'tpaint', 17:25:20 INFO - 'sessionrestore', 17:25:20 INFO - 'sessionrestore_no_auto_restore', 17:25:20 INFO - 'tabpaint']}, 17:25:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:25:20 INFO - 'tests': ['a11yr', 17:25:20 INFO - 'ts_paint', 17:25:20 INFO - 'tpaint', 17:25:20 INFO - 'sessionrestore', 17:25:20 INFO - 'sessionrestore_no_auto_restore', 17:25:20 INFO - 'tabpaint']}, 17:25:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:25:20 INFO - 'tests': ['tsvgx', 17:25:20 INFO - 'tsvgr_opacity', 17:25:20 INFO - 'tart', 17:25:20 INFO - 'tscrollx', 17:25:20 INFO - 'cart', 17:25:20 INFO - 'tsvg_static']}, 17:25:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:25:20 INFO - 'tsvgr_opacity', 17:25:20 INFO - 'tart', 17:25:20 INFO - 'tscrollx', 17:25:20 INFO - 'cart', 17:25:20 INFO - 'tsvg_static']}, 17:25:20 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:25:20 INFO - 'talos_options': ['--disable-e10s'], 17:25:20 INFO - 'tests': ['tp5o']}, 17:25:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:25:20 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:25:20 INFO - 'talos_options': ['--disable-e10s', 17:25:20 INFO - '--xperf_path', 17:25:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:25:20 INFO - 'tests': ['tp5n']}, 17:25:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:25:20 INFO - 'talos_options': ['--xperf_path', 17:25:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:25:20 INFO - 'tests': ['tp5n']}}, 17:25:20 INFO - 'talos.zip': {'path': '', 17:25:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:25:20 INFO - Running post-action listener: _resource_record_post_action 17:25:20 INFO - [mozharness: 2017-04-13 00:25:20.699614Z] Finished populate-webroot step (success) 17:25:20 INFO - [mozharness: 2017-04-13 00:25:20.699751Z] Running create-virtualenv step. 17:25:20 INFO - Running pre-action listener: _resource_record_pre_action 17:25:20 INFO - Running main action method: create_virtualenv 17:25:20 INFO - Creating virtualenv /builds/slave/test/build/venv 17:25:20 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 17:25:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:25:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:25:20 INFO - Using real prefix '/tools/python27' 17:25:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:25:21 INFO - Installing distribute.............................................................................................................................................................................................done. 17:25:24 INFO - Installing pip.................done. 17:25:24 INFO - Return code: 0 17:25:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:25:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:25:24 INFO - Reading from file tmpfile_stdout 17:25:24 INFO - Output received: 17:25:24 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) 17:25:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:25:24 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')]} 17:25:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:24 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')]} 17:25:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:24 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 0x100a71b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a74768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcba4b8d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10084fd30>, '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 0x100776db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100844be8>, '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.JynS31bETa/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.0TtkLHU9qy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 17:25:24 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 17:25:24 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 17:25:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JynS31bETa/Render', 17:25:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:24 INFO - 'HOME': '/Users/cltbld', 17:25:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:24 INFO - 'LOGNAME': 'cltbld', 17:25:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:24 INFO - 'MOZ_NO_REMOTE': '1', 17:25:24 INFO - 'NO_EM_RESTART': '1', 17:25:24 INFO - 'PAGER': '/bin/cat', 17:25:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:24 INFO - 'PWD': '/builds/slave/test', 17:25:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:24 INFO - 'SHELL': '/bin/bash', 17:25:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0TtkLHU9qy/Listeners', 17:25:24 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 17:25:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:24 INFO - 'USER': 'cltbld', 17:25:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:24 INFO - 'XPC_FLAGS': '0x0', 17:25:24 INFO - 'XPC_SERVICE_NAME': '0', 17:25:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:24 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 17:25:24 INFO - Cleaning up... 17:25:24 INFO - Return code: 0 17:25:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:25:24 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')]} 17:25:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:24 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')]} 17:25:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:24 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 0x100a71b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a74768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcba4b8d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10084fd30>, '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 0x100776db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100844be8>, '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.JynS31bETa/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.0TtkLHU9qy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 17:25:24 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 17:25:24 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 17:25:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JynS31bETa/Render', 17:25:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:24 INFO - 'HOME': '/Users/cltbld', 17:25:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:24 INFO - 'LOGNAME': 'cltbld', 17:25:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:24 INFO - 'MOZ_NO_REMOTE': '1', 17:25:24 INFO - 'NO_EM_RESTART': '1', 17:25:24 INFO - 'PAGER': '/bin/cat', 17:25:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:24 INFO - 'PWD': '/builds/slave/test', 17:25:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:24 INFO - 'SHELL': '/bin/bash', 17:25:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0TtkLHU9qy/Listeners', 17:25:24 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 17:25:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:24 INFO - 'USER': 'cltbld', 17:25:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:24 INFO - 'XPC_FLAGS': '0x0', 17:25:24 INFO - 'XPC_SERVICE_NAME': '0', 17:25:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:25 INFO - Downloading/unpacking psutil>=3.1.1 17:25:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:25:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:25:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:25:29 INFO - Installing collected packages: psutil 17:25:29 INFO - Running setup.py install for psutil 17:25:29 INFO - building 'psutil._psutil_osx' extension 17:25:29 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 17:25:29 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 17:25:29 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 17:25:29 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 17:25:29 INFO - building 'psutil._psutil_posix' extension 17:25:29 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 17:25:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:25:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:25:29 INFO - ^ 17:25:29 INFO - 1 warning generated. 17:25:29 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 17:25:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:25:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:25:29 INFO - Successfully installed psutil 17:25:29 INFO - Cleaning up... 17:25:29 INFO - Return code: 0 17:25:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:25:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:29 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 0x100a71b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a74768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcba4b8d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10084fd30>, '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 0x100776db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100844be8>, '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.JynS31bETa/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.0TtkLHU9qy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 17:25:29 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 17:25:29 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 17:25:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JynS31bETa/Render', 17:25:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:29 INFO - 'HOME': '/Users/cltbld', 17:25:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:29 INFO - 'LOGNAME': 'cltbld', 17:25:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:29 INFO - 'MOZ_NO_REMOTE': '1', 17:25:29 INFO - 'NO_EM_RESTART': '1', 17:25:29 INFO - 'PAGER': '/bin/cat', 17:25:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:29 INFO - 'PWD': '/builds/slave/test', 17:25:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:29 INFO - 'SHELL': '/bin/bash', 17:25:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0TtkLHU9qy/Listeners', 17:25:29 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 17:25:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:29 INFO - 'USER': 'cltbld', 17:25:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:29 INFO - 'XPC_FLAGS': '0x0', 17:25:29 INFO - 'XPC_SERVICE_NAME': '0', 17:25:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:25:30 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 17:25:30 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 17:25:30 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 17:25:30 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 17:25:30 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 17:25:30 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 17:25:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:25:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:25:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:25:32 INFO - Installing collected packages: mozsystemmonitor 17:25:32 INFO - Running setup.py install for mozsystemmonitor 17:25:33 INFO - Successfully installed mozsystemmonitor 17:25:33 INFO - Cleaning up... 17:25:33 INFO - Return code: 0 17:25:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:25:33 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')]} 17:25:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:33 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')]} 17:25:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:33 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 0x100a71b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a74768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcba4b8d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10084fd30>, '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 0x100776db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100844be8>, '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.JynS31bETa/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.0TtkLHU9qy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 17:25:33 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 17:25:33 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 17:25:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JynS31bETa/Render', 17:25:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:33 INFO - 'HOME': '/Users/cltbld', 17:25:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:33 INFO - 'LOGNAME': 'cltbld', 17:25:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:33 INFO - 'MOZ_NO_REMOTE': '1', 17:25:33 INFO - 'NO_EM_RESTART': '1', 17:25:33 INFO - 'PAGER': '/bin/cat', 17:25:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:33 INFO - 'PWD': '/builds/slave/test', 17:25:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:33 INFO - 'SHELL': '/bin/bash', 17:25:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0TtkLHU9qy/Listeners', 17:25:33 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 17:25:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:33 INFO - 'USER': 'cltbld', 17:25:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:33 INFO - 'XPC_FLAGS': '0x0', 17:25:33 INFO - 'XPC_SERVICE_NAME': '0', 17:25:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:34 INFO - Downloading/unpacking jsonschema==2.5.1 17:25:34 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 17:25:34 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 17:25:34 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 17:25:34 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 17:25:34 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 17:25:34 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 17:25:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:25:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:25:36 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 17:25:36 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 17:25:36 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 17:25:36 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 17:25:36 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 17:25:36 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 17:25:37 INFO - Downloading functools32-3.2.3-2.tar.gz 17:25:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:25:37 INFO - warning: no files found matching '*.txt' 17:25:37 INFO - no previously-included directories found matching 'build' 17:25:37 INFO - no previously-included directories found matching 'dist' 17:25:37 INFO - no previously-included directories found matching '.git*' 17:25:37 INFO - Installing collected packages: jsonschema, functools32 17:25:37 INFO - Running setup.py install for functools32 17:25:37 INFO - warning: no files found matching '*.txt' 17:25:37 INFO - no previously-included directories found matching 'build' 17:25:37 INFO - no previously-included directories found matching 'dist' 17:25:37 INFO - no previously-included directories found matching '.git*' 17:25:37 INFO - Successfully installed jsonschema functools32 17:25:37 INFO - Cleaning up... 17:25:37 INFO - Return code: 0 17:25:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:25:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100a71b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a74768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcba4b8d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10084fd30>, '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 0x100776db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100844be8>, '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.JynS31bETa/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.0TtkLHU9qy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 17:25:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:25:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:25:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JynS31bETa/Render', 17:25:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:37 INFO - 'HOME': '/Users/cltbld', 17:25:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:37 INFO - 'LOGNAME': 'cltbld', 17:25:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:37 INFO - 'MOZ_NO_REMOTE': '1', 17:25:37 INFO - 'NO_EM_RESTART': '1', 17:25:37 INFO - 'PAGER': '/bin/cat', 17:25:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:37 INFO - 'PWD': '/builds/slave/test', 17:25:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:37 INFO - 'SHELL': '/bin/bash', 17:25:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0TtkLHU9qy/Listeners', 17:25:37 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 17:25:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:37 INFO - 'USER': 'cltbld', 17:25:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:37 INFO - 'XPC_FLAGS': '0x0', 17:25:37 INFO - 'XPC_SERVICE_NAME': '0', 17:25:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:25:37 INFO - Cleaning up... 17:25:37 INFO - Return code: 0 17:25:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:25:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100a71b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a74768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcba4b8d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10084fd30>, '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 0x100776db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100844be8>, '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.JynS31bETa/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.0TtkLHU9qy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 17:25:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:25:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:25:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JynS31bETa/Render', 17:25:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:37 INFO - 'HOME': '/Users/cltbld', 17:25:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:37 INFO - 'LOGNAME': 'cltbld', 17:25:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:37 INFO - 'MOZ_NO_REMOTE': '1', 17:25:37 INFO - 'NO_EM_RESTART': '1', 17:25:37 INFO - 'PAGER': '/bin/cat', 17:25:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:37 INFO - 'PWD': '/builds/slave/test', 17:25:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:37 INFO - 'SHELL': '/bin/bash', 17:25:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0TtkLHU9qy/Listeners', 17:25:37 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 17:25:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:37 INFO - 'USER': 'cltbld', 17:25:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:37 INFO - 'XPC_FLAGS': '0x0', 17:25:37 INFO - 'XPC_SERVICE_NAME': '0', 17:25:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:38 INFO - Downloading/unpacking blobuploader==1.2.4 17:25:38 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 17:25:38 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 17:25:38 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 17:25:38 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 17:25:38 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 17:25:38 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 17:25:40 INFO - Downloading blobuploader-1.2.4.tar.gz 17:25:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:25:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:25:40 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 17:25:40 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 17:25:40 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 17:25:40 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 17:25:40 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 17:25:40 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 17:25:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:25:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:25:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:42 INFO - Downloading docopt-0.6.1.tar.gz 17:25:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:25:42 INFO - Installing collected packages: blobuploader, requests, docopt 17:25:42 INFO - Running setup.py install for blobuploader 17:25:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:25:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:25:42 INFO - Running setup.py install for requests 17:25:42 INFO - Running setup.py install for docopt 17:25:42 INFO - Successfully installed blobuploader requests docopt 17:25:42 INFO - Cleaning up... 17:25:43 INFO - Return code: 0 17:25:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:25:43 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')]} 17:25:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:43 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')]} 17:25:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:43 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 0x100a71b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a74768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcba4b8d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10084fd30>, '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 0x100776db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100844be8>, '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.JynS31bETa/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.0TtkLHU9qy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 17:25:43 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 17:25:43 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 17:25:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JynS31bETa/Render', 17:25:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:43 INFO - 'HOME': '/Users/cltbld', 17:25:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:43 INFO - 'LOGNAME': 'cltbld', 17:25:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:43 INFO - 'MOZ_NO_REMOTE': '1', 17:25:43 INFO - 'NO_EM_RESTART': '1', 17:25:43 INFO - 'PAGER': '/bin/cat', 17:25:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:43 INFO - 'PWD': '/builds/slave/test', 17:25:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:43 INFO - 'SHELL': '/bin/bash', 17:25:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0TtkLHU9qy/Listeners', 17:25:43 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 17:25:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:43 INFO - 'USER': 'cltbld', 17:25:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:43 INFO - 'XPC_FLAGS': '0x0', 17:25:43 INFO - 'XPC_SERVICE_NAME': '0', 17:25:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:25:43 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-phZfP1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:25:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:25:43 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-dIAGJK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:25:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:25:43 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-28FBHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:25:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:25:43 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-FrhLyf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:25:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:25:43 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-oDWV8S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:25:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:25:43 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-oQ4vnr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:25:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:25:43 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-BpcR4V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:25:44 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-irp1gX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:25:44 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-vHjHkf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:25:44 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-ri85OT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:25:44 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-lbkZJa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:25:44 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-HKyoaw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:25:44 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-mki3V6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:25:44 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-7rD8T5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:25:44 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-Kv7deY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:25:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:25:44 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-B8Fwyv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:25:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:25:45 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-4rzlJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:25:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:25:45 INFO - Running setup.py install for manifestparser 17:25:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:25:45 INFO - Running setup.py install for mozcrash 17:25:45 INFO - Running setup.py install for mozdebug 17:25:45 INFO - Running setup.py install for mozdevice 17:25:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:25:45 INFO - Running setup.py install for mozfile 17:25:45 INFO - Running setup.py install for mozhttpd 17:25:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:25:45 INFO - Running setup.py install for mozinfo 17:25:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:25:46 INFO - Running setup.py install for mozInstall 17:25:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:25:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:25:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:25:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:25:46 INFO - Running setup.py install for mozleak 17:25:46 INFO - Running setup.py install for mozlog 17:25:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:25:46 INFO - Running setup.py install for moznetwork 17:25:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:25:46 INFO - Running setup.py install for mozprocess 17:25:46 INFO - Running setup.py install for mozprofile 17:25:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:25:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:25:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:25:47 INFO - Running setup.py install for mozrunner 17:25:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:25:47 INFO - Running setup.py install for mozscreenshot 17:25:47 INFO - Running setup.py install for moztest 17:25:47 INFO - Running setup.py install for mozversion 17:25:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:25:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:25:47 INFO - Cleaning up... 17:25:47 INFO - Return code: 0 17:25:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:25: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')]} 17:25:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25: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')]} 17:25:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:47 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 0x100a71b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a74768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcba4b8d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10084fd30>, '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 0x100776db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100844be8>, '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.JynS31bETa/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.0TtkLHU9qy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 17:25:47 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 17:25:47 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 17:25:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JynS31bETa/Render', 17:25:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:47 INFO - 'HOME': '/Users/cltbld', 17:25:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:47 INFO - 'LOGNAME': 'cltbld', 17:25:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:47 INFO - 'MOZ_NO_REMOTE': '1', 17:25:47 INFO - 'NO_EM_RESTART': '1', 17:25:47 INFO - 'PAGER': '/bin/cat', 17:25:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:47 INFO - 'PWD': '/builds/slave/test', 17:25:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:47 INFO - 'SHELL': '/bin/bash', 17:25:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0TtkLHU9qy/Listeners', 17:25:47 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 17:25:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:47 INFO - 'USER': 'cltbld', 17:25:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:47 INFO - 'XPC_FLAGS': '0x0', 17:25:47 INFO - 'XPC_SERVICE_NAME': '0', 17:25:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:25:47 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-fDC9xC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:25:48 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)) 17:25:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:25:48 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-YDfdVt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:25:48 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)) 17:25:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:25:48 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-wzK6Ca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:25:48 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)) 17:25:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:25:48 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-NWsXBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:25:48 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)) 17:25:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:25:48 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-f2Ed2R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:25:48 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)) 17:25:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:25:48 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-gNCdRr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:25:48 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)) 17:25:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:25:48 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-G3W6cY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:25:48 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)) 17:25:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:25:48 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-eouBGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:25:48 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)) 17:25:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:25:48 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-mtHfrq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:25:48 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)) 17:25:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:25:48 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-Dumpii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:25:49 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)) 17:25:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:25:49 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-OGSHU0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:25:49 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)) 17:25:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:25:49 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-yIsgRN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:25:49 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)) 17:25:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:25:49 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-fiVeNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:25:49 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)) 17:25:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:25:49 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-w0MQFy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:25:49 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)) 17:25:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:25:49 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-m6kaZk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:25:49 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)) 17:25:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:25:49 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-khoMhw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:25:49 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)) 17:25:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:25:49 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-wMnrd4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:25:49 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)) 17:25:49 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)) 17:25:49 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)) 17:25:49 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)) 17:25:49 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)) 17:25:49 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)) 17:25:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:25:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:52 INFO - Downloading blessings-1.6.tar.gz 17:25:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:25:52 INFO - Installing collected packages: blessings 17:25:52 INFO - Running setup.py install for blessings 17:25:52 INFO - Successfully installed blessings 17:25:52 INFO - Cleaning up... 17:25:52 INFO - Return code: 0 17:25:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:25:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:25:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:25:53 INFO - Reading from file tmpfile_stdout 17:25:53 INFO - Current package versions: 17:25:53 INFO - blessings == 1.6 17:25:53 INFO - blobuploader == 1.2.4 17:25:53 INFO - docopt == 0.6.1 17:25:53 INFO - functools32 == 3.2.3-2 17:25:53 INFO - jsonschema == 2.5.1 17:25:53 INFO - manifestparser == 1.1 17:25:53 INFO - mozInstall == 1.12 17:25:53 INFO - mozcrash == 1.0 17:25:53 INFO - mozdebug == 0.1 17:25:53 INFO - mozdevice == 0.50 17:25:53 INFO - mozfile == 1.2 17:25:53 INFO - mozhttpd == 0.7 17:25:53 INFO - mozinfo == 0.9 17:25:53 INFO - mozleak == 0.1 17:25:53 INFO - mozlog == 3.4 17:25:53 INFO - moznetwork == 0.27 17:25:53 INFO - mozprocess == 0.25 17:25:53 INFO - mozprofile == 0.28 17:25:53 INFO - mozrunner == 6.13 17:25:53 INFO - mozscreenshot == 0.1 17:25:53 INFO - mozsystemmonitor == 0.3 17:25:53 INFO - moztest == 0.8 17:25:53 INFO - mozversion == 1.4 17:25:53 INFO - psutil == 3.1.1 17:25:53 INFO - requests == 1.2.3 17:25:53 INFO - wsgiref == 0.1.2 17:25:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:25:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:53 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 0x100a71b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a74768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcba4b8d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10084fd30>, '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 0x100776db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100844be8>, '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.JynS31bETa/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.0TtkLHU9qy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 17:25:53 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 17:25:53 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 17:25:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JynS31bETa/Render', 17:25:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:53 INFO - 'HOME': '/Users/cltbld', 17:25:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:53 INFO - 'LOGNAME': 'cltbld', 17:25:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:53 INFO - 'MOZ_NO_REMOTE': '1', 17:25:53 INFO - 'NO_EM_RESTART': '1', 17:25:53 INFO - 'PAGER': '/bin/cat', 17:25:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:53 INFO - 'PWD': '/builds/slave/test', 17:25:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:53 INFO - 'SHELL': '/bin/bash', 17:25:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0TtkLHU9qy/Listeners', 17:25:53 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 17:25:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:53 INFO - 'USER': 'cltbld', 17:25:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:53 INFO - 'XPC_FLAGS': '0x0', 17:25:53 INFO - 'XPC_SERVICE_NAME': '0', 17:25:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:53 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)) 17:25:53 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)) 17:25:53 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)) 17:25:53 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)) 17:25:53 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)) 17:25:53 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)) 17:25:53 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)) 17:25:53 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)) 17:25:53 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)) 17:25:53 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)) 17:25:53 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)) 17:25:53 INFO - Cleaning up... 17:25:53 INFO - Return code: 0 17:25:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:25:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:53 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 0x100a71b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100a74768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbcba4b8d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10084fd30>, '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 0x100776db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100844be8>, '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.JynS31bETa/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.0TtkLHU9qy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 17:25:53 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 17:25:53 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 17:25:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JynS31bETa/Render', 17:25:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:53 INFO - 'HOME': '/Users/cltbld', 17:25:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:53 INFO - 'LOGNAME': 'cltbld', 17:25:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:53 INFO - 'MOZ_NO_REMOTE': '1', 17:25:53 INFO - 'NO_EM_RESTART': '1', 17:25:53 INFO - 'PAGER': '/bin/cat', 17:25:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:53 INFO - 'PWD': '/builds/slave/test', 17:25:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:53 INFO - 'SHELL': '/bin/bash', 17:25:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0TtkLHU9qy/Listeners', 17:25:53 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 17:25:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:53 INFO - 'USER': 'cltbld', 17:25:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:53 INFO - 'XPC_FLAGS': '0x0', 17:25:53 INFO - 'XPC_SERVICE_NAME': '0', 17:25:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:25:53 INFO - Cleaning up... 17:25:53 INFO - Return code: 0 17:25:53 INFO - Running post-action listener: _resource_record_post_action 17:25:53 INFO - Running post-action listener: _start_resource_monitoring 17:25:53 INFO - Starting resource monitoring. 17:25:53 INFO - [mozharness: 2017-04-13 00:25:53.644324Z] Finished create-virtualenv step (success) 17:25:53 INFO - [mozharness: 2017-04-13 00:25:53.645406Z] Running install step. 17:25:53 INFO - Running pre-action listener: _resource_record_pre_action 17:25:53 INFO - Running main action method: install 17:25:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:25:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:25:53 INFO - Reading from file tmpfile_stdout 17:25:53 INFO - Detecting whether we're running mozinstall >=1.0... 17:25:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:25:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:25:54 INFO - Reading from file tmpfile_stdout 17:25:54 INFO - Output received: 17:25:54 INFO - Usage: mozinstall [options] installer 17:25:54 INFO - Options: 17:25:54 INFO - -h, --help show this help message and exit 17:25:54 INFO - -d DEST, --destination=DEST 17:25:54 INFO - Directory to install application into. [default: 17:25:54 INFO - "/builds/slave/test"] 17:25:54 INFO - --app=APP Application being installed. [default: firefox] 17:25:54 INFO - mkdir: /builds/slave/test/build/application 17:25:54 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'] 17:25:54 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 17:26:13 INFO - Reading from file tmpfile_stdout 17:26:13 INFO - Output received: 17:26:13 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:26:13 INFO - Running post-action listener: _resource_record_post_action 17:26:13 INFO - [mozharness: 2017-04-13 00:26:13.549321Z] Finished install step (success) 17:26:13 INFO - [mozharness: 2017-04-13 00:26:13.549471Z] Running run-tests step. 17:26:13 INFO - Running pre-action listener: _resource_record_pre_action 17:26:13 INFO - Running main action method: run_tests 17:26:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:26:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:26:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:26:13 INFO - 2017-04-12 17:26:13.564 screenresolution[30538:272047] starting screenresolution argv=screenresolution get 17:26:13 INFO - 2017-04-12 17:26:13.567 screenresolution[30538:272047] Display 0: 1600x1200x32@60 17:26:13 INFO - 2017-04-12 17:26:13.573 screenresolution[30539:272051] starting screenresolution argv=screenresolution list 17:26:13 INFO - Available Modes on Display 0 17:26:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:26:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:26:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:26:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:26:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:26:13 INFO - Intel Iris: 17:26:13 INFO - Chipset Model: Intel Iris 17:26:13 INFO - Type: GPU 17:26:13 INFO - Bus: Built-In 17:26:13 INFO - VRAM (Dynamic, Max): 1536 MB 17:26:13 INFO - Vendor: Intel (0x8086) 17:26:13 INFO - Device ID: 0x0a2e 17:26:13 INFO - Revision ID: 0x0009 17:26:13 INFO - Displays: 17:26:13 INFO - Display: 17:26:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:26:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:26:13 INFO - Display Serial Number: 1600x1200 60 17:26:13 INFO - Main Display: Yes 17:26:13 INFO - Mirror: Off 17:26:13 INFO - Online: Yes 17:26:13 INFO - Rotation: Supported 17:26:13 INFO - Return code: 0 17:26:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:26:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:26:13 INFO - Python 2.7.3 17:26:13 INFO - Return code: 0 17:26:13 INFO - grabbing minidump binary from tooltool 17:26:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:26:13 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 0x10084fd30>, '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 0x100776db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100844be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:26:13 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 17:26:13 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 17:26:13 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 17:26:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:26:13 INFO - Return code: 0 17:26:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:26:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:26:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:26:13 INFO - ENV: RUST_BACKTRACE is now 1 17:26:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:26:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:26:13 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/RHDIZci0TiS2HlTXaAyJFA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols-full.zip', '--title', 't-yosemite-r7-0301', '--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 17:26:13 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/RHDIZci0TiS2HlTXaAyJFA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols-full.zip --title t-yosemite-r7-0301 --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 17:26:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JynS31bETa/Render', 17:26:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:26:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:26:13 INFO - 'HOME': '/Users/cltbld', 17:26:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:26:13 INFO - 'LOGNAME': 'cltbld', 17:26:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:26:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:26:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:26:13 INFO - 'MOZ_NO_REMOTE': '1', 17:26:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:26:13 INFO - 'NO_EM_RESTART': '1', 17:26:13 INFO - 'PAGER': '/bin/cat', 17:26:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:26:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:26:13 INFO - 'PWD': '/builds/slave/test', 17:26:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:26:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:26:13 INFO - 'RUST_BACKTRACE': '1', 17:26:13 INFO - 'SHELL': '/bin/bash', 17:26:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0TtkLHU9qy/Listeners', 17:26:13 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 17:26:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:26:13 INFO - 'USER': 'cltbld', 17:26:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:26:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:26:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:26:13 INFO - 'XPC_FLAGS': '0x0', 17:26:13 INFO - 'XPC_SERVICE_NAME': '0', 17:26:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:26:13 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/RHDIZci0TiS2HlTXaAyJFA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols-full.zip', '--title', 't-yosemite-r7-0301', '--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 17:26:14 INFO - mozversion application_buildid: 20170412155307 17:26:14 INFO - mozversion application_changeset: 03b9065133d1bdbf7d5aa534c9345444b1729627 17:26:14 INFO - mozversion application_display_name: Nightly 17:26:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:26:14 INFO - mozversion application_name: Firefox 17:26:14 INFO - mozversion application_remotingname: firefox 17:26:14 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 17:26:14 INFO - mozversion application_vendor: Mozilla 17:26:14 INFO - mozversion application_version: 55.0a1 17:26:14 INFO - mozversion platform_buildid: 20170412155307 17:26:14 INFO - mozversion platform_changeset: 03b9065133d1bdbf7d5aa534c9345444b1729627 17:26:14 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 17:26:14 INFO - mozversion platform_version: 55.0a1 17:26:14 INFO - using testdate: 1492043174 17:26:14 INFO - actual date: 1492043174 17:26:14 INFO - starting webserver on 'localhost:60465' 17:26:14 INFO - SUITE-START | Running 2 tests 17:26:14 INFO - TEST-START | dromaeo_css 17:26:14 INFO - operating with platform_type : mac_ 17:26:14 INFO - Initialising browser for dromaeo_css test... 17:26:14 INFO - TEST-INFO | started process 30549 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmpF5gt9O/profile http://localhost:60465/getInfo.html) 17:26:17 INFO - PID 30549 | 2017-04-12 17:26:17.056 plugin-container[30551:272219] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b47, name = 'com.apple.tsm.portname' 17:26:17 INFO - PID 30549 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:26:17 INFO - PID 30549 | 2017-04-12 17:26:17.059 plugin-container[30551:272219] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4917, name = 'com.apple.CFPasteboardClient' 17:26:17 INFO - PID 30549 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:26:17 INFO - PID 30549 | 2017-04-12 17:26:17.059 plugin-container[30551:272219] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:26:17 INFO - PID 30549 | __metrics Screen width/height:1600/1200 17:26:17 INFO - PID 30549 | colorDepth:24 17:26:17 INFO - PID 30549 | Browser inner width/height: 1024/689 17:26:17 INFO - PID 30549 | __metrics 17:26:17 ERROR - PID 30549 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 17:26:18 INFO - TEST-INFO | 30549: exit 0 17:26:18 INFO - Browser initialized. 17:26:18 INFO - Running cycle 1/1 for dromaeo_css test... 17:26:18 INFO - TEST-INFO | started process 30552 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmpF5gt9O/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:26:21 INFO - PID 30552 | 2017-04-12 17:26:21.003 plugin-container[30553:272350] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 17:26:21 INFO - PID 30552 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:26:21 INFO - PID 30552 | 2017-04-12 17:26:21.004 plugin-container[30553:272350] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 17:26:21 INFO - PID 30552 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:26:21 INFO - PID 30552 | 2017-04-12 17:26:21.004 plugin-container[30553:272350] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:26:30 INFO - PID 30552 | RSS: Main: 236625920 17:26:30 INFO - PID 30552 | 17:29:34 INFO - PID 30552 | Cycle 1(1): loaded http://localhost:60465/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60465/tests/dromaeo/cssquery-ext.html) 17:29:35 INFO - PID 30552 | RSS: Main: 255246336 17:29:35 INFO - PID 30552 | 17:31:18 INFO - PID 30552 | ************************* 17:31:18 INFO - PID 30552 | A coding exception was thrown and uncaught in a Task. 17:31:18 INFO - PID 30552 | 17:31:18 ERROR - PID 30552 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:31:18 INFO - PID 30552 | Full stack: 17:31:18 INFO - PID 30552 | ************************* 17:31:18 INFO - PID 30552 | ************************* 17:31:18 INFO - PID 30552 | A coding exception was thrown and uncaught in a Task. 17:31:18 INFO - PID 30552 | 17:31:18 ERROR - PID 30552 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:31:18 INFO - PID 30552 | Full stack: 17:31:18 INFO - PID 30552 | ************************* 17:31:18 INFO - PID 30552 | ************************* 17:31:18 INFO - PID 30552 | A coding exception was thrown and uncaught in a Task. 17:31:18 INFO - PID 30552 | 17:31:18 ERROR - PID 30552 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:31:18 INFO - PID 30552 | Full stack: 17:31:18 INFO - PID 30552 | ************************* 17:31:18 INFO - PID 30552 | ************************* 17:31:18 INFO - PID 30552 | A coding exception was thrown and uncaught in a Task. 17:31:18 INFO - PID 30552 | 17:31:18 ERROR - PID 30552 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:31:18 INFO - PID 30552 | Full stack: 17:31:18 INFO - PID 30552 | ************************* 17:32:37 INFO - PID 30552 | Cycle 1(1): loaded http://localhost:60465/tests/dromaeo/cssquery-ext.html (next: http://localhost:60465/tests/dromaeo/cssquery-jquery.html) 17:32:38 INFO - PID 30552 | RSS: Main: 264507392 17:32:38 INFO - PID 30552 | 17:35:39 INFO - PID 30552 | Cycle 1(1): loaded http://localhost:60465/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60465/tests/dromaeo/cssquery-mootools.html) 17:35:40 INFO - PID 30552 | RSS: Main: 276074496 17:35:40 INFO - PID 30552 | 17:38:42 INFO - PID 30552 | Cycle 1(1): loaded http://localhost:60465/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60465/tests/dromaeo/cssquery-prototype.html) 17:38:43 INFO - PID 30552 | RSS: Main: 268025856 17:38:43 INFO - PID 30552 | 17:41:46 INFO - PID 30552 | Cycle 1(1): loaded http://localhost:60465/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60465/tests/dromaeo/cssquery-yui.html) 17:41:46 INFO - PID 30552 | RSS: Main: 279179264 17:41:46 INFO - PID 30552 | 17:44:48 INFO - PID 30552 | Cycle 1(1): loaded http://localhost:60465/tests/dromaeo/cssquery-yui.html (next: http://localhost:60465/tests/dromaeo/cssquery-dojo.html) 17:44:48 INFO - PID 30552 | RSS: Main: 272826368 17:44:48 INFO - PID 30552 | 17:44:48 INFO - PID 30552 | __start_tp_report 17:44:48 INFO - PID 30552 | _x_x_mozilla_page_load 17:44:48 INFO - PID 30552 | _x_x_mozilla_page_load_details 17:44:48 INFO - PID 30552 | |i|pagename|runs| 17:44:48 INFO - PID 30552 | |0;dojo.html;2959.9408011839764;3040.148758347662;3410.164509695125;3518.700910422614;3521.366154092262;6112.959454150314;6156.045812899002;6178.443940045395;6201.317855035943;6213.409726076021;5833.329167145779;6132.722655450954;6152.876942461148;6205.131281620576;6253.468455181303;4872.220444728844;5426.240326354314;5471.480209380103;5536.086545719952;5583.5254003409555;5404.945950540484;5511.118221084627;5557.3331200256025;5584.916226256589;5624.184493248476;5376.327959005124;5443.727813609304;5486.725663716838;5499.367572729107;5501.559875209994;5481.698506582176;5488.204210389511;5493.23094766733;5493.423190564973;5527;5407.918881216785;5433.157860531624;5443.755031023614;5443.755031023614;5502.944970550283;4879.268109783526;5823.82528524145;6117.035835319757;6131.89663773324;6178.41305076017;6660.067590537328;6684.131062961833;6690.732370705215;6698.732050717966;6700.865982680319;6782.694778734969;6787.626680532569;6806.727730890759;6810.693518791617;6829.351211634887;6152.477039451696;6205.875882482325;6269.811905642838;6293.685315734195;6325.715342809584;6020.705548307135;6862.382385585322;6864.073350097773;6901;6955.269696681877;6369.267534233622;6420.678966051679;6548.443382312556;6616.139901812733;6630.30381809903;6450.322716114834;6576.375244351779;6642.302558231413;6646.83382915431;6654.86690266192;6381.744730210832;6664.566803157876;6667.133272674521;6684.197896252481;6700.430463410665;6509.1863517060365;6524.902126468011;6553.770618028346;6563.934360656428;6574.474042076622;5353.86615334612;5358.624896257303;5360.597955153379;5361.249425080492;5417.783288668488;4032.5765794591734;4731.810727570859;4742.5731684148595;4748.715077095385;4762.071396077731;5219.217117432416;6199.938000620026;6210.534209934273;6284.402981716729;6603.70283337251;6681.866362672817;6791.558548694319;6795.762148324785;6808.8297792554595;6842.726290948306;6225.937740622536;6273.749050038039;6316.778912738032;6319.778807741707;6337.683115844189;5754.4533269339345;5920.052791553417;5935.258092738408;5961.344252132262;5973.581849270509;5673.971630141823;6713.160854893138;6761.729530818712;6812.93187068123;6872.896906546506;6762.459003279628;6772.085768421397;6783.559068660521;6796.694148763417;6816.0457535944015;6816.625085620339;6818.28408017151;6821.420179284618;6857.5199736018;6873.862522749417;6440.6135631862235;6503;6582.605043697394;6627.635480048643;6669.4664426844765;6645.269020407848;6749.93250067493;6754.898676519758;6787.083743694538;6803.557768745016;6290.685465726787;6332.11350410952;6487.416132548094;6554.901676474952;6564.113844631104;6561.114249576246;6573.737050517926;6595.604263744191;6599.868002639824;6615.702293396905;5607.859803504782;6248.843778905381;6299.08663243818;6330.39861213184;6339.619622822645;6442.484601231797;6500.219973603009;6531.967340163269;6556.803295901131;6559.114519539801;5206.2450944612065;6382.297947225708;6384.361563843764;6386.584871983307;6387.712552935222 17:44:48 INFO - PID 30552 | |1;ext.html;800.3357213512783;837.2004735477617;1241.4723742409474;1259.4017841525276;1293.9805902911457;31154.532682009758;31198.844005779938;38419.61580384195;38558.80720596393;38638.613613863854;38688.80655596718;38735.61264387355;38882.8055859721;39061.82814515562;39113.60886391135;6997.930020699804;8069.919300806991;8208.220219079178;8827.293816494697;8849.60176792046;8716.477011379338;8791.252743516809;8812.295016398704;8826.24976876963;8856.822863542726;8799.824003519925;8840.027596964328;8848.690295839648;8869.733907982738;8869.979952305503;7472.551646901176;8767.298616110727;8810.647574097029;8822.250108740765;8830.823383532294;8268.297194738454;8324.62539185738;8332.875006874901;8336.541490218036;8343.16568343167;37979.24041519154;38201.235975280615;38251.808740956396;38508.229835403414;38521.80739096315;3587.461880717913;3600.7299452541165;3632.600413954463;3637.272545490886;3639.47227651989;30959.845200774078;31167.22081947947;31187.752489900377;31227.531587026213;31232.9068482602;2713.6879258885283;2733.521633714112;2948.5994152777475;3139.8116113033293;3140.994881637877;260318.3968160313;298198;299605;302203.48898255586;302305;40022.199556008716;40631.98420039523;48948.75525622349;49323.01353972972;49674.254886176735;42724.44582204294;45605.08789824185;50917;51148.74425627848;51168.23247651288;12917.935410322889;14802.555923322318;14814.814814815005;14816.25918704082;14831.70336593262;11335.943320283512;11783.705407364712;11932.88067119335;11944.820827687592;12143.514259429701;12732.618021459371;12908.35458227085;13526.985476089332;13685.521006764715;13793.931030344784;5065.3161823153405;5994.880102397928;6039.8792024159275;6077.301110372276;6077.4226448487325;1013.5439052426437;1024.5543188712934;1025.1696126137851;1056.6949816976028;1061.5010944855906;23127;23961.16135935234;24124.87937560301;24156.516869662508;24282.392940176636;1121.2151493954264;1122.1247427006947;1127.672974837288;1157.265136638224;1167.0838391862346;9153.725388238363;12243.387830608433;12284.570040048555;12287.201331913397;12386.62840114798;362976;363190;363246;365192.1740391334;366022;53304.733476333146;53630.46369536333;53678.46321536813;53696.46303536915;54132.729336353856;1466.3254902744643;1484.7847062175924;1524.3673875341608;1530.1048886401356;1542.96142596436;23808.16671416492;23895.641565376187;23897.76102239025;23921.76078239265;24041.51916961686;38315.6168438312;38332;38574;38579;38663.42004869873;320377;322217.38891305396;322553;322604;322838.38580806943;33792.83103584466;34395.656043439245;34725.47911781376;34880.47679284762;35175.64824351724;44358.11283774371;45146.097078057595;45478.18087276604;45504.317435237834;45692.77153614211;24459.75540244575;29556.55665164981;30798;31630.36739265248;31916.68083319137;26183.869080654476;30466.324352160846;31433.899813506418;31445;31744.841275793475;32398.028059158263;36264.81867590645;36486.63513364938;37130.628693712715;37615.43576846295;22823;31361.372772543964;32042.35915281728;32176;32543.02370928876;20180.798192017894;24308.756912431356;24323.63514547248;24387.878060609582;24734 17:44:48 INFO - PID 30552 | |2;jquery.html;434.85215026890853;436.692132046907;436.9781510924455;440.58144762475644;465.71125901940803;18737;20197.293094741686;20755.962201889903;20863.895680521575;21101.26145584905;21465.356039318845;21662.458438539004;21687.915604219765;21839.78160218397;22167.889160554216;5986.371430999737;6228.128062071313;6268.404501572343;6272.7490900364155;6279.654618995954;5920.934231838268;6055.697215139226;6195.969020154893;6205.844853878644;6229.190205273331;5950.434708702688;6114.663693496856;6144.815655530341;6170.136180934673;6189.412005859436;5992.880142397149;6135.987562052278;6147.661878596676;6164.013757798775;6174.413430724074;5390.6226564140525;5443.101888188444;5450.945490545123;5452.318460192476;5591.30108736408;26672;27021.054263100697;27022.5946610801;27189.048383306686;27829.582556261674;2210.8452408331423;2267.353804165805;2292.106078629336;2297.0122847175708;2297.253392647396;14723.263836808117;17616.766826322164;17866.374676886375;18096.547586310317;18566.071696415125;2140.1332460353583;2141.95716085679;2194.5391467791783;2203.2068455356057;2205.911763529473;158155;168134;169553;170259;170916;10306.896931030744;12335.629931102081;12594.37028148589;12690.238585684889;12756.55352062692;16164.838351616569;16168.353265869233;16177.514674559781;16194.352225911067;16230.675386492205;8783.297336213087;8971.327150463741;9099.863502047474;9223.631054757734;9242.676506322246;8738.257248133852;8775.561221938877;8836.734897953073;8983.955080224558;8999.64001439935;6288.402601752826;7779.533228006338;7789.337906277917;7796.193370386656;7818.022747156606;2124.8193903518063;2129.414411036946;2132.9893350533457;2137.4549489879983;2304.8501847379866;831;965.6330594374093;983.5967253426061;990.7188425383106;1010.4291075542295;19087.2365105397;20390.59218815645;20717;20809.1676332945;21092.367228983156;828.825946551231;919.9310051746146;946.7030168668967;1006.5872992073215;1028.2493779540978;10511.583735285909;10847.186461015455;13216.008799340088;13947.58157255284;14190.361433735507;605217;733580;734427;746777;751903;156088;156786;161268.19365903095;163876;164256.11054728858;1197.1141355396944;1220.8473940757406;1240.7580521798343;1243.9875601244053;1257.3084803358117;20828;21696.349109526738;21798.673019904396;21947;21988;53444;64714;65726.67136664285;66158;66902.66548667225;89318.55340723255;93599.53200233955;94467;94811;95169;7955.2840244378285;8389;8574.44266122725;8586.527740974307;8753.91246087531;10958.575385199872;13222.603321900358;13280.402381892645;13454.730905382034;13636.590902272948;9227.261819054595;9245.104753525646;9422.481763503074;11336.716582085184;11613.593524226611;8766.736997890073;9053.230475409664;10555.208359373231;10919.290246133976;11068.391238481961;11451.770964580828;11507.539698411969;11567.305961642329;11954;11998.16012879125;8461.78845528842;8479.660813567389;8485.745427637181;8574.22831945128;8723.825523489368;11321.264117832314;11327.773444531229;11501.712457188303;11685.941570292094;11696.941515292368 17:44:48 INFO - PID 30552 | |3;mootools.html;1194.743130227001;1286.9806952895708;1410.7037522120347;1415.037774313467;1439.1580925158778;9471.526423678806;10059.396436213814;11487.770244595104;11999;12190.329531875743;11667.24162929411;11982.94008529956;11992.700182495442;12000.699982500442;12006.819897701542;2497.4255921138106;2532.126416386344;2543.783778378831;2544.185860524633;2554.7317531659187;2467.481828815951;2479.2066538707622;2515.434027343851;2517.899284028646;2523.50791595638;2391.091385273602;2447.840890342131;2472.987635061822;2519.219042096947;2522.3946252899354;2423.260905423845;2470.0243403855466;2476.8513889166707;2508.0845491395694;2528.8229823912334;2153.536989547255;2330.4290448839974;2475.8143139264625;2485.0184177249885;2491.1156539428603;11662.650120496313;11755;11806.822897656542;11809.763804723858;11838.348890811001;6514.804555863332;6767;6770.221424536143;6780.321967803229;6802.4217941475035;3256.6417694053634;3580.3018411409785;4049.5950404959267;4129.421880936671;4286.57134286572;1031.5564307347913;1217;1226.7423840993313;1266.5756971414587;1289.7227096174367;14956.775648365283;15534.766978495332;16351.509454716377;16517.421890233905;16590.83409165905;5531.640443371168;5651.802186923438;5870.765169393262;5880.764769409176;5892.941070589325;12058.939705301418;12203.328816915155;12309.815352769536;12314.81527777084;12330.81503777444;6554.7378104876225;6607.075009498674;6608.140941677551;6619.172603424572;6621.337866213436;5993.910091348634;6178.104174894615;6188.597741146811;6224.813255602339;6277.905831412532;4390.341448782644;4470.955290447054;4516.412866327357;4553.2714765638;4553.817847286137;1290.9225446473242;1309.4744622514854;1314.5793346129146;1323.9602811915659;1342.2751714074486;780.2431641307923;783.2832957843509;796.7092011415907;803.2609998801117;803.3974519110667;6534.640594767334;6604.1084453599115;6649.667516624246;6878.3465570770695;6902.171739391305;775.6199462263451;936.8875734911854;941.1670671455811;941.9529023548932;943.8112377524523;3896.473976013259;4055.6958228132416;4150.501939767248;4240;4324.372965919987;12175.391230438443;14754.336054877556;15386;15420.691586168214;15478.613034673997;14744.483943061941;14997.100173989596;15300.311485983157;15372.923135384475;15554.611134721496;10926.562937482571;10943.124550035978;10999.780004400027;11084.168687348481;11085.667429977275;6037.758489660365;6900.6204658742245;7043.964780176067;7124.85750284981;7140;12816.487340506275;12846.743065138831;13106.868931310564;13144;13163.736725265633;13144.60566183016;13159.868401315864;13255.072144949761;13257.801132983206;13356.599302020955;8769.956150219208;8976.192142706926;9021.368504204642;9485.193758530326;9877.851832222666;8317.459365141247;10189.796204076025;11015.063719583924;11157.051650609788;11175.329480231212;9194.908050919405;9276.443413395218;9318.62725491;9355.251579873458;9450.385724927857;9006.684766033157;9053.366264361695;9126.954365228132;9137.451752894847;9169.770755730893;7997.1203167652675;8091.109977902308;8188.713395031145;8198.262156405952;8228.588570571328;5559.749056462425;5661.405552416937;6031.728572214173;6044;6241.563090583615;4960.50394960501;5907.261592300963;6159.414855588712;6182.969085154546;6284.4972402206795 17:44:48 INFO - PID 30552 | |4;prototype.html;408.2467846822612;409.42271397329773;410.97739624320667;413.7414116177389;420.2708301097595;6171.691415429233;6262.373762623741;6367.395097465739;6374.872502549946;6415.871682566346;5093.046033300686;5720.6567605943565;5781.467911003588;5975.790847320344;6203.813885583429;4028;4346.456692913378;4571.4514258289055;4585.335126406669;4587.24310488769;3929.941050884239;4479.8352428368535;4600.6549508786975;4603.332516785064;4634.536546345372;4560.17916774982;4579.67942244043;4586.243269860485;4588.220002599566;4599.908001839978;4460.219461594224;4510.887227819298;4546.499885012646;4560.1335746208115;4565.360849481075;2692.4211294571564;3567.518385018015;3902.765834049966;3947.072437977073;4033.334499807528;4947.2579113133315;6045.969770151165;6046.3349031606485;6100.633961962297;6116.26604807426;4270.444842170529;5378.865528361783;5448.782048718046;5458.563314934796;5479.794320322616;5778.508826749731;5891.675957822318;6664.733410663568;6666.600003999775;6676.899846502306;4348.630181492851;4363.890902727458;4425.247707889666;4464.933026004613;4495.46054473462;5541.3904470507805;5928.525717942554;6743.831404214885;7029.753958611424;7254.818629534304;3669.908152353133;4526.20791361517;4531.77341132942;4541.8183272669385;4558.544145585482;3855.8264878079995;4636.976815115903;4643.049919034014;4646.070785842845;4653.348061438141;5176.61875537257;5381.654586353412;5457.61796674229;5459.372172200247;5486.478784515544;5224.791008359623;6550.475961923034;6662.800115996528;6688.528523724773;6742.393184613408;1557.1591340676134;1561.3520389038422;1573.3627880708332;1573.4886161997397;1585.5639699082844;940.2665920581957;941.23759754599;1028.6708253358854;1036.0520124086377;1050.201846596592;6242.781502647385;6297.4962003039645;6311.842203944846;6332.461740751995;6340.175777148941;5680.971595141998;6191.659458729813;6840.863182736317;6894.172699276119;6910.965445172742;5464.240558517923;5787.913181302284;5985.730642121115;6273.18448601688;6396.872062558723;2972.75144439336;3468.583769947572;3556.28874225156;3638.599754027055;3643.6154261380507;6433.163688720437;6741.831454213684;7067.399271061914;7101.467389945775;7145.6784444700115;5642.915356269659;6778.76274330406;7019.92980070203;7183.748568800172;7197.856042879113;6900.344467275701;7047.925493644568;7122.501424900311;7154.213036565973;7255.637218138966;6988.231294557493;6990.61551614666;6995.195552511526;6998.75504357345;7041.788746337618;6367.808965731036;6374.573621730817;6727.746205021177;6765.598684439267;6851.280615535297;6496.252930913006;6706.362895524917;6845.452363810784;6948.583085014915;7121.148501389573;3811.818336315751;4017.8348278999933;4323.52441231458;4505.648305508269;4524.276115821453;6102.115193297036;6687.465002799861;6872.244053154249;6963.338482844122;6975.128108986377;6015.5488338373925;6153.892299385974;6640.90038649411;6708.444524402074;6763.540818518205;6098.512119030555;6405.011314421031;6465.579737317059;6670.799876003728;6701.363370479797;5584.916226256691;5684.232628595087;5850.829834033125;6544.770933017512;6567.44176744982;5997;6022.969885150546;6444.581102228341;6703.229128650268;6735.87035797422;5135.794568217229;5952.303593475838;6346.333634968262;6379.245707430494;6505 17:44:48 INFO - PID 30552 | |5;yui.html;745.925407459254;824.983500329993;843.4559708987699;871.2028493928052;879.3317079019943;14422.278886055681;15099.169545674984;16346.836531634695;16521.66956660866;16744.413945511893;15783;17388.21788513838;17579.033153176602;17656;17784.021878796662;954.4559601027435;992.4442312305132;992.6277645882794;995.0944640377259;999.5052449037737;970.5195928015606;974.3423189347197;983.4935008470643;988.4316518002141;988.7132731507854;1064.7072055184808;1070.3417398300028;1072.2119242356862;1074.1192222377654;1077.8167711489027;1044.4307852220559;1063.5107850388831;1064.6167379743285;1067.359584249448;1068.4604274841233;781.0978320040306;791.1100012485954;795.4272923495074;795.4710117771675;798.6885114805231;16531.504054878253;17056.48830535086;17298.56753581158;17306.826931730648;17327;16241.675166496721;16702;16740.9955402675;16746.49760507187;16943.661126777395;12537.247765134121;12730.490780368775;12879.291638959854;12893.613191604267;12917.547885823951;3333.4166520858753;3346.5482159908343;3352.4971254311804;3357.4292370297107;3385.779924304912;115185;115940;116602.41698791621;117072.41463792711;117631;12120.212186208044;12189.146759726735;12224.75550489003;12231.633051008484;12232.633021009384;20262.49343766388;20319.8984005082;20353.89823050905;20384.89807550953;20539.38381848547;7327.047483827068;7563.319301262913;7919.20807919215;8259.84362189294;8298.377621678399;4443.800028998703;4521.638268938477;4542.295944128666;4549.886252843639;4571.2000399929875;4022.175454031975;4113.547509773923;4165.12532368206;4167.020750123718;4183.456150700389;880.1330689271063;904.2765787370209;907.2605826251715;910.899801021887;911.4759013567225;658.8748137853793;663.6316844151446;664.5381459885333;667.9198496180489;695.2664938489942;11273.830892536618;11760.294382337088;11973.221740586834;11999;12132;660.2110477978865;661.408039804382;668.4385116502065;668.7258224128084;691.9827004324932;5444.373897001817;5455.290812194389;5566.6381685191145;5579.13523403873;5680.744366503517;107702.46148769205;107828.46085769522;108144.4592777031;108273;110212.4489377564;73733;95960;96043.51978240065;96973;97122;82724.93187670237;83830;84684;85518.57240713881;85616;9298.860517092384;10574.577016919546;10680.092192163442;11111.666650000512;11230.719232019264;37866.43200351942;38522;39726.801365992986;40222;41170.38244426276;66751.33248667451;67359;67716;68804.3119568818;72522.63738681484;14213;15616.90681652319;16275.674486510441;16833.74749378736;16863;18422.631547369245;18481.72277415813;18491.630167396845;18615.255389784255;18637.627247455246;15518.224088795288;15817.762733558775;15827;15923.44267950616;17104.486865394058;15155.242237888282;15222;15274.007189532645;15533.533993980198;16762.24569894382;16062;16570.751438728188;16583.17084145764;16584.585385365463;16652.3339066436;11539.884601154217;11817.527298907948;11955;12188.75622487563;12413.255204687748;11570.421478926188;12441.129120961446;12710.428030738454;12949.805752913526;12990.35048247565 17:44:48 INFO - PID 30552 | __end_tp_report 17:44:48 INFO - PID 30552 | __start_cc_report 17:44:48 INFO - PID 30552 | _x_x_mozilla_cycle_collect,432 17:44:48 INFO - PID 30552 | __end_cc_report 17:44:48 INFO - PID 30552 | __startTimestamp1492044288692__endTimestamp 17:44:48 INFO - PID 30552 | 17:44:48 INFO - PID 30552 | ------- Summary: start ------- 17:44:48 INFO - PID 30552 | Number of tests: 6 17:44:48 INFO - PID 30552 | 17:44:48 INFO - PID 30552 | [#0] dojo.html Cycles:165 Average:6118.07 Median:6337.68 stddev:766.21 (12.1%) stddev-sans-first:727.41 17:44:48 INFO - PID 30552 | Values: 2959.9 3040.1 3410.2 3518.7 3521.4 6113.0 6156.0 6178.4 6201.3 6213.4 5833.3 6132.7 6152.9 6205.1 6253.5 4872.2 5426.2 5471.5 5536.1 5583.5 5404.9 5511.1 5557.3 5584.9 5624.2 5376.3 5443.7 5486.7 5499.4 5501.6 5481.7 5488.2 5493.2 5493.4 5527.0 5407.9 5433.2 5443.8 5443.8 5502.9 4879.3 5823.8 6117.0 6131.9 6178.4 6660.1 6684.1 6690.7 6698.7 6700.9 6782.7 6787.6 6806.7 6810.7 6829.4 6152.5 6205.9 6269.8 6293.7 6325.7 6020.7 6862.4 6864.1 6901.0 6955.3 6369.3 6420.7 6548.4 6616.1 6630.3 6450.3 6576.4 6642.3 6646.8 6654.9 6381.7 6664.6 6667.1 6684.2 6700.4 6509.2 6524.9 6553.8 6563.9 6574.5 5353.9 5358.6 5360.6 5361.2 5417.8 4032.6 4731.8 4742.6 4748.7 4762.1 5219.2 6199.9 6210.5 6284.4 6603.7 6681.9 6791.6 6795.8 6808.8 6842.7 6225.9 6273.7 6316.8 6319.8 6337.7 5754.5 5920.1 5935.3 5961.3 5973.6 5674.0 6713.2 6761.7 6812.9 6872.9 6762.5 6772.1 6783.6 6796.7 6816.0 6816.6 6818.3 6821.4 6857.5 6873.9 6440.6 6503.0 6582.6 6627.6 6669.5 6645.3 6749.9 6754.9 6787.1 6803.6 6290.7 6332.1 6487.4 6554.9 6564.1 6561.1 6573.7 6595.6 6599.9 6615.7 5607.9 6248.8 6299.1 6330.4 6339.6 6442.5 6500.2 6532.0 6556.8 6559.1 5206.2 6382.3 6384.4 6386.6 6387.7 17:44:48 INFO - PID 30552 | 17:44:48 INFO - PID 30552 | [#1] ext.html Cycles:180 Average:47150.51 Median:34094.24 stddev:86242.06 (253.0%) stddev-sans-first:86413.77 17:44:48 INFO - PID 30552 | Values: 800.3 837.2 1241.5 1259.4 1294.0 31154.5 31198.8 38419.6 38558.8 38638.6 38688.8 38735.6 38882.8 39061.8 39113.6 6997.9 8069.9 8208.2 8827.3 8849.6 8716.5 8791.3 8812.3 8826.2 8856.8 8799.8 8840.0 8848.7 8869.7 8870.0 7472.6 8767.3 8810.6 8822.3 8830.8 8268.3 8324.6 8332.9 8336.5 8343.2 37979.2 38201.2 38251.8 38508.2 38521.8 3587.5 3600.7 3632.6 3637.3 3639.5 30959.8 31167.2 31187.8 31227.5 31232.9 2713.7 2733.5 2948.6 3139.8 3141.0 260318.4 298198.0 299605.0 302203.5 302305.0 40022.2 40632.0 48948.8 49323.0 49674.3 42724.4 45605.1 50917.0 51148.7 51168.2 12917.9 14802.6 14814.8 14816.3 14831.7 11335.9 11783.7 11932.9 11944.8 12143.5 12732.6 12908.4 13527.0 13685.5 13793.9 5065.3 5994.9 6039.9 6077.3 6077.4 1013.5 1024.6 1025.2 1056.7 1061.5 23127.0 23961.2 24124.9 24156.5 24282.4 1121.2 1122.1 1127.7 1157.3 1167.1 9153.7 12243.4 12284.6 12287.2 12386.6 362976.0 363190.0 363246.0 365192.2 366022.0 53304.7 53630.5 53678.5 53696.5 54132.7 1466.3 1484.8 1524.4 1530.1 1543.0 23808.2 23895.6 23897.8 23921.8 24041.5 38315.6 38332.0 38574.0 38579.0 38663.4 320377.0 322217.4 322553.0 322604.0 322838.4 33792.8 34395.7 34725.5 34880.5 35175.6 44358.1 45146.1 45478.2 45504.3 45692.8 24459.8 29556.6 30798.0 31630.4 31916.7 26183.9 30466.3 31433.9 31445.0 31744.8 32398.0 36264.8 36486.6 37130.6 37615.4 22823.0 31361.4 32042.4 32176.0 32543.0 20180.8 24308.8 24323.6 24387.9 24734.0 17:44:48 INFO - PID 30552 | 17:44:48 INFO - PID 30552 | [#2] jquery.html Cycles:180 Average:41879.34 Median:2297.13 stddev:120683.32 (5253.7%) stddev-sans-first:120981.75 17:44:48 INFO - PID 30552 | Values: 434.9 436.7 437.0 440.6 465.7 18737.0 20197.3 20756.0 20863.9 21101.3 21465.4 21662.5 21687.9 21839.8 22167.9 5986.4 6228.1 6268.4 6272.7 6279.7 5920.9 6055.7 6196.0 6205.8 6229.2 5950.4 6114.7 6144.8 6170.1 6189.4 5992.9 6136.0 6147.7 6164.0 6174.4 5390.6 5443.1 5450.9 5452.3 5591.3 26672.0 27021.1 27022.6 27189.0 27829.6 2210.8 2267.4 2292.1 2297.0 2297.3 14723.3 17616.8 17866.4 18096.5 18566.1 2140.1 2142.0 2194.5 2203.2 2205.9 158155.0 168134.0 169553.0 170259.0 170916.0 10306.9 12335.6 12594.4 12690.2 12756.6 16164.8 16168.4 16177.5 16194.4 16230.7 8783.3 8971.3 9099.9 9223.6 9242.7 8738.3 8775.6 8836.7 8984.0 8999.6 6288.4 7779.5 7789.3 7796.2 7818.0 2124.8 2129.4 2133.0 2137.5 2304.9 831.0 965.6 983.6 990.7 1010.4 19087.2 20390.6 20717.0 20809.2 21092.4 828.8 919.9 946.7 1006.6 1028.2 10511.6 10847.2 13216.0 13947.6 14190.4 605217.0 733580.0 734427.0 746777.0 751903.0 156088.0 156786.0 161268.2 163876.0 164256.1 1197.1 1220.8 1240.8 1244.0 1257.3 20828.0 21696.3 21798.7 21947.0 21988.0 53444.0 64714.0 65726.7 66158.0 66902.7 89318.6 93599.5 94467.0 94811.0 95169.0 7955.3 8389.0 8574.4 8586.5 8753.9 10958.6 13222.6 13280.4 13454.7 13636.6 9227.3 9245.1 9422.5 11336.7 11613.6 8766.7 9053.2 10555.2 10919.3 11068.4 11451.8 11507.5 11567.3 11954.0 11998.2 8461.8 8479.7 8485.7 8574.2 8723.8 11321.3 11327.8 11501.7 11685.9 11696.9 17:44:48 INFO - PID 30552 | 17:44:48 INFO - PID 30552 | [#3] mootools.html Cycles:180 Average:7147.40 Median:2543.98 stddev:4502.07 (177.0%) stddev-sans-first:4492.48 17:44:48 INFO - PID 30552 | Values: 1194.7 1287.0 1410.7 1415.0 1439.2 9471.5 10059.4 11487.8 11999.0 12190.3 11667.2 11982.9 11992.7 12000.7 12006.8 2497.4 2532.1 2543.8 2544.2 2554.7 2467.5 2479.2 2515.4 2517.9 2523.5 2391.1 2447.8 2473.0 2519.2 2522.4 2423.3 2470.0 2476.9 2508.1 2528.8 2153.5 2330.4 2475.8 2485.0 2491.1 11662.7 11755.0 11806.8 11809.8 11838.3 6514.8 6767.0 6770.2 6780.3 6802.4 3256.6 3580.3 4049.6 4129.4 4286.6 1031.6 1217.0 1226.7 1266.6 1289.7 14956.8 15534.8 16351.5 16517.4 16590.8 5531.6 5651.8 5870.8 5880.8 5892.9 12058.9 12203.3 12309.8 12314.8 12330.8 6554.7 6607.1 6608.1 6619.2 6621.3 5993.9 6178.1 6188.6 6224.8 6277.9 4390.3 4471.0 4516.4 4553.3 4553.8 1290.9 1309.5 1314.6 1324.0 1342.3 780.2 783.3 796.7 803.3 803.4 6534.6 6604.1 6649.7 6878.3 6902.2 775.6 936.9 941.2 942.0 943.8 3896.5 4055.7 4150.5 4240.0 4324.4 12175.4 14754.3 15386.0 15420.7 15478.6 14744.5 14997.1 15300.3 15372.9 15554.6 10926.6 10943.1 10999.8 11084.2 11085.7 6037.8 6900.6 7044.0 7124.9 7140.0 12816.5 12846.7 13106.9 13144.0 13163.7 13144.6 13159.9 13255.1 13257.8 13356.6 8770.0 8976.2 9021.4 9485.2 9877.9 8317.5 10189.8 11015.1 11157.1 11175.3 9194.9 9276.4 9318.6 9355.3 9450.4 9006.7 9053.4 9127.0 9137.5 9169.8 7997.1 8091.1 8188.7 8198.3 8228.6 5559.7 5661.4 6031.7 6044.0 6241.6 4960.5 5907.3 6159.4 6183.0 6284.5 17:44:48 INFO - PID 30552 | 17:44:48 INFO - PID 30552 | [#4] prototype.html Cycles:180 Average:5317.29 Median:5964.05 stddev:1691.90 (28.4%) stddev-sans-first:1656.03 17:44:48 INFO - PID 30552 | Values: 408.2 409.4 411.0 413.7 420.3 6171.7 6262.4 6367.4 6374.9 6415.9 5093.0 5720.7 5781.5 5975.8 6203.8 4028.0 4346.5 4571.5 4585.3 4587.2 3929.9 4479.8 4600.7 4603.3 4634.5 4560.2 4579.7 4586.2 4588.2 4599.9 4460.2 4510.9 4546.5 4560.1 4565.4 2692.4 3567.5 3902.8 3947.1 4033.3 4947.3 6046.0 6046.3 6100.6 6116.3 4270.4 5378.9 5448.8 5458.6 5479.8 5778.5 5891.7 6664.7 6666.6 6676.9 4348.6 4363.9 4425.2 4464.9 4495.5 5541.4 5928.5 6743.8 7029.8 7254.8 3669.9 4526.2 4531.8 4541.8 4558.5 3855.8 4637.0 4643.0 4646.1 4653.3 5176.6 5381.7 5457.6 5459.4 5486.5 5224.8 6550.5 6662.8 6688.5 6742.4 1557.2 1561.4 1573.4 1573.5 1585.6 940.3 941.2 1028.7 1036.1 1050.2 6242.8 6297.5 6311.8 6332.5 6340.2 5681.0 6191.7 6840.9 6894.2 6911.0 5464.2 5787.9 5985.7 6273.2 6396.9 2972.8 3468.6 3556.3 3638.6 3643.6 6433.2 6741.8 7067.4 7101.5 7145.7 5642.9 6778.8 7019.9 7183.7 7197.9 6900.3 7047.9 7122.5 7154.2 7255.6 6988.2 6990.6 6995.2 6998.8 7041.8 6367.8 6374.6 6727.7 6765.6 6851.3 6496.3 6706.4 6845.5 6948.6 7121.1 3811.8 4017.8 4323.5 4505.6 4524.3 6102.1 6687.5 6872.2 6963.3 6975.1 6015.5 6153.9 6640.9 6708.4 6763.5 6098.5 6405.0 6465.6 6670.8 6701.4 5584.9 5684.2 5850.8 6544.8 6567.4 5997.0 6023.0 6444.6 6703.2 6735.9 5135.8 5952.3 6346.3 6379.2 6505.0 17:44:48 INFO - PID 30552 | 17:44:48 INFO - PID 30552 | [#5] yui.html Cycles:180 Average:21795.26 Median:18486.68 stddev:30864.17 (167.0%) stddev-sans-first:30910.28 17:44:48 INFO - PID 30552 | Values: 745.9 825.0 843.5 871.2 879.3 14422.3 15099.2 16346.8 16521.7 16744.4 15783.0 17388.2 17579.0 17656.0 17784.0 954.5 992.4 992.6 995.1 999.5 970.5 974.3 983.5 988.4 988.7 1064.7 1070.3 1072.2 1074.1 1077.8 1044.4 1063.5 1064.6 1067.4 1068.5 781.1 791.1 795.4 795.5 798.7 16531.5 17056.5 17298.6 17306.8 17327.0 16241.7 16702.0 16741.0 16746.5 16943.7 12537.2 12730.5 12879.3 12893.6 12917.5 3333.4 3346.5 3352.5 3357.4 3385.8 115185.0 115940.0 116602.4 117072.4 117631.0 12120.2 12189.1 12224.8 12231.6 12232.6 20262.5 20319.9 20353.9 20384.9 20539.4 7327.0 7563.3 7919.2 8259.8 8298.4 4443.8 4521.6 4542.3 4549.9 4571.2 4022.2 4113.5 4165.1 4167.0 4183.5 880.1 904.3 907.3 910.9 911.5 658.9 663.6 664.5 667.9 695.3 11273.8 11760.3 11973.2 11999.0 12132.0 660.2 661.4 668.4 668.7 692.0 5444.4 5455.3 5566.6 5579.1 5680.7 107702.5 107828.5 108144.5 108273.0 110212.4 73733.0 95960.0 96043.5 96973.0 97122.0 82724.9 83830.0 84684.0 85518.6 85616.0 9298.9 10574.6 10680.1 11111.7 11230.7 37866.4 38522.0 39726.8 40222.0 41170.4 66751.3 67359.0 67716.0 68804.3 72522.6 14213.0 15616.9 16275.7 16833.7 16863.0 18422.6 18481.7 18491.6 18615.3 18637.6 15518.2 15817.8 15827.0 15923.4 17104.5 15155.2 15222.0 15274.0 15533.5 16762.2 16062.0 16570.8 16583.2 16584.6 16652.3 11539.9 11817.5 11955.0 12188.8 12413.3 11570.4 12441.1 12710.4 12949.8 12990.4 17:44:48 INFO - PID 30552 | -------- Summary: end -------- 17:44:48 INFO - PID 30552 | 17:44:49 INFO - TEST-INFO | 30552: exit 0 17:44:49 INFO - TEST-OK | dromaeo_css | took 1115345ms 17:44:49 INFO - TEST-START | kraken 17:44:49 INFO - operating with platform_type : mac_ 17:44:49 INFO - Initialising browser for kraken test... 17:44:49 INFO - TEST-INFO | started process 30558 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmp6iBxCU/profile http://localhost:60465/getInfo.html) 17:44:52 INFO - PID 30558 | 2017-04-12 17:44:52.691 plugin-container[30559:274390] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 17:44:52 INFO - PID 30558 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:44:52 INFO - PID 30558 | 2017-04-12 17:44:52.694 plugin-container[30559:274390] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c13, name = 'com.apple.CFPasteboardClient' 17:44:52 INFO - PID 30558 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:44:52 INFO - PID 30558 | 2017-04-12 17:44:52.694 plugin-container[30559:274390] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:44:52 INFO - PID 30558 | __metrics Screen width/height:1600/1200 17:44:52 INFO - PID 30558 | colorDepth:24 17:44:52 INFO - PID 30558 | Browser inner width/height: 1024/689 17:44:52 INFO - PID 30558 | __metrics 17:44:52 ERROR - PID 30558 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 17:44:53 INFO - TEST-INFO | 30558: exit 0 17:44:53 INFO - Browser initialized. 17:44:53 INFO - Running cycle 1/1 for kraken test... 17:44:53 INFO - TEST-INFO | started process 30560 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmp6iBxCU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:44:56 INFO - PID 30560 | 2017-04-12 17:44:56.605 plugin-container[30561:274514] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443f, name = 'com.apple.tsm.portname' 17:44:56 INFO - PID 30560 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:44:56 INFO - PID 30560 | 2017-04-12 17:44:56.606 plugin-container[30561:274514] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 17:44:56 INFO - PID 30560 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:44:56 INFO - PID 30560 | 2017-04-12 17:44:56.606 plugin-container[30561:274514] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:45:06 INFO - PID 30560 | RSS: Main: 235655168 17:45:06 INFO - PID 30560 | 17:45:09 INFO - PID 30560 | Cycle 1(1): loaded http://localhost:60465/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60465/tests/kraken/driver.html?testName=audio-beat-detection) 17:45:10 INFO - PID 30560 | RSS: Main: 250359808 17:45:10 INFO - PID 30560 | 17:45:12 INFO - PID 30560 | Cycle 1(1): loaded http://localhost:60465/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60465/tests/kraken/driver.html?testName=audio-dft) 17:45:13 INFO - PID 30560 | RSS: Main: 258850816 17:45:13 INFO - PID 30560 | 17:45:17 INFO - PID 30560 | Cycle 1(1): loaded http://localhost:60465/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60465/tests/kraken/driver.html?testName=audio-fft) 17:45:18 INFO - PID 30560 | RSS: Main: 261181440 17:45:18 INFO - PID 30560 | 17:45:20 INFO - PID 30560 | Cycle 1(1): loaded http://localhost:60465/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60465/tests/kraken/driver.html?testName=audio-oscillator) 17:45:20 INFO - PID 30560 | RSS: Main: 263233536 17:45:20 INFO - PID 30560 | 17:45:22 INFO - PID 30560 | Cycle 1(1): loaded http://localhost:60465/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60465/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:45:23 INFO - PID 30560 | RSS: Main: 263454720 17:45:23 INFO - PID 30560 | 17:45:26 INFO - PID 30560 | Cycle 1(1): loaded http://localhost:60465/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60465/tests/kraken/driver.html?testName=imaging-darkroom) 17:45:27 INFO - PID 30560 | RSS: Main: 269058048 17:45:27 INFO - PID 30560 | 17:45:30 INFO - PID 30560 | Cycle 1(1): loaded http://localhost:60465/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60465/tests/kraken/driver.html?testName=imaging-desaturate) 17:45:31 INFO - PID 30560 | RSS: Main: 267059200 17:45:31 INFO - PID 30560 | 17:45:35 INFO - PID 30560 | Cycle 1(1): loaded http://localhost:60465/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60465/tests/kraken/driver.html?testName=json-parse-financial) 17:45:35 INFO - PID 30560 | RSS: Main: 267042816 17:45:35 INFO - PID 30560 | 17:45:37 INFO - PID 30560 | Cycle 1(1): loaded http://localhost:60465/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60465/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:45:37 INFO - PID 30560 | RSS: Main: 268066816 17:45:37 INFO - PID 30560 | 17:45:39 INFO - PID 30560 | Cycle 1(1): loaded http://localhost:60465/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60465/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:45:40 INFO - PID 30560 | RSS: Main: 268357632 17:45:40 INFO - PID 30560 | 17:45:42 INFO - PID 30560 | Cycle 1(1): loaded http://localhost:60465/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60465/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:45:43 INFO - PID 30560 | RSS: Main: 268673024 17:45:43 INFO - PID 30560 | 17:45:46 INFO - PID 30560 | Cycle 1(1): loaded http://localhost:60465/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60465/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:45:47 INFO - PID 30560 | RSS: Main: 269197312 17:45:47 INFO - PID 30560 | 17:45:50 INFO - PID 30560 | Cycle 1(1): loaded http://localhost:60465/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60465/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:45:51 INFO - PID 30560 | RSS: Main: 267485184 17:45:51 INFO - PID 30560 | 17:45:53 INFO - PID 30560 | Cycle 1(1): loaded http://localhost:60465/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60465/tests/kraken/driver.html?testName=ai-astar) 17:45:53 INFO - PID 30560 | RSS: Main: 270270464 17:45:53 INFO - PID 30560 | 17:45:53 INFO - PID 30560 | __start_tp_report 17:45:53 INFO - PID 30560 | _x_x_mozilla_page_load 17:45:53 INFO - PID 30560 | _x_x_mozilla_page_load_details 17:45:53 INFO - PID 30560 | |i|pagename|runs| 17:45:53 INFO - PID 30560 | |0;ai-astar;109;123;124;121;121;122;138;188;185;104 17:45:53 INFO - PID 30560 | |1;audio-beat-detection;155;116;115;157;117;125;158;159;136;115 17:45:53 INFO - PID 30560 | |2;audio-dft;227;292;265;284;295;216;213;205;208;212 17:45:53 INFO - PID 30560 | |3;audio-fft;87;59;61;92;77;51;85;50;66;59 17:45:53 INFO - PID 30560 | |4;audio-oscillator;86;96;118;70;73;76;74;82;78;80 17:45:53 INFO - PID 30560 | |5;imaging-gaussian-blur;94;142;79;82;92;143;142;92;91;90 17:45:53 INFO - PID 30560 | |6;imaging-darkroom;158;99;98;165;161;160;124;115;103;165 17:45:53 INFO - PID 30560 | |7;imaging-desaturate;102;101;104;164;132;143;165;161;141;101 17:45:53 INFO - PID 30560 | |8;json-parse-financial;52;53;51;52;53;53;53;53;58;53 17:45:53 INFO - PID 30560 | |9;json-stringify-tinderbox;60;67;66;64;62;60;63;63;61;62 17:45:53 INFO - PID 30560 | |10;stanford-crypto-aes;87;85;83;98;117;117;89;87;115;93 17:45:53 INFO - PID 30560 | |11;stanford-crypto-ccm;150;170;173;169;145;145;147;167;224;197 17:45:53 INFO - PID 30560 | |12;stanford-crypto-pbkdf2;209;222;280;200;229;286;230;213;194;192 17:45:53 INFO - PID 30560 | |13;stanford-crypto-sha256-iterative;75;73;74;73;73;74;74;90;105;110 17:45:53 INFO - PID 30560 | __end_tp_report 17:45:53 INFO - PID 30560 | __start_cc_report 17:45:53 INFO - PID 30560 | _x_x_mozilla_cycle_collect,1029 17:45:53 INFO - PID 30560 | __end_cc_report 17:45:53 INFO - PID 30560 | __startTimestamp1492044353566__endTimestamp 17:45:53 INFO - PID 30560 | 17:45:53 INFO - PID 30560 | ------- Summary: start ------- 17:45:53 INFO - PID 30560 | Number of tests: 14 17:45:53 INFO - PID 30560 | 17:45:53 INFO - PID 30560 | [#0] ai-astar Cycles:10 Average:133.50 Median:123.50 stddev:29.36 (23.8%) stddev-sans-first:29.77 17:45:53 INFO - PID 30560 | Values: 109.0 123.0 124.0 121.0 121.0 122.0 138.0 188.0 185.0 104.0 17:45:53 INFO - PID 30560 | 17:45:53 INFO - PID 30560 | [#1] audio-beat-detection Cycles:10 Average:135.30 Median:145.50 stddev:19.93 (13.7%) stddev-sans-first:19.82 17:45:53 INFO - PID 30560 | Values: 155.0 116.0 115.0 157.0 117.0 125.0 158.0 159.0 136.0 115.0 17:45:53 INFO - PID 30560 | 17:45:53 INFO - PID 30560 | [#2] audio-dft Cycles:10 Average:241.70 Median:246.00 stddev:37.67 (15.3%) stddev-sans-first:39.57 17:45:53 INFO - PID 30560 | Values: 227.0 292.0 265.0 284.0 295.0 216.0 213.0 205.0 208.0 212.0 17:45:53 INFO - PID 30560 | 17:45:53 INFO - PID 30560 | [#3] audio-fft Cycles:10 Average:68.70 Median:71.50 stddev:15.38 (21.5%) stddev-sans-first:14.82 17:45:53 INFO - PID 30560 | Values: 87.0 59.0 61.0 92.0 77.0 51.0 85.0 50.0 66.0 59.0 17:45:53 INFO - PID 30560 | 17:45:53 INFO - PID 30560 | [#4] audio-oscillator Cycles:10 Average:83.30 Median:79.00 stddev:14.28 (18.1%) stddev-sans-first:15.12 17:45:53 INFO - PID 30560 | Values: 86.0 96.0 118.0 70.0 73.0 76.0 74.0 82.0 78.0 80.0 17:45:53 INFO - PID 30560 | 17:45:53 INFO - PID 30560 | [#5] imaging-gaussian-blur Cycles:10 Average:104.70 Median:90.50 stddev:26.39 (29.2%) stddev-sans-first:27.70 17:45:53 INFO - PID 30560 | Values: 94.0 142.0 79.0 82.0 92.0 143.0 142.0 92.0 91.0 90.0 17:45:53 INFO - PID 30560 | 17:45:53 INFO - PID 30560 | [#6] imaging-darkroom Cycles:10 Average:134.80 Median:163.00 stddev:29.51 (18.1%) stddev-sans-first:30.09 17:45:53 INFO - PID 30560 | Values: 158.0 99.0 98.0 165.0 161.0 160.0 124.0 115.0 103.0 165.0 17:45:53 INFO - PID 30560 | 17:45:53 INFO - PID 30560 | [#7] imaging-desaturate Cycles:10 Average:131.40 Median:142.00 stddev:27.40 (19.3%) stddev-sans-first:26.92 17:45:53 INFO - PID 30560 | Values: 102.0 101.0 104.0 164.0 132.0 143.0 165.0 161.0 141.0 101.0 17:45:53 INFO - PID 30560 | 17:45:53 INFO - PID 30560 | [#8] json-parse-financial Cycles:10 Average:53.10 Median:53.00 stddev:1.85 (3.5%) stddev-sans-first:1.92 17:45:53 INFO - PID 30560 | Values: 52.0 53.0 51.0 52.0 53.0 53.0 53.0 53.0 58.0 53.0 17:45:53 INFO - PID 30560 | 17:45:53 INFO - PID 30560 | [#9] json-stringify-tinderbox Cycles:10 Average:62.80 Median:63.00 stddev:2.35 (3.7%) stddev-sans-first:2.26 17:45:53 INFO - PID 30560 | Values: 60.0 67.0 66.0 64.0 62.0 60.0 63.0 63.0 61.0 62.0 17:45:53 INFO - PID 30560 | 17:45:53 INFO - PID 30560 | [#10] stanford-crypto-aes Cycles:10 Average:97.10 Median:87.00 stddev:13.92 (16.0%) stddev-sans-first:14.28 17:45:53 INFO - PID 30560 | Values: 87.0 85.0 83.0 98.0 117.0 117.0 89.0 87.0 115.0 93.0 17:45:53 INFO - PID 30560 | 17:45:53 INFO - PID 30560 | [#11] stanford-crypto-ccm Cycles:10 Average:168.70 Median:169.50 stddev:25.44 (15.0%) stddev-sans-first:26.07 17:45:53 INFO - PID 30560 | Values: 150.0 170.0 173.0 169.0 145.0 145.0 147.0 167.0 224.0 197.0 17:45:53 INFO - PID 30560 | 17:45:53 INFO - PID 30560 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:225.50 Median:225.50 stddev:33.11 (14.7%) stddev-sans-first:34.58 17:45:53 INFO - PID 30560 | Values: 209.0 222.0 280.0 200.0 229.0 286.0 230.0 213.0 194.0 192.0 17:45:53 INFO - PID 30560 | 17:45:53 INFO - PID 30560 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:82.10 Median:74.00 stddev:14.38 (19.4%) stddev-sans-first:15.02 17:45:53 INFO - PID 30560 | Values: 75.0 73.0 74.0 73.0 73.0 74.0 74.0 90.0 105.0 110.0 17:45:53 INFO - PID 30560 | -------- Summary: end -------- 17:45:53 INFO - PID 30560 | 17:45:54 INFO - TEST-INFO | 30560: exit 0 17:45:54 INFO - TEST-OK | kraken | took 65125ms 17:45:54 INFO - SUITE-END | took 1180s 17:45:54 INFO - Completed test suite (00:19:40) 17:45:54 INFO - javascript benchmark 17:45:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7903.414812495287, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2959.9408011839764, 3040.148758347662, 3410.164509695125, 3518.700910422614, 3521.366154092262, 6112.959454150314, 6156.045812899002, 6178.443940045395, 6201.317855035943, 6213.409726076021, 5833.329167145779, 6132.722655450954, 6152.876942461148, 6205.131281620576, 6253.468455181303, 4872.220444728844, 5426.240326354314, 5471.480209380103, 5536.086545719952, 5583.5254003409555, 5404.945950540484, 5511.118221084627, 5557.3331200256025, 5584.916226256589, 5624.184493248476, 5376.327959005124, 5443.727813609304, 5486.725663716838, 5499.367572729107, 5501.559875209994, 5481.698506582176, 5488.204210389511, 5493.23094766733, 5493.423190564973, 5527.0, 5407.918881216785, 5433.157860531624, 5443.755031023614, 5443.755031023614, 5502.944970550283, 4879.268109783526, 5823.82528524145, 6117.035835319757, 6131.89663773324, 6178.41305076017, 6660.067590537328, 6684.131062961833, 6690.732370705215, 6698.732050717966, 6700.865982680319, 6782.694778734969, 6787.626680532569, 6806.727730890759, 6810.693518791617, 6829.351211634887, 6152.477039451696, 6205.875882482325, 6269.811905642838, 6293.685315734195, 6325.715342809584, 6020.705548307135, 6862.382385585322, 6864.073350097773, 6901.0, 6955.269696681877, 6369.267534233622, 6420.678966051679, 6548.443382312556, 6616.139901812733, 6630.30381809903, 6450.322716114834, 6576.375244351779, 6642.302558231413, 6646.83382915431, 6654.86690266192, 6381.744730210832, 6664.566803157876, 6667.133272674521, 6684.197896252481, 6700.430463410665, 6509.1863517060365, 6524.902126468011, 6553.770618028346, 6563.934360656428, 6574.474042076622, 5353.86615334612, 5358.624896257303, 5360.597955153379, 5361.249425080492, 5417.783288668488, 4032.5765794591734, 4731.810727570859, 4742.5731684148595, 4748.715077095385, 4762.071396077731, 5219.217117432416, 6199.938000620026, 6210.534209934273, 6284.402981716729, 6603.70283337251, 6681.866362672817, 6791.558548694319, 6795.762148324785, 6808.8297792554595, 6842.726290948306, 6225.937740622536, 6273.749050038039, 6316.778912738032, 6319.778807741707, 6337.683115844189, 5754.4533269339345, 5920.052791553417, 5935.258092738408, 5961.344252132262, 5973.581849270509, 5673.971630141823, 6713.160854893138, 6761.729530818712, 6812.93187068123, 6872.896906546506, 6762.459003279628, 6772.085768421397, 6783.559068660521, 6796.694148763417, 6816.0457535944015, 6816.625085620339, 6818.28408017151, 6821.420179284618, 6857.5199736018, 6873.862522749417, 6440.6135631862235, 6503.0, 6582.605043697394, 6627.635480048643, 6669.4664426844765, 6645.269020407848, 6749.93250067493, 6754.898676519758, 6787.083743694538, 6803.557768745016, 6290.685465726787, 6332.11350410952, 6487.416132548094, 6554.901676474952, 6564.113844631104, 6561.114249576246, 6573.737050517926, 6595.604263744191, 6599.868002639824, 6615.702293396905, 5607.859803504782, 6248.843778905381, 6299.08663243818, 6330.39861213184, 6339.619622822645, 6442.484601231797, 6500.219973603009, 6531.967340163269, 6556.803295901131, 6559.114519539801, 5206.2450944612065, 6382.297947225708, 6384.361563843764, 6386.584871983307, 6387.712552935222], "value": 6063.5593776201595, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [800.3357213512783, 837.2004735477617, 1241.4723742409474, 1259.4017841525276, 1293.9805902911457, 31154.532682009758, 31198.844005779938, 38419.61580384195, 38558.80720596393, 38638.613613863854, 38688.80655596718, 38735.61264387355, 38882.8055859721, 39061.82814515562, 39113.60886391135, 6997.930020699804, 8069.919300806991, 8208.220219079178, 8827.293816494697, 8849.60176792046, 8716.477011379338, 8791.252743516809, 8812.295016398704, 8826.24976876963, 8856.822863542726, 8799.824003519925, 8840.027596964328, 8848.690295839648, 8869.733907982738, 8869.979952305503, 7472.551646901176, 8767.298616110727, 8810.647574097029, 8822.250108740765, 8830.823383532294, 8268.297194738454, 8324.62539185738, 8332.875006874901, 8336.541490218036, 8343.16568343167, 37979.24041519154, 38201.235975280615, 38251.808740956396, 38508.229835403414, 38521.80739096315, 3587.461880717913, 3600.7299452541165, 3632.600413954463, 3637.272545490886, 3639.47227651989, 30959.845200774078, 31167.22081947947, 31187.752489900377, 31227.531587026213, 31232.9068482602, 2713.6879258885283, 2733.521633714112, 2948.5994152777475, 3139.8116113033293, 3140.994881637877, 260318.3968160313, 298198.0, 299605.0, 302203.48898255586, 302305.0, 40022.199556008716, 40631.98420039523, 48948.75525622349, 49323.01353972972, 49674.254886176735, 42724.44582204294, 45605.08789824185, 50917.0, 51148.74425627848, 51168.23247651288, 12917.935410322889, 14802.555923322318, 14814.814814815005, 14816.25918704082, 14831.70336593262, 11335.943320283512, 11783.705407364712, 11932.88067119335, 11944.820827687592, 12143.514259429701, 12732.618021459371, 12908.35458227085, 13526.985476089332, 13685.521006764715, 13793.931030344784, 5065.3161823153405, 5994.880102397928, 6039.8792024159275, 6077.301110372276, 6077.4226448487325, 1013.5439052426437, 1024.5543188712934, 1025.1696126137851, 1056.6949816976028, 1061.5010944855906, 23127.0, 23961.16135935234, 24124.87937560301, 24156.516869662508, 24282.392940176636, 1121.2151493954264, 1122.1247427006947, 1127.672974837288, 1157.265136638224, 1167.0838391862346, 9153.725388238363, 12243.387830608433, 12284.570040048555, 12287.201331913397, 12386.62840114798, 362976.0, 363190.0, 363246.0, 365192.1740391334, 366022.0, 53304.733476333146, 53630.46369536333, 53678.46321536813, 53696.46303536915, 54132.729336353856, 1466.3254902744643, 1484.7847062175924, 1524.3673875341608, 1530.1048886401356, 1542.96142596436, 23808.16671416492, 23895.641565376187, 23897.76102239025, 23921.76078239265, 24041.51916961686, 38315.6168438312, 38332.0, 38574.0, 38579.0, 38663.42004869873, 320377.0, 322217.38891305396, 322553.0, 322604.0, 322838.38580806943, 33792.83103584466, 34395.656043439245, 34725.47911781376, 34880.47679284762, 35175.64824351724, 44358.11283774371, 45146.097078057595, 45478.18087276604, 45504.317435237834, 45692.77153614211, 24459.75540244575, 29556.55665164981, 30798.0, 31630.36739265248, 31916.68083319137, 26183.869080654476, 30466.324352160846, 31433.899813506418, 31445.0, 31744.841275793475, 32398.028059158263, 36264.81867590645, 36486.63513364938, 37130.628693712715, 37615.43576846295, 22823.0, 31361.372772543964, 32042.35915281728, 32176.0, 32543.02370928876, 20180.798192017894, 24308.756912431356, 24323.63514547248, 24387.878060609582, 24734.0], "value": 17657.167841953422, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [434.85215026890853, 436.692132046907, 436.9781510924455, 440.58144762475644, 465.71125901940803, 18737.0, 20197.293094741686, 20755.962201889903, 20863.895680521575, 21101.26145584905, 21465.356039318845, 21662.458438539004, 21687.915604219765, 21839.78160218397, 22167.889160554216, 5986.371430999737, 6228.128062071313, 6268.404501572343, 6272.7490900364155, 6279.654618995954, 5920.934231838268, 6055.697215139226, 6195.969020154893, 6205.844853878644, 6229.190205273331, 5950.434708702688, 6114.663693496856, 6144.815655530341, 6170.136180934673, 6189.412005859436, 5992.880142397149, 6135.987562052278, 6147.661878596676, 6164.013757798775, 6174.413430724074, 5390.6226564140525, 5443.101888188444, 5450.945490545123, 5452.318460192476, 5591.30108736408, 26672.0, 27021.054263100697, 27022.5946610801, 27189.048383306686, 27829.582556261674, 2210.8452408331423, 2267.353804165805, 2292.106078629336, 2297.0122847175708, 2297.253392647396, 14723.263836808117, 17616.766826322164, 17866.374676886375, 18096.547586310317, 18566.071696415125, 2140.1332460353583, 2141.95716085679, 2194.5391467791783, 2203.2068455356057, 2205.911763529473, 158155.0, 168134.0, 169553.0, 170259.0, 170916.0, 10306.896931030744, 12335.629931102081, 12594.37028148589, 12690.238585684889, 12756.55352062692, 16164.838351616569, 16168.353265869233, 16177.514674559781, 16194.352225911067, 16230.675386492205, 8783.297336213087, 8971.327150463741, 9099.863502047474, 9223.631054757734, 9242.676506322246, 8738.257248133852, 8775.561221938877, 8836.734897953073, 8983.955080224558, 8999.64001439935, 6288.402601752826, 7779.533228006338, 7789.337906277917, 7796.193370386656, 7818.022747156606, 2124.8193903518063, 2129.414411036946, 2132.9893350533457, 2137.4549489879983, 2304.8501847379866, 831.0, 965.6330594374093, 983.5967253426061, 990.7188425383106, 1010.4291075542295, 19087.2365105397, 20390.59218815645, 20717.0, 20809.1676332945, 21092.367228983156, 828.825946551231, 919.9310051746146, 946.7030168668967, 1006.5872992073215, 1028.2493779540978, 10511.583735285909, 10847.186461015455, 13216.008799340088, 13947.58157255284, 14190.361433735507, 605217.0, 733580.0, 734427.0, 746777.0, 751903.0, 156088.0, 156786.0, 161268.19365903095, 163876.0, 164256.11054728858, 1197.1141355396944, 1220.8473940757406, 1240.7580521798343, 1243.9875601244053, 1257.3084803358117, 20828.0, 21696.349109526738, 21798.673019904396, 21947.0, 21988.0, 53444.0, 64714.0, 65726.67136664285, 66158.0, 66902.66548667225, 89318.55340723255, 93599.53200233955, 94467.0, 94811.0, 95169.0, 7955.2840244378285, 8389.0, 8574.44266122725, 8586.527740974307, 8753.91246087531, 10958.575385199872, 13222.603321900358, 13280.402381892645, 13454.730905382034, 13636.590902272948, 9227.261819054595, 9245.104753525646, 9422.481763503074, 11336.716582085184, 11613.593524226611, 8766.736997890073, 9053.230475409664, 10555.208359373231, 10919.290246133976, 11068.391238481961, 11451.770964580828, 11507.539698411969, 11567.305961642329, 11954.0, 11998.16012879125, 8461.78845528842, 8479.660813567389, 8485.745427637181, 8574.22831945128, 8723.825523489368, 11321.264117832314, 11327.773444531229, 11501.712457188303, 11685.941570292094, 11696.941515292368], "value": 10604.722834824932, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1194.743130227001, 1286.9806952895708, 1410.7037522120347, 1415.037774313467, 1439.1580925158778, 9471.526423678806, 10059.396436213814, 11487.770244595104, 11999.0, 12190.329531875743, 11667.24162929411, 11982.94008529956, 11992.700182495442, 12000.699982500442, 12006.819897701542, 2497.4255921138106, 2532.126416386344, 2543.783778378831, 2544.185860524633, 2554.7317531659187, 2467.481828815951, 2479.2066538707622, 2515.434027343851, 2517.899284028646, 2523.50791595638, 2391.091385273602, 2447.840890342131, 2472.987635061822, 2519.219042096947, 2522.3946252899354, 2423.260905423845, 2470.0243403855466, 2476.8513889166707, 2508.0845491395694, 2528.8229823912334, 2153.536989547255, 2330.4290448839974, 2475.8143139264625, 2485.0184177249885, 2491.1156539428603, 11662.650120496313, 11755.0, 11806.822897656542, 11809.763804723858, 11838.348890811001, 6514.804555863332, 6767.0, 6770.221424536143, 6780.321967803229, 6802.4217941475035, 3256.6417694053634, 3580.3018411409785, 4049.5950404959267, 4129.421880936671, 4286.57134286572, 1031.5564307347913, 1217.0, 1226.7423840993313, 1266.5756971414587, 1289.7227096174367, 14956.775648365283, 15534.766978495332, 16351.509454716377, 16517.421890233905, 16590.83409165905, 5531.640443371168, 5651.802186923438, 5870.765169393262, 5880.764769409176, 5892.941070589325, 12058.939705301418, 12203.328816915155, 12309.815352769536, 12314.81527777084, 12330.81503777444, 6554.7378104876225, 6607.075009498674, 6608.140941677551, 6619.172603424572, 6621.337866213436, 5993.910091348634, 6178.104174894615, 6188.597741146811, 6224.813255602339, 6277.905831412532, 4390.341448782644, 4470.955290447054, 4516.412866327357, 4553.2714765638, 4553.817847286137, 1290.9225446473242, 1309.4744622514854, 1314.5793346129146, 1323.9602811915659, 1342.2751714074486, 780.2431641307923, 783.2832957843509, 796.7092011415907, 803.2609998801117, 803.3974519110667, 6534.640594767334, 6604.1084453599115, 6649.667516624246, 6878.3465570770695, 6902.171739391305, 775.6199462263451, 936.8875734911854, 941.1670671455811, 941.9529023548932, 943.8112377524523, 3896.473976013259, 4055.6958228132416, 4150.501939767248, 4240.0, 4324.372965919987, 12175.391230438443, 14754.336054877556, 15386.0, 15420.691586168214, 15478.613034673997, 14744.483943061941, 14997.100173989596, 15300.311485983157, 15372.923135384475, 15554.611134721496, 10926.562937482571, 10943.124550035978, 10999.780004400027, 11084.168687348481, 11085.667429977275, 6037.758489660365, 6900.6204658742245, 7043.964780176067, 7124.85750284981, 7140.0, 12816.487340506275, 12846.743065138831, 13106.868931310564, 13144.0, 13163.736725265633, 13144.60566183016, 13159.868401315864, 13255.072144949761, 13257.801132983206, 13356.599302020955, 8769.956150219208, 8976.192142706926, 9021.368504204642, 9485.193758530326, 9877.851832222666, 8317.459365141247, 10189.796204076025, 11015.063719583924, 11157.051650609788, 11175.329480231212, 9194.908050919405, 9276.443413395218, 9318.62725491, 9355.251579873458, 9450.385724927857, 9006.684766033157, 9053.366264361695, 9126.954365228132, 9137.451752894847, 9169.770755730893, 7997.1203167652675, 8091.109977902308, 8188.713395031145, 8198.262156405952, 8228.588570571328, 5559.749056462425, 5661.405552416937, 6031.728572214173, 6044.0, 6241.563090583615, 4960.50394960501, 5907.261592300963, 6159.414855588712, 6182.969085154546, 6284.4972402206795], "value": 5392.402559154294, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [408.2467846822612, 409.42271397329773, 410.97739624320667, 413.7414116177389, 420.2708301097595, 6171.691415429233, 6262.373762623741, 6367.395097465739, 6374.872502549946, 6415.871682566346, 5093.046033300686, 5720.6567605943565, 5781.467911003588, 5975.790847320344, 6203.813885583429, 4028.0, 4346.456692913378, 4571.4514258289055, 4585.335126406669, 4587.24310488769, 3929.941050884239, 4479.8352428368535, 4600.6549508786975, 4603.332516785064, 4634.536546345372, 4560.17916774982, 4579.67942244043, 4586.243269860485, 4588.220002599566, 4599.908001839978, 4460.219461594224, 4510.887227819298, 4546.499885012646, 4560.1335746208115, 4565.360849481075, 2692.4211294571564, 3567.518385018015, 3902.765834049966, 3947.072437977073, 4033.334499807528, 4947.2579113133315, 6045.969770151165, 6046.3349031606485, 6100.633961962297, 6116.26604807426, 4270.444842170529, 5378.865528361783, 5448.782048718046, 5458.563314934796, 5479.794320322616, 5778.508826749731, 5891.675957822318, 6664.733410663568, 6666.600003999775, 6676.899846502306, 4348.630181492851, 4363.890902727458, 4425.247707889666, 4464.933026004613, 4495.46054473462, 5541.3904470507805, 5928.525717942554, 6743.831404214885, 7029.753958611424, 7254.818629534304, 3669.908152353133, 4526.20791361517, 4531.77341132942, 4541.8183272669385, 4558.544145585482, 3855.8264878079995, 4636.976815115903, 4643.049919034014, 4646.070785842845, 4653.348061438141, 5176.61875537257, 5381.654586353412, 5457.61796674229, 5459.372172200247, 5486.478784515544, 5224.791008359623, 6550.475961923034, 6662.800115996528, 6688.528523724773, 6742.393184613408, 1557.1591340676134, 1561.3520389038422, 1573.3627880708332, 1573.4886161997397, 1585.5639699082844, 940.2665920581957, 941.23759754599, 1028.6708253358854, 1036.0520124086377, 1050.201846596592, 6242.781502647385, 6297.4962003039645, 6311.842203944846, 6332.461740751995, 6340.175777148941, 5680.971595141998, 6191.659458729813, 6840.863182736317, 6894.172699276119, 6910.965445172742, 5464.240558517923, 5787.913181302284, 5985.730642121115, 6273.18448601688, 6396.872062558723, 2972.75144439336, 3468.583769947572, 3556.28874225156, 3638.599754027055, 3643.6154261380507, 6433.163688720437, 6741.831454213684, 7067.399271061914, 7101.467389945775, 7145.6784444700115, 5642.915356269659, 6778.76274330406, 7019.92980070203, 7183.748568800172, 7197.856042879113, 6900.344467275701, 7047.925493644568, 7122.501424900311, 7154.213036565973, 7255.637218138966, 6988.231294557493, 6990.61551614666, 6995.195552511526, 6998.75504357345, 7041.788746337618, 6367.808965731036, 6374.573621730817, 6727.746205021177, 6765.598684439267, 6851.280615535297, 6496.252930913006, 6706.362895524917, 6845.452363810784, 6948.583085014915, 7121.148501389573, 3811.818336315751, 4017.8348278999933, 4323.52441231458, 4505.648305508269, 4524.276115821453, 6102.115193297036, 6687.465002799861, 6872.244053154249, 6963.338482844122, 6975.128108986377, 6015.5488338373925, 6153.892299385974, 6640.90038649411, 6708.444524402074, 6763.540818518205, 6098.512119030555, 6405.011314421031, 6465.579737317059, 6670.799876003728, 6701.363370479797, 5584.916226256691, 5684.232628595087, 5850.829834033125, 6544.770933017512, 6567.44176744982, 5997.0, 6022.969885150546, 6444.581102228341, 6703.229128650268, 6735.87035797422, 5135.794568217229, 5952.303593475838, 6346.333634968262, 6379.245707430494, 6505.0], "value": 4795.008165083611, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [745.925407459254, 824.983500329993, 843.4559708987699, 871.2028493928052, 879.3317079019943, 14422.278886055681, 15099.169545674984, 16346.836531634695, 16521.66956660866, 16744.413945511893, 15783.0, 17388.21788513838, 17579.033153176602, 17656.0, 17784.021878796662, 954.4559601027435, 992.4442312305132, 992.6277645882794, 995.0944640377259, 999.5052449037737, 970.5195928015606, 974.3423189347197, 983.4935008470643, 988.4316518002141, 988.7132731507854, 1064.7072055184808, 1070.3417398300028, 1072.2119242356862, 1074.1192222377654, 1077.8167711489027, 1044.4307852220559, 1063.5107850388831, 1064.6167379743285, 1067.359584249448, 1068.4604274841233, 781.0978320040306, 791.1100012485954, 795.4272923495074, 795.4710117771675, 798.6885114805231, 16531.504054878253, 17056.48830535086, 17298.56753581158, 17306.826931730648, 17327.0, 16241.675166496721, 16702.0, 16740.9955402675, 16746.49760507187, 16943.661126777395, 12537.247765134121, 12730.490780368775, 12879.291638959854, 12893.613191604267, 12917.547885823951, 3333.4166520858753, 3346.5482159908343, 3352.4971254311804, 3357.4292370297107, 3385.779924304912, 115185.0, 115940.0, 116602.41698791621, 117072.41463792711, 117631.0, 12120.212186208044, 12189.146759726735, 12224.75550489003, 12231.633051008484, 12232.633021009384, 20262.49343766388, 20319.8984005082, 20353.89823050905, 20384.89807550953, 20539.38381848547, 7327.047483827068, 7563.319301262913, 7919.20807919215, 8259.84362189294, 8298.377621678399, 4443.800028998703, 4521.638268938477, 4542.295944128666, 4549.886252843639, 4571.2000399929875, 4022.175454031975, 4113.547509773923, 4165.12532368206, 4167.020750123718, 4183.456150700389, 880.1330689271063, 904.2765787370209, 907.2605826251715, 910.899801021887, 911.4759013567225, 658.8748137853793, 663.6316844151446, 664.5381459885333, 667.9198496180489, 695.2664938489942, 11273.830892536618, 11760.294382337088, 11973.221740586834, 11999.0, 12132.0, 660.2110477978865, 661.408039804382, 668.4385116502065, 668.7258224128084, 691.9827004324932, 5444.373897001817, 5455.290812194389, 5566.6381685191145, 5579.13523403873, 5680.744366503517, 107702.46148769205, 107828.46085769522, 108144.4592777031, 108273.0, 110212.4489377564, 73733.0, 95960.0, 96043.51978240065, 96973.0, 97122.0, 82724.93187670237, 83830.0, 84684.0, 85518.57240713881, 85616.0, 9298.860517092384, 10574.577016919546, 10680.092192163442, 11111.666650000512, 11230.719232019264, 37866.43200351942, 38522.0, 39726.801365992986, 40222.0, 41170.38244426276, 66751.33248667451, 67359.0, 67716.0, 68804.3119568818, 72522.63738681484, 14213.0, 15616.90681652319, 16275.674486510441, 16833.74749378736, 16863.0, 18422.631547369245, 18481.72277415813, 18491.630167396845, 18615.255389784255, 18637.627247455246, 15518.224088795288, 15817.762733558775, 15827.0, 15923.44267950616, 17104.486865394058, 15155.242237888282, 15222.0, 15274.007189532645, 15533.533993980198, 16762.24569894382, 16062.0, 16570.751438728188, 16583.17084145764, 16584.585385365463, 16652.3339066436, 11539.884601154217, 11817.527298907948, 11955.0, 12188.75622487563, 12413.255204687748, 11570.421478926188, 12441.129120961446, 12710.428030738454, 12949.805752913526, 12990.35048247565], "value": 8301.147079662887, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1722.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 123.0, 124.0, 121.0, 121.0, 122.0, 138.0, 188.0, 185.0, 104.0], "value": 133.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 116.0, 115.0, 157.0, 117.0, 125.0, 158.0, 159.0, 136.0, 115.0], "value": 135.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [227.0, 292.0, 265.0, 284.0, 295.0, 216.0, 213.0, 205.0, 208.0, 212.0], "value": 241.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 59.0, 61.0, 92.0, 77.0, 51.0, 85.0, 50.0, 66.0, 59.0], "value": 68.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 96.0, 118.0, 70.0, 73.0, 76.0, 74.0, 82.0, 78.0, 80.0], "value": 83.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 142.0, 79.0, 82.0, 92.0, 143.0, 142.0, 92.0, 91.0, 90.0], "value": 104.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 99.0, 98.0, 165.0, 161.0, 160.0, 124.0, 115.0, 103.0, 165.0], "value": 134.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 101.0, 104.0, 164.0, 132.0, 143.0, 165.0, 161.0, 141.0, 101.0], "value": 131.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 51.0, 52.0, 53.0, 53.0, 53.0, 53.0, 58.0, 53.0], "value": 53.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 67.0, 66.0, 64.0, 62.0, 60.0, 63.0, 63.0, 61.0, 62.0], "value": 62.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 83.0, 98.0, 117.0, 117.0, 89.0, 87.0, 115.0, 93.0], "value": 97.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 170.0, 173.0, 169.0, 145.0, 145.0, 147.0, 167.0, 224.0, 197.0], "value": 168.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [209.0, 222.0, 280.0, 200.0, 229.0, 286.0, 230.0, 213.0, 194.0, 192.0], "value": 225.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 74.0, 73.0, 73.0, 74.0, 74.0, 90.0, 105.0, 110.0], "value": 82.1, "unit": "score"}]}]} 17:45:54 INFO - Return code: 0 17:45:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:45:54 ERROR - # TBPL SUCCESS # 17:45:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:45:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:45:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:45:54 INFO - 2017-04-12 17:45:54.844 screenresolution[30565:274907] starting screenresolution argv=screenresolution get 17:45:54 INFO - 2017-04-12 17:45:54.846 screenresolution[30565:274907] Display 0: 1600x1200x32@60 17:45:54 INFO - 2017-04-12 17:45:54.852 screenresolution[30566:274910] starting screenresolution argv=screenresolution list 17:45:54 INFO - Available Modes on Display 0 17:45:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:45:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:45:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:45:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:45:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:45:55 INFO - Intel Iris: 17:45:55 INFO - Chipset Model: Intel Iris 17:45:55 INFO - Type: GPU 17:45:55 INFO - Bus: Built-In 17:45:55 INFO - VRAM (Dynamic, Max): 1536 MB 17:45:55 INFO - Vendor: Intel (0x8086) 17:45:55 INFO - Device ID: 0x0a2e 17:45:55 INFO - Revision ID: 0x0009 17:45:55 INFO - Displays: 17:45:55 INFO - Display: 17:45:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:45:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:45:55 INFO - Display Serial Number: 1600x1200 60 17:45:55 INFO - Main Display: Yes 17:45:55 INFO - Mirror: Off 17:45:55 INFO - Online: Yes 17:45:55 INFO - Rotation: Supported 17:45:55 INFO - Return code: 0 17:45:55 INFO - Running post-action listener: _resource_record_post_action 17:45:55 INFO - [mozharness: 2017-04-13 00:45:55.106576Z] Finished run-tests step (success) 17:45:55 INFO - Running post-run listener: _resource_record_post_run 17:45:55 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 614400; Write bytes: 517731328; Read time: 6; Write time: 1604 17:45:55 INFO - TinderboxPrint: CPU usage
54.1% 17:45:55 INFO - TinderboxPrint: I/O read bytes / time
614,400 / 6 17:45:55 INFO - TinderboxPrint: I/O write bytes / time
517,731,328 / 1,604 17:45:55 INFO - TinderboxPrint: CPU idle
2,204.7 (45.9%) 17:45:55 INFO - TinderboxPrint: CPU system
964.2 (20.1%) 17:45:55 INFO - TinderboxPrint: CPU user
1,632.7 (34.0%) 17:45:55 INFO - TinderboxPrint: Swap in / out
207,896,576 / 0 17:45:55 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 194124288; Write bytes: 195274752; Read time: 14896; Write time: 396 17:45:55 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 614400; Write bytes: 305609728; Read time: 6; Write time: 1155 17:45:55 INFO - Running post-run listener: _upload_blobber_files 17:45:55 INFO - Blob upload gear active. 17:45:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:45:55 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 17:45:55 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'] 17:45:55 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 17:45:55 INFO - (blobuploader) - INFO - Open directory for files ... 17:45:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:45:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:45:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:45:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:45:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:45:56 INFO - (blobuploader) - INFO - Done attempting. 17:45:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:45:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:45:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:45:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:45:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:45:57 INFO - (blobuploader) - INFO - Done attempting. 17:45:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:45:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:45:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:45:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:45:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:45:58 INFO - (blobuploader) - INFO - Done attempting. 17:45:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:45:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:45:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:46:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:46:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:46:00 INFO - (blobuploader) - INFO - Done attempting. 17:46:00 INFO - (blobuploader) - INFO - Iteration through files over. 17:46:00 INFO - Return code: 0 17:46:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:46:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:46:00 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/30621f1be69c79f70424b2fa4dd2b11fd6d6fd7eb6594df5b08f7341b12d5ddbe511023d29a5af90b6fa3b2fb5796ea765bee213e0ba87dfb6afffa8ffe0169d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/f292750efe5842832c28b5799a2fbeabc79962aace1c5f7dd0fa7788d5ed81c9589e0a224a60d2d465376d34ee6cf9435b4948fe616a7003cdb4a73e79271087", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/54f7fc79cf1ce7601635e637ac617e51999a740908c3daea0a5fbafbf4028dce73ac3a0bc9631d078b112e5145caf9e40f474be5b6c604253897ddc3bbb06b2e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ee5dbf20c485e8f1f5aa749c7ecf6139009f9825cebe51fa16324eac5b1d69f70264315f0a8df16279433a69afaa8e199093221a6a073319063b2d2ca82b5cf1"} 17:46:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:46:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:46:00 INFO - Contents: 17:46:00 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/30621f1be69c79f70424b2fa4dd2b11fd6d6fd7eb6594df5b08f7341b12d5ddbe511023d29a5af90b6fa3b2fb5796ea765bee213e0ba87dfb6afffa8ffe0169d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/f292750efe5842832c28b5799a2fbeabc79962aace1c5f7dd0fa7788d5ed81c9589e0a224a60d2d465376d34ee6cf9435b4948fe616a7003cdb4a73e79271087", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/54f7fc79cf1ce7601635e637ac617e51999a740908c3daea0a5fbafbf4028dce73ac3a0bc9631d078b112e5145caf9e40f474be5b6c604253897ddc3bbb06b2e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ee5dbf20c485e8f1f5aa749c7ecf6139009f9825cebe51fa16324eac5b1d69f70264315f0a8df16279433a69afaa8e199093221a6a073319063b2d2ca82b5cf1"} 17:46:00 INFO - Running post-run listener: copy_logs_to_upload_dir 17:46:00 INFO - Copying logs to upload dir... 17:46:00 INFO - mkdir: /builds/slave/test/build/upload/logs 17:46:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.748890 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-04-12 17:46:00.855032) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-12 17:46:00.858095) ========= 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.JynS31bETa/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.0TtkLHU9qy/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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/30621f1be69c79f70424b2fa4dd2b11fd6d6fd7eb6594df5b08f7341b12d5ddbe511023d29a5af90b6fa3b2fb5796ea765bee213e0ba87dfb6afffa8ffe0169d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/f292750efe5842832c28b5799a2fbeabc79962aace1c5f7dd0fa7788d5ed81c9589e0a224a60d2d465376d34ee6cf9435b4948fe616a7003cdb4a73e79271087", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/54f7fc79cf1ce7601635e637ac617e51999a740908c3daea0a5fbafbf4028dce73ac3a0bc9631d078b112e5145caf9e40f474be5b6c604253897ddc3bbb06b2e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ee5dbf20c485e8f1f5aa749c7ecf6139009f9825cebe51fa16324eac5b1d69f70264315f0a8df16279433a69afaa8e199093221a6a073319063b2d2ca82b5cf1"} build_url:https://queue.taskcluster.net/v1/task/RHDIZci0TiS2HlTXaAyJFA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011236 build_url: 'https://queue.taskcluster.net/v1/task/RHDIZci0TiS2HlTXaAyJFA/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/30621f1be69c79f70424b2fa4dd2b11fd6d6fd7eb6594df5b08f7341b12d5ddbe511023d29a5af90b6fa3b2fb5796ea765bee213e0ba87dfb6afffa8ffe0169d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/f292750efe5842832c28b5799a2fbeabc79962aace1c5f7dd0fa7788d5ed81c9589e0a224a60d2d465376d34ee6cf9435b4948fe616a7003cdb4a73e79271087", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/54f7fc79cf1ce7601635e637ac617e51999a740908c3daea0a5fbafbf4028dce73ac3a0bc9631d078b112e5145caf9e40f474be5b6c604253897ddc3bbb06b2e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ee5dbf20c485e8f1f5aa749c7ecf6139009f9825cebe51fa16324eac5b1d69f70264315f0a8df16279433a69afaa8e199093221a6a073319063b2d2ca82b5cf1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-12 17:46:00.893238) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 17:46:00.893595) ========= 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.JynS31bETa/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.0TtkLHU9qy/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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.005378 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-12 17:46:00.942724) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-12 17:46:00.943120) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-12 17:46:00.943455) ========= ========= Total master_lag: 0.31 =========