builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0342 starttime: 1485328198.88 results: success (0) buildid: 20170124203147 builduid: 41359cb8c3c748f6a8ed1813e71ab55f revision: 50cb9a3e39ac70489ee7c42ac17e7baf7b209a43 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-24 23:09:58.878832) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-24 23:09:58.879274) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-24 23:09:58.879883) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-24 23:09:58.913733) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-24 23:09:58.914024) ========= 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.KUkQfECKPO/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.X86v054kIY/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-24 23:09:58-- 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% 12.0M=0.001s 2017-01-24 23:09:59 (12.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.104400 ========= master_lag: 0.23 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-24 23:09:59.251113) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-24 23:09:59.251395) ========= 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.KUkQfECKPO/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.X86v054kIY/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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.020159 ========= master_lag: 0.68 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-24 23:09:59.951720) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-24 23:09:59.952031) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 50cb9a3e39ac70489ee7c42ac17e7baf7b209a43 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 50cb9a3e39ac70489ee7c42ac17e7baf7b209a43 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KUkQfECKPO/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.X86v054kIY/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-24 23:10:00,045 truncating revision to first 12 chars 2017-01-24 23:10:00,045 Setting DEBUG logging. 2017-01-24 23:10:00,045 attempt 1/10 2017-01-24 23:10:00,045 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/50cb9a3e39ac?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-24 23:10:00,835 unpacking tar archive at: graphics-50cb9a3e39ac/testing/mozharness/ program finished with exit code 0 elapsedTime=1.073232 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-24 23:10:01.049353) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-24 23:10:01.049730) ========= script_repo_revision: 50cb9a3e39ac70489ee7c42ac17e7baf7b209a43 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-24 23:10:01.050172) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-24 23:10:01.050447) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-24 23:10:01.070129) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-01-24 23:10:01.070726) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KUkQfECKPO/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.X86v054kIY/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 23:10:01 INFO - MultiFileLogger online at 20170124 23:10:01 in /builds/slave/test 23:10:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 23:10:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:10:01 INFO - {'append_to_log': False, 23:10:01 INFO - 'base_work_dir': '/builds/slave/test', 23:10:01 INFO - 'blob_upload_branch': 'Graphics', 23:10:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:10:01 INFO - 'branch': 'Graphics', 23:10:01 INFO - 'buildbot_json_path': 'buildprops.json', 23:10:01 INFO - 'config_files': ('talos/mac_config.py',), 23:10:01 INFO - 'default_actions': ('clobber', 23:10:01 INFO - 'read-buildbot-config', 23:10:01 INFO - 'download-and-extract', 23:10:01 INFO - 'populate-webroot', 23:10:01 INFO - 'create-virtualenv', 23:10:01 INFO - 'install', 23:10:01 INFO - 'run-tests'), 23:10:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:10:01 INFO - 'download_minidump_stackwalk': True, 23:10:01 INFO - 'download_symbols': 'ondemand', 23:10:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:10:01 INFO - 'tooltool.py': '/tools/tooltool.py', 23:10:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:10:01 INFO - '/tools/misc-python/virtualenv.py')}, 23:10:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:10:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:10:01 INFO - 'installer_path': 'installer.exe', 23:10:01 INFO - 'log_level': 'info', 23:10:01 INFO - 'log_name': 'talos', 23:10:01 INFO - 'log_to_console': True, 23:10:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:10:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:10:01 INFO - 'opt_config_files': (), 23:10:01 INFO - 'pip_index': False, 23:10:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:10:01 INFO - 'cmd': ('bash', 23:10:01 INFO - '-c', 23:10:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:10:01 INFO - 'enabled': True, 23:10:01 INFO - 'halt_on_failure': False, 23:10:01 INFO - 'name': 'check_screen_resolution'},), 23:10:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:10:01 INFO - 'cmd': ('bash', 23:10:01 INFO - '-c', 23:10:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:10:01 INFO - 'enabled': True, 23:10:01 INFO - 'halt_on_failure': False, 23:10:01 INFO - 'name': 'check_screen_resolution'},), 23:10:01 INFO - 'run_cmd_checks_enabled': True, 23:10:01 INFO - 'sps_profile': False, 23:10:01 INFO - 'sps_profile_interval': 0, 23:10:01 INFO - 'suite': 'dromaeojs-e10s', 23:10:01 INFO - 'system_bits': '32', 23:10:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:10:01 INFO - 'title': 't-yosemite-r7-0342', 23:10:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:10:01 INFO - 'use_talos_json': True, 23:10:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:10:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:10:01 INFO - 'work_dir': 'build'} 23:10:01 INFO - [mozharness: 2017-01-25 07:10:01.280319Z] Running clobber step. 23:10:01 INFO - Running pre-action listener: _resource_record_pre_action 23:10:01 INFO - Running main action method: clobber 23:10:01 INFO - rmtree: /builds/slave/test/build 23:10:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:10:03 INFO - Running post-action listener: _resource_record_post_action 23:10:03 INFO - [mozharness: 2017-01-25 07:10:03.300383Z] Finished clobber step (success) 23:10:03 INFO - [mozharness: 2017-01-25 07:10:03.300565Z] Running read-buildbot-config step. 23:10:03 INFO - Running pre-action listener: _resource_record_pre_action 23:10:03 INFO - Running main action method: read_buildbot_config 23:10:03 INFO - Using buildbot properties: 23:10:03 INFO - { 23:10:03 INFO - "project": "", 23:10:03 INFO - "product": "firefox", 23:10:03 INFO - "script_repo_revision": "default", 23:10:03 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 23:10:03 INFO - "repository": "", 23:10:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 23:10:03 INFO - "buildid": "20170124203147", 23:10:03 INFO - "pgo_build": "False", 23:10:03 INFO - "basedir": "/builds/slave/test", 23:10:03 INFO - "buildnumber": 10, 23:10:03 INFO - "slavename": "t-yosemite-r7-0342", 23:10:03 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 23:10:03 INFO - "platform": "yosemite_r7", 23:10:03 INFO - "scheduler": "tests-graphics-macosx64-talos-8-3600", 23:10:03 INFO - "branch": "graphics", 23:10:03 INFO - "repo_path": "projects/graphics", 23:10:03 INFO - "revision": "50cb9a3e39ac70489ee7c42ac17e7baf7b209a43", 23:10:03 INFO - "stage_platform": "macosx64", 23:10:03 INFO - "builduid": "41359cb8c3c748f6a8ed1813e71ab55f", 23:10:03 INFO - "slavebuilddir": "test" 23:10:03 INFO - } 23:10:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/T3fyx_EvSEiVTFm-cmPg3w/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 23:10:03 INFO - Running post-action listener: _resource_record_post_action 23:10:03 INFO - [mozharness: 2017-01-25 07:10:03.303989Z] Finished read-buildbot-config step (success) 23:10:03 INFO - [mozharness: 2017-01-25 07:10:03.304120Z] Running download-and-extract step. 23:10:03 INFO - Running pre-action listener: _resource_record_pre_action 23:10:03 INFO - Running main action method: download_and_extract 23:10:03 INFO - mkdir: /builds/slave/test/build/tests 23:10:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:10:03 INFO - trying https://queue.taskcluster.net/v1/task/T3fyx_EvSEiVTFm-cmPg3w/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 23:10:03 INFO - Downloading https://queue.taskcluster.net/v1/task/T3fyx_EvSEiVTFm-cmPg3w/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 23:10:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T3fyx_EvSEiVTFm-cmPg3w/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 23:10:04 INFO - Downloaded 1199 bytes. 23:10:04 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 23:10:04 INFO - Using the following test package requirements: 23:10:04 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 23:10:04 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 23:10:04 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 23:10:04 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 23:10:04 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 23:10:04 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 23:10:04 INFO - u'jsshell-mac.zip'], 23:10:04 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 23:10:04 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 23:10:04 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 23:10:04 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 23:10:04 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 23:10:04 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 23:10:04 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 23:10:04 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 23:10:04 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 23:10:04 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 23:10:04 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 23:10:04 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 23:10:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T3fyx_EvSEiVTFm-cmPg3w/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 23:10:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T3fyx_EvSEiVTFm-cmPg3w/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:10:04 INFO - Fetch https://queue.taskcluster.net/v1/task/T3fyx_EvSEiVTFm-cmPg3w/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 23:10:05 INFO - Expected file size: 16351048 23:10:05 INFO - Obtained file size: 16351048 23:10:11 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 23:10:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T3fyx_EvSEiVTFm-cmPg3w/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 23:10:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T3fyx_EvSEiVTFm-cmPg3w/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 23:10:11 INFO - Fetch https://queue.taskcluster.net/v1/task/T3fyx_EvSEiVTFm-cmPg3w/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 23:10:12 INFO - Expected file size: 16351048 23:10:12 INFO - Obtained file size: 16351048 23:10:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T3fyx_EvSEiVTFm-cmPg3w/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 23:10:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T3fyx_EvSEiVTFm-cmPg3w/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 23:10:19 INFO - Fetch https://queue.taskcluster.net/v1/task/T3fyx_EvSEiVTFm-cmPg3w/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 23:10:19 INFO - Expected file size: 14403485 23:10:19 INFO - Obtained file size: 14403485 23:10:20 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')]} 23:10:20 INFO - trying https://queue.taskcluster.net/v1/task/T3fyx_EvSEiVTFm-cmPg3w/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 23:10:20 INFO - Downloading https://queue.taskcluster.net/v1/task/T3fyx_EvSEiVTFm-cmPg3w/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 23:10:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T3fyx_EvSEiVTFm-cmPg3w/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 23:10:22 INFO - Downloaded 70111772 bytes. 23:10:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/T3fyx_EvSEiVTFm-cmPg3w/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 23:10:22 INFO - mkdir: /builds/slave/test/properties 23:10:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:10:22 INFO - Writing to file /builds/slave/test/properties/build_url 23:10:22 INFO - Contents: 23:10:22 INFO - build_url:https://queue.taskcluster.net/v1/task/T3fyx_EvSEiVTFm-cmPg3w/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 23:10:22 INFO - Running post-action listener: _resource_record_post_action 23:10:22 INFO - Running post-action listener: set_extra_try_arguments 23:10:22 INFO - [mozharness: 2017-01-25 07:10:22.872516Z] Finished download-and-extract step (success) 23:10:22 INFO - [mozharness: 2017-01-25 07:10:22.872632Z] Running populate-webroot step. 23:10:22 INFO - Running pre-action listener: _resource_record_pre_action 23:10:22 INFO - Running main action method: populate_webroot 23:10:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:10:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:10:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:10:22 INFO - '--tppagecycles', 23:10:22 INFO - '7'], 23:10:22 INFO - 'tests': ['tsvgm']}}, 23:10:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:10:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:10:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:10:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:10:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:10:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:10:22 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 23:10:22 INFO - 'talos_options': ['--disable-e10s'], 23:10:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:10:22 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 23:10:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:10:22 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 23:10:22 INFO - 'talos_options': ['--disable-e10s'], 23:10:22 INFO - 'tests': ['damp', 'tps']}, 23:10:22 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 23:10:22 INFO - 'tests': ['damp', 'tps']}, 23:10:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:10:22 INFO - 'tests': ['dromaeo_dom']}, 23:10:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:10:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:10:22 INFO - 'tests': ['basic_compositor_video']}, 23:10:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:10:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:10:22 INFO - 'tests': ['a11yr', 23:10:22 INFO - 'ts_paint', 23:10:22 INFO - 'tpaint', 23:10:22 INFO - 'sessionrestore', 23:10:22 INFO - 'sessionrestore_no_auto_restore', 23:10:22 INFO - 'tabpaint']}, 23:10:22 INFO - 'other-e10s': {'tests': ['a11yr', 23:10:22 INFO - 'ts_paint', 23:10:22 INFO - 'tpaint', 23:10:22 INFO - 'sessionrestore', 23:10:22 INFO - 'sessionrestore_no_auto_restore', 23:10:22 INFO - 'tabpaint']}, 23:10:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:10:22 INFO - 'ts_paint', 23:10:22 INFO - 'tpaint', 23:10:22 INFO - 'sessionrestore', 23:10:22 INFO - 'sessionrestore_no_auto_restore', 23:10:22 INFO - 'tabpaint']}, 23:10:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:10:22 INFO - 'ts_paint', 23:10:22 INFO - 'tpaint', 23:10:22 INFO - 'sessionrestore', 23:10:22 INFO - 'sessionrestore_no_auto_restore', 23:10:22 INFO - 'tabpaint']}, 23:10:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:10:22 INFO - 'tests': ['a11yr', 23:10:22 INFO - 'ts_paint', 23:10:22 INFO - 'tpaint', 23:10:22 INFO - 'sessionrestore', 23:10:22 INFO - 'sessionrestore_no_auto_restore', 23:10:22 INFO - 'tabpaint']}, 23:10:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:10:22 INFO - 'tests': ['a11yr', 23:10:22 INFO - 'ts_paint', 23:10:22 INFO - 'tpaint', 23:10:22 INFO - 'sessionrestore', 23:10:22 INFO - 'sessionrestore_no_auto_restore', 23:10:22 INFO - 'tabpaint']}, 23:10:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:10:22 INFO - 'tests': ['tsvgx', 23:10:22 INFO - 'tsvgr_opacity', 23:10:22 INFO - 'tart', 23:10:22 INFO - 'tscrollx', 23:10:22 INFO - 'cart', 23:10:22 INFO - 'tsvg_static']}, 23:10:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:10:22 INFO - 'tsvgr_opacity', 23:10:22 INFO - 'tart', 23:10:22 INFO - 'tscrollx', 23:10:22 INFO - 'cart', 23:10:22 INFO - 'tsvg_static']}, 23:10:22 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 23:10:22 INFO - 'talos_options': ['--disable-e10s'], 23:10:22 INFO - 'tests': ['tp5o']}, 23:10:22 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 23:10:22 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 23:10:22 INFO - 'talos_options': ['--disable-e10s', 23:10:22 INFO - '--xperf_path', 23:10:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:10:22 INFO - 'tests': ['tp5n']}, 23:10:22 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 23:10:22 INFO - 'talos_options': ['--xperf_path', 23:10:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:10:22 INFO - 'tests': ['tp5n']}}, 23:10:22 INFO - 'talos.zip': {'path': '', 23:10:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:10:22 INFO - Running post-action listener: _resource_record_post_action 23:10:22 INFO - [mozharness: 2017-01-25 07:10:22.891023Z] Finished populate-webroot step (success) 23:10:22 INFO - [mozharness: 2017-01-25 07:10:22.891149Z] Running create-virtualenv step. 23:10:22 INFO - Running pre-action listener: _resource_record_pre_action 23:10:22 INFO - Running main action method: create_virtualenv 23:10:22 INFO - Creating virtualenv /builds/slave/test/build/venv 23:10:22 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 23:10:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:10:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:10:23 INFO - Using real prefix '/tools/python27' 23:10:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:10:23 INFO - Installing distribute.............................................................................................................................................................................................done. 23:10:26 INFO - Installing pip.................done. 23:10:26 INFO - Return code: 0 23:10:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 23:10:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 23:10:26 INFO - Reading from file tmpfile_stdout 23:10:26 INFO - Output received: 23:10:26 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) 23:10:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:10:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:10:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:10:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:10:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:10:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:10:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103169b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10316c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1a9cc4580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f47d30>, '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 0x102e6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f3cbe8>, '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.KUkQfECKPO/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.X86v054kIY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 23:10:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:10:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:10:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KUkQfECKPO/Render', 23:10:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:10:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:10:27 INFO - 'HOME': '/Users/cltbld', 23:10:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:10:27 INFO - 'LOGNAME': 'cltbld', 23:10:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:10:27 INFO - 'MOZ_NO_REMOTE': '1', 23:10:27 INFO - 'NO_EM_RESTART': '1', 23:10:27 INFO - 'PAGER': '/bin/cat', 23:10:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:10:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:10:27 INFO - 'PWD': '/builds/slave/test', 23:10:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:10:27 INFO - 'SHELL': '/bin/bash', 23:10:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X86v054kIY/Listeners', 23:10:27 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 23:10:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:10:27 INFO - 'USER': 'cltbld', 23:10:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:10:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:10:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:10:27 INFO - 'XPC_FLAGS': '0x0', 23:10:27 INFO - 'XPC_SERVICE_NAME': '0', 23:10:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:10:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:10:27 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 23:10:27 INFO - Cleaning up... 23:10:27 INFO - Return code: 0 23:10:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:10:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:10:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:10:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:10:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:10:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:10:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:10:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103169b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10316c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1a9cc4580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f47d30>, '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 0x102e6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f3cbe8>, '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.KUkQfECKPO/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.X86v054kIY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 23:10:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:10:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:10:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KUkQfECKPO/Render', 23:10:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:10:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:10:27 INFO - 'HOME': '/Users/cltbld', 23:10:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:10:27 INFO - 'LOGNAME': 'cltbld', 23:10:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:10:27 INFO - 'MOZ_NO_REMOTE': '1', 23:10:27 INFO - 'NO_EM_RESTART': '1', 23:10:27 INFO - 'PAGER': '/bin/cat', 23:10:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:10:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:10:27 INFO - 'PWD': '/builds/slave/test', 23:10:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:10:27 INFO - 'SHELL': '/bin/bash', 23:10:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X86v054kIY/Listeners', 23:10:27 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 23:10:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:10:27 INFO - 'USER': 'cltbld', 23:10:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:10:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:10:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:10:27 INFO - 'XPC_FLAGS': '0x0', 23:10:27 INFO - 'XPC_SERVICE_NAME': '0', 23:10:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:10:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:10:27 INFO - Downloading/unpacking psutil>=3.1.1 23:10:27 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 23:10:27 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 23:10:27 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 23:10:27 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 23:10:27 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 23:10:27 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 23:10:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:10:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:10:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:10:30 INFO - Installing collected packages: psutil 23:10:30 INFO - Running setup.py install for psutil 23:10:31 INFO - building 'psutil._psutil_osx' extension 23:10:31 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 23:10:31 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 23:10:31 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 23:10:31 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 23:10:31 INFO - building 'psutil._psutil_posix' extension 23:10:31 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 23:10:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:10:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:10:31 INFO - ^ 23:10:31 INFO - 1 warning generated. 23:10:31 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 23:10:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:10:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:10:31 INFO - Successfully installed psutil 23:10:31 INFO - Cleaning up... 23:10:31 INFO - Return code: 0 23:10:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:10:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:10:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:10:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:10:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:10:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:10:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:10:31 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 0x103169b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10316c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1a9cc4580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f47d30>, '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 0x102e6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f3cbe8>, '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.KUkQfECKPO/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.X86v054kIY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 23:10:31 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 23:10:31 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 23:10:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KUkQfECKPO/Render', 23:10:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:10:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:10:31 INFO - 'HOME': '/Users/cltbld', 23:10:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:10:31 INFO - 'LOGNAME': 'cltbld', 23:10:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:10:31 INFO - 'MOZ_NO_REMOTE': '1', 23:10:31 INFO - 'NO_EM_RESTART': '1', 23:10:31 INFO - 'PAGER': '/bin/cat', 23:10:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:10:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:10:31 INFO - 'PWD': '/builds/slave/test', 23:10:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:10:31 INFO - 'SHELL': '/bin/bash', 23:10:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X86v054kIY/Listeners', 23:10:31 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 23:10:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:10:31 INFO - 'USER': 'cltbld', 23:10:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:10:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:10:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:10:31 INFO - 'XPC_FLAGS': '0x0', 23:10:31 INFO - 'XPC_SERVICE_NAME': '0', 23:10:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:10:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:10:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:10:32 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 23:10:32 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 23:10:32 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 23:10:32 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 23:10:32 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 23:10:32 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 23:10:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:10:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:10:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:10:34 INFO - Installing collected packages: mozsystemmonitor 23:10:34 INFO - Running setup.py install for mozsystemmonitor 23:10:34 INFO - Successfully installed mozsystemmonitor 23:10:34 INFO - Cleaning up... 23:10:34 INFO - Return code: 0 23:10:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:10:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:10:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:10:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:10:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:10:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:10:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:10:35 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 0x103169b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10316c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1a9cc4580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f47d30>, '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 0x102e6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f3cbe8>, '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.KUkQfECKPO/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.X86v054kIY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 23:10:35 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 23:10:35 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 23:10:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KUkQfECKPO/Render', 23:10:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:10:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:10:35 INFO - 'HOME': '/Users/cltbld', 23:10:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:10:35 INFO - 'LOGNAME': 'cltbld', 23:10:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:10:35 INFO - 'MOZ_NO_REMOTE': '1', 23:10:35 INFO - 'NO_EM_RESTART': '1', 23:10:35 INFO - 'PAGER': '/bin/cat', 23:10:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:10:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:10:35 INFO - 'PWD': '/builds/slave/test', 23:10:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:10:35 INFO - 'SHELL': '/bin/bash', 23:10:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X86v054kIY/Listeners', 23:10:35 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 23:10:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:10:35 INFO - 'USER': 'cltbld', 23:10:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:10:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:10:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:10:35 INFO - 'XPC_FLAGS': '0x0', 23:10:35 INFO - 'XPC_SERVICE_NAME': '0', 23:10:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:10:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:10:35 INFO - Downloading/unpacking jsonschema==2.5.1 23:10:35 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 23:10:35 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 23:10:35 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 23:10:35 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 23:10:35 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 23:10:35 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 23:10:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:10:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:10:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:10:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:10:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:10:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:10:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:10:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:10:38 INFO - Downloading functools32-3.2.3-2.tar.gz 23:10:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:10:38 INFO - warning: no files found matching '*.txt' 23:10:38 INFO - no previously-included directories found matching 'build' 23:10:38 INFO - no previously-included directories found matching 'dist' 23:10:38 INFO - no previously-included directories found matching '.git*' 23:10:38 INFO - Installing collected packages: jsonschema, functools32 23:10:38 INFO - Running setup.py install for functools32 23:10:38 INFO - warning: no files found matching '*.txt' 23:10:38 INFO - no previously-included directories found matching 'build' 23:10:38 INFO - no previously-included directories found matching 'dist' 23:10:38 INFO - no previously-included directories found matching '.git*' 23:10:38 INFO - Successfully installed jsonschema functools32 23:10:38 INFO - Cleaning up... 23:10:38 INFO - Return code: 0 23:10:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:10:38 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')]} 23:10:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:10:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:10:38 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')]} 23:10:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:10:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:10:38 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 0x103169b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10316c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1a9cc4580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f47d30>, '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 0x102e6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f3cbe8>, '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.KUkQfECKPO/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.X86v054kIY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 23:10:38 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 23:10:38 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 23:10:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KUkQfECKPO/Render', 23:10:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:10:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:10:38 INFO - 'HOME': '/Users/cltbld', 23:10:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:10:38 INFO - 'LOGNAME': 'cltbld', 23:10:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:10:38 INFO - 'MOZ_NO_REMOTE': '1', 23:10:38 INFO - 'NO_EM_RESTART': '1', 23:10:38 INFO - 'PAGER': '/bin/cat', 23:10:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:10:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:10:38 INFO - 'PWD': '/builds/slave/test', 23:10:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:10:38 INFO - 'SHELL': '/bin/bash', 23:10:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X86v054kIY/Listeners', 23:10:38 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 23:10:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:10:38 INFO - 'USER': 'cltbld', 23:10:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:10:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:10:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:10:38 INFO - 'XPC_FLAGS': '0x0', 23:10:38 INFO - 'XPC_SERVICE_NAME': '0', 23:10:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:10:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:10:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:10:38 INFO - Cleaning up... 23:10:38 INFO - Return code: 0 23:10:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:10:38 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')]} 23:10:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:10:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:10:38 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')]} 23:10:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:10:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:10:38 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 0x103169b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10316c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1a9cc4580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f47d30>, '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 0x102e6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f3cbe8>, '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.KUkQfECKPO/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.X86v054kIY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 23:10:38 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 23:10:38 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 23:10:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KUkQfECKPO/Render', 23:10:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:10:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:10:38 INFO - 'HOME': '/Users/cltbld', 23:10:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:10:38 INFO - 'LOGNAME': 'cltbld', 23:10:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:10:38 INFO - 'MOZ_NO_REMOTE': '1', 23:10:38 INFO - 'NO_EM_RESTART': '1', 23:10:38 INFO - 'PAGER': '/bin/cat', 23:10:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:10:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:10:38 INFO - 'PWD': '/builds/slave/test', 23:10:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:10:38 INFO - 'SHELL': '/bin/bash', 23:10:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X86v054kIY/Listeners', 23:10:38 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 23:10:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:10:38 INFO - 'USER': 'cltbld', 23:10:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:10:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:10:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:10:38 INFO - 'XPC_FLAGS': '0x0', 23:10:38 INFO - 'XPC_SERVICE_NAME': '0', 23:10:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:10:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:10:39 INFO - Downloading/unpacking blobuploader==1.2.4 23:10:39 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 23:10:39 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 23:10:39 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 23:10:39 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 23:10:39 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 23:10:39 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 23:10:41 INFO - Downloading blobuploader-1.2.4.tar.gz 23:10:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:10:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:10: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 23:10: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 23:10: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 23:10: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 23:10: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 23:10: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 23:10:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:10:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:10:42 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 23:10:42 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 23:10:42 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 23:10:42 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 23:10:42 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 23:10:42 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 23:10:43 INFO - Downloading docopt-0.6.1.tar.gz 23:10:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:10:43 INFO - Installing collected packages: blobuploader, requests, docopt 23:10:43 INFO - Running setup.py install for blobuploader 23:10:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:10:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:10:43 INFO - Running setup.py install for requests 23:10:43 INFO - Running setup.py install for docopt 23:10:43 INFO - Successfully installed blobuploader requests docopt 23:10:43 INFO - Cleaning up... 23:10:44 INFO - Return code: 0 23:10:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:10:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:10:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:10:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:10:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:10:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:10:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:10:44 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 0x103169b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10316c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1a9cc4580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f47d30>, '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 0x102e6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f3cbe8>, '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.KUkQfECKPO/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.X86v054kIY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 23:10:44 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 23:10:44 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 23:10:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KUkQfECKPO/Render', 23:10:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:10:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:10:44 INFO - 'HOME': '/Users/cltbld', 23:10:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:10:44 INFO - 'LOGNAME': 'cltbld', 23:10:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:10:44 INFO - 'MOZ_NO_REMOTE': '1', 23:10:44 INFO - 'NO_EM_RESTART': '1', 23:10:44 INFO - 'PAGER': '/bin/cat', 23:10:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:10:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:10:44 INFO - 'PWD': '/builds/slave/test', 23:10:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:10:44 INFO - 'SHELL': '/bin/bash', 23:10:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X86v054kIY/Listeners', 23:10:44 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 23:10:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:10:44 INFO - 'USER': 'cltbld', 23:10:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:10:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:10:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:10:44 INFO - 'XPC_FLAGS': '0x0', 23:10:44 INFO - 'XPC_SERVICE_NAME': '0', 23:10:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:10:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:10:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:10:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-1OSwbD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:10:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:10:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-MwVOk2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:10:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:10:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-UWBgXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:10:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:10:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-mdgATy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:10:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:10:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-RdKHVb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:10:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:10:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-v_5G24-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:10:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:10:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-NUhPgz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:10:45 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-fkMvie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:10:45 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-5YcQ7I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:10:45 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-c8zgKw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:10:45 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-pcomma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:10:45 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-C3Re4p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:10:45 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-YQwPsJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:10:45 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-Q5QImA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:10:45 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-j1fNiH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:10:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:10:45 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-2eUTIp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:10:46 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-og0gKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:10:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:10:46 INFO - Running setup.py install for manifestparser 23:10:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:10:46 INFO - Running setup.py install for mozcrash 23:10:46 INFO - Running setup.py install for mozdebug 23:10:46 INFO - Running setup.py install for mozdevice 23:10:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:10:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:10:46 INFO - Running setup.py install for mozfile 23:10:46 INFO - Running setup.py install for mozhttpd 23:10:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:10:47 INFO - Running setup.py install for mozinfo 23:10:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:10:47 INFO - Running setup.py install for mozInstall 23:10:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:10:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:10:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:10:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:10:47 INFO - Running setup.py install for mozleak 23:10:47 INFO - Running setup.py install for mozlog 23:10:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:10:47 INFO - Running setup.py install for moznetwork 23:10:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:10:47 INFO - Running setup.py install for mozprocess 23:10:47 INFO - Running setup.py install for mozprofile 23:10:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:10:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:10:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:10:48 INFO - Running setup.py install for mozrunner 23:10:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:10:48 INFO - Running setup.py install for mozscreenshot 23:10:48 INFO - Running setup.py install for moztest 23:10:48 INFO - Running setup.py install for mozversion 23:10:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:10:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:10:48 INFO - Cleaning up... 23:10:48 INFO - Return code: 0 23:10:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:10:48 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')]} 23:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:10:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:10:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:10:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:10:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:10:49 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 0x103169b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10316c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1a9cc4580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f47d30>, '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 0x102e6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f3cbe8>, '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.KUkQfECKPO/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.X86v054kIY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 23:10:49 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 23:10:49 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 23:10:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KUkQfECKPO/Render', 23:10:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:10:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:10:49 INFO - 'HOME': '/Users/cltbld', 23:10:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:10:49 INFO - 'LOGNAME': 'cltbld', 23:10:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:10:49 INFO - 'MOZ_NO_REMOTE': '1', 23:10:49 INFO - 'NO_EM_RESTART': '1', 23:10:49 INFO - 'PAGER': '/bin/cat', 23:10:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:10:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:10:49 INFO - 'PWD': '/builds/slave/test', 23:10:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:10:49 INFO - 'SHELL': '/bin/bash', 23:10:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X86v054kIY/Listeners', 23:10:49 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 23:10:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:10:49 INFO - 'USER': 'cltbld', 23:10:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:10:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:10:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:10:49 INFO - 'XPC_FLAGS': '0x0', 23:10:49 INFO - 'XPC_SERVICE_NAME': '0', 23:10:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:10:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:10:49 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-gcXcOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:10:49 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)) 23:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:10:49 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-7fzFBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:10:50 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)) 23:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:10:50 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-RId3uo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:10:50 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)) 23:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:10:50 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-u5XhJF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 23:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:10:50 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-0R9dX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:10:50 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)) 23:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:10:50 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-2ITbTz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:10:50 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)) 23:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:10:50 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-2MAztU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:10:50 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)) 23:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:10:50 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-01Egsn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:10:50 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)) 23:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:10:50 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-uD3NVg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:10:50 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)) 23:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:10:50 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-TNzjKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:10:50 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)) 23:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:10:50 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-B1_6Az-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:10:50 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)) 23:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:10:51 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-UwnoMs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:10:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 23:10:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:10:51 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-DrbIow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:10:51 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)) 23:10:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:10:51 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-T9CVq8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:10:51 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)) 23:10:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:10:51 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-xSmHpc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:10:51 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)) 23:10:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:10:51 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-5L091g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:10:51 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)) 23:10:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:10:51 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-LfoMZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:10:51 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)) 23:10:51 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)) 23:10:51 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)) 23:10:51 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)) 23:10:51 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:10:51 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:10:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:10:51 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 23:10:51 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 23:10:51 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 23:10:51 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 23:10:51 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 23:10:51 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 23:10:54 INFO - Downloading blessings-1.6.tar.gz 23:10:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:10:54 INFO - Installing collected packages: blessings 23:10:54 INFO - Running setup.py install for blessings 23:10:54 INFO - Successfully installed blessings 23:10:54 INFO - Cleaning up... 23:10:54 INFO - Return code: 0 23:10:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:10:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:10:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:10:54 INFO - Reading from file tmpfile_stdout 23:10:54 INFO - Current package versions: 23:10:54 INFO - blessings == 1.6 23:10:54 INFO - blobuploader == 1.2.4 23:10:54 INFO - docopt == 0.6.1 23:10:54 INFO - functools32 == 3.2.3-2 23:10:54 INFO - jsonschema == 2.5.1 23:10:54 INFO - manifestparser == 1.1 23:10:54 INFO - mozInstall == 1.12 23:10:54 INFO - mozcrash == 1.0 23:10:54 INFO - mozdebug == 0.1 23:10:54 INFO - mozdevice == 0.49 23:10:54 INFO - mozfile == 1.2 23:10:54 INFO - mozhttpd == 0.7 23:10:54 INFO - mozinfo == 0.9 23:10:54 INFO - mozleak == 0.1 23:10:54 INFO - mozlog == 3.4 23:10:54 INFO - moznetwork == 0.27 23:10:54 INFO - mozprocess == 0.24 23:10:54 INFO - mozprofile == 0.28 23:10:54 INFO - mozrunner == 6.13 23:10:54 INFO - mozscreenshot == 0.1 23:10:54 INFO - mozsystemmonitor == 0.3 23:10:54 INFO - moztest == 0.8 23:10:54 INFO - mozversion == 1.4 23:10:54 INFO - psutil == 3.1.1 23:10:54 INFO - requests == 1.2.3 23:10:54 INFO - wsgiref == 0.1.2 23:10:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:10:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:10:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:10:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:10:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:10:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:10:54 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 0x103169b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10316c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1a9cc4580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f47d30>, '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 0x102e6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f3cbe8>, '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.KUkQfECKPO/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.X86v054kIY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 23:10:54 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 23:10:54 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 23:10:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KUkQfECKPO/Render', 23:10:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:10:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:10:54 INFO - 'HOME': '/Users/cltbld', 23:10:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:10:54 INFO - 'LOGNAME': 'cltbld', 23:10:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:10:54 INFO - 'MOZ_NO_REMOTE': '1', 23:10:54 INFO - 'NO_EM_RESTART': '1', 23:10:54 INFO - 'PAGER': '/bin/cat', 23:10:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:10:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:10:54 INFO - 'PWD': '/builds/slave/test', 23:10:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:10:54 INFO - 'SHELL': '/bin/bash', 23:10:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X86v054kIY/Listeners', 23:10:54 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 23:10:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:10:54 INFO - 'USER': 'cltbld', 23:10:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:10:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:10:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:10:54 INFO - 'XPC_FLAGS': '0x0', 23:10:54 INFO - 'XPC_SERVICE_NAME': '0', 23:10:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:10:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:10:55 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)) 23:10:55 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)) 23:10:55 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)) 23:10:55 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)) 23:10:55 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)) 23:10:55 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)) 23:10:55 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)) 23:10:55 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)) 23:10:55 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)) 23:10:55 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)) 23:10:55 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)) 23:10:55 INFO - Cleaning up... 23:10:55 INFO - Return code: 0 23:10:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:10:55 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')]} 23:10:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:10:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:10:55 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')]} 23:10:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:10:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:10:55 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 0x103169b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10316c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1a9cc4580>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f47d30>, '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 0x102e6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f3cbe8>, '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.KUkQfECKPO/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.X86v054kIY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 23:10:55 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 23:10:55 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 23:10:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KUkQfECKPO/Render', 23:10:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:10:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:10:55 INFO - 'HOME': '/Users/cltbld', 23:10:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:10:55 INFO - 'LOGNAME': 'cltbld', 23:10:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:10:55 INFO - 'MOZ_NO_REMOTE': '1', 23:10:55 INFO - 'NO_EM_RESTART': '1', 23:10:55 INFO - 'PAGER': '/bin/cat', 23:10:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:10:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:10:55 INFO - 'PWD': '/builds/slave/test', 23:10:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:10:55 INFO - 'SHELL': '/bin/bash', 23:10:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X86v054kIY/Listeners', 23:10:55 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 23:10:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:10:55 INFO - 'USER': 'cltbld', 23:10:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:10:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:10:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:10:55 INFO - 'XPC_FLAGS': '0x0', 23:10:55 INFO - 'XPC_SERVICE_NAME': '0', 23:10:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:10:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:10:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:10:55 INFO - Cleaning up... 23:10:55 INFO - Return code: 0 23:10:55 INFO - Running post-action listener: _resource_record_post_action 23:10:55 INFO - Running post-action listener: _start_resource_monitoring 23:10:55 INFO - Starting resource monitoring. 23:10:55 INFO - [mozharness: 2017-01-25 07:10:55.514876Z] Finished create-virtualenv step (success) 23:10:55 INFO - [mozharness: 2017-01-25 07:10:55.515948Z] Running install step. 23:10:55 INFO - Running pre-action listener: _resource_record_pre_action 23:10:55 INFO - Running main action method: install 23:10:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:10:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:10:55 INFO - Reading from file tmpfile_stdout 23:10:55 INFO - Detecting whether we're running mozinstall >=1.0... 23:10:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:10:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:10:55 INFO - Reading from file tmpfile_stdout 23:10:55 INFO - Output received: 23:10:55 INFO - Usage: mozinstall [options] installer 23:10:55 INFO - Options: 23:10:55 INFO - -h, --help show this help message and exit 23:10:55 INFO - -d DEST, --destination=DEST 23:10:55 INFO - Directory to install application into. [default: 23:10:55 INFO - "/builds/slave/test"] 23:10:55 INFO - --app=APP Application being installed. [default: firefox] 23:10:55 INFO - mkdir: /builds/slave/test/build/application 23:10:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 23:10:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 23:11:18 INFO - Reading from file tmpfile_stdout 23:11:18 INFO - Output received: 23:11:18 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 23:11:18 INFO - Running post-action listener: _resource_record_post_action 23:11:18 INFO - [mozharness: 2017-01-25 07:11:18.073534Z] Finished install step (success) 23:11:18 INFO - [mozharness: 2017-01-25 07:11:18.073669Z] Running run-tests step. 23:11:18 INFO - Running pre-action listener: _resource_record_pre_action 23:11:18 INFO - Running main action method: run_tests 23:11:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:11:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:11:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:11:18 INFO - 2017-01-24 23:11:18.088 screenresolution[1902:18558] starting screenresolution argv=screenresolution get 23:11:18 INFO - 2017-01-24 23:11:18.091 screenresolution[1902:18558] Display 0: 1600x1200x32@60 23:11:18 INFO - 2017-01-24 23:11:18.097 screenresolution[1903:18560] starting screenresolution argv=screenresolution list 23:11:18 INFO - Available Modes on Display 0 23:11:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:11:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:11:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:11:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:11:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:11:18 INFO - Intel Iris: 23:11:18 INFO - Chipset Model: Intel Iris 23:11:18 INFO - Type: GPU 23:11:18 INFO - Bus: Built-In 23:11:18 INFO - VRAM (Dynamic, Max): 1536 MB 23:11:18 INFO - Vendor: Intel (0x8086) 23:11:18 INFO - Device ID: 0x0a2e 23:11:18 INFO - Revision ID: 0x0009 23:11:18 INFO - Displays: 23:11:18 INFO - Display: 23:11:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:11:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:11:18 INFO - Display Serial Number: 1600x1200 60 23:11:18 INFO - Main Display: Yes 23:11:18 INFO - Mirror: Off 23:11:18 INFO - Online: Yes 23:11:18 INFO - Rotation: Supported 23:11:18 INFO - Return code: 0 23:11:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:11:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:11:18 INFO - Python 2.7.3 23:11:18 INFO - Return code: 0 23:11:18 INFO - grabbing minidump binary from tooltool 23:11:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:11:18 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 0x102f47d30>, '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 0x102e6edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102f3cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:11:18 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 23:11:18 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 23:11:18 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 23:11:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:11:18 INFO - Return code: 0 23:11:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:11:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:11:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:11:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:11:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:11:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/T3fyx_EvSEiVTFm-cmPg3w/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0342', '--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 23:11:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/T3fyx_EvSEiVTFm-cmPg3w/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0342 --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 23:11:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KUkQfECKPO/Render', 23:11:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:11:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:11:18 INFO - 'HOME': '/Users/cltbld', 23:11:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:11:18 INFO - 'LOGNAME': 'cltbld', 23:11:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:11:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:11:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:11:18 INFO - 'MOZ_NO_REMOTE': '1', 23:11:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:11:18 INFO - 'NO_EM_RESTART': '1', 23:11:18 INFO - 'PAGER': '/bin/cat', 23:11:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:11:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:11:18 INFO - 'PWD': '/builds/slave/test', 23:11:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:11:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:11:18 INFO - 'SHELL': '/bin/bash', 23:11:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.X86v054kIY/Listeners', 23:11:18 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 23:11:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:11:18 INFO - 'USER': 'cltbld', 23:11:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:11:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:11:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:11:18 INFO - 'XPC_FLAGS': '0x0', 23:11:18 INFO - 'XPC_SERVICE_NAME': '0', 23:11:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:11:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/T3fyx_EvSEiVTFm-cmPg3w/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0342', '--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 23:11:18 INFO - mozversion application_buildid: 20170124203147 23:11:18 INFO - mozversion application_changeset: 50cb9a3e39ac70489ee7c42ac17e7baf7b209a43 23:11:18 INFO - mozversion application_display_name: Nightly 23:11:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:11:18 INFO - mozversion application_name: Firefox 23:11:18 INFO - mozversion application_remotingname: firefox 23:11:18 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 23:11:18 INFO - mozversion application_vendor: Mozilla 23:11:18 INFO - mozversion application_version: 54.0a1 23:11:18 INFO - mozversion platform_buildid: 20170124203147 23:11:18 INFO - mozversion platform_changeset: 50cb9a3e39ac70489ee7c42ac17e7baf7b209a43 23:11:18 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 23:11:18 INFO - mozversion platform_version: 54.0a1 23:11:18 INFO - using testdate: 1485328278 23:11:18 INFO - actual date: 1485328278 23:11:18 INFO - starting webserver on 'localhost:56524' 23:11:18 INFO - SUITE-START | Running 2 tests 23:11:18 INFO - TEST-START | dromaeo_css 23:11:18 INFO - operating with platform_type : mac_ 23:11:18 INFO - Initialising browser for dromaeo_css test... 23:11:18 INFO - TEST-INFO | started process 1913 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpwF8WUj/profile http://localhost:56524/getInfo.html) 23:11:21 INFO - PROCESS | 1913 | 2017-01-24 23:11:21.588 plugin-container[1915:18726] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903b, name = 'com.apple.tsm.portname' 23:11:21 INFO - PROCESS | 1913 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:11:21 INFO - PROCESS | 1913 | 2017-01-24 23:11:21.590 plugin-container[1915:18726] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920f, name = 'com.apple.CFPasteboardClient' 23:11:21 INFO - PROCESS | 1913 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:11:21 INFO - PROCESS | 1913 | 2017-01-24 23:11:21.591 plugin-container[1915:18726] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:11:21 INFO - PROCESS | 1913 | __metrics Screen width/height:1600/1200 23:11:21 INFO - PROCESS | 1913 | colorDepth:24 23:11:21 INFO - PROCESS | 1913 | Browser inner width/height: 1024/689 23:11:21 INFO - PROCESS | 1913 | __metrics 23:11:21 ERROR - PROCESS | 1913 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 23:11:21 INFO - PROCESS | 1913 | JavaScript error: resource://app/modules/webrtcUI.jsm, line 66: NS_ERROR_XPC_NOT_ENOUGH_ARGS: Not enough arguments [nsIMessageListenerManager.removeMessageListener] 23:11:22 INFO - TEST-INFO | 1913: exit 0 23:11:22 INFO - Browser initialized. 23:11:22 INFO - Running cycle 1/1 for dromaeo_css test... 23:11:22 INFO - TEST-INFO | started process 1916 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpwF8WUj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:11:24 INFO - PROCESS | 1916 | 2017-01-24 23:11:24.959 plugin-container[1917:18844] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4f, name = 'com.apple.tsm.portname' 23:11:24 INFO - PROCESS | 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:11:24 INFO - PROCESS | 1916 | 2017-01-24 23:11:24.960 plugin-container[1917:18844] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x451b, name = 'com.apple.CFPasteboardClient' 23:11:24 INFO - PROCESS | 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:11:24 INFO - PROCESS | 1916 | 2017-01-24 23:11:24.960 plugin-container[1917:18844] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:11:34 INFO - PROCESS | 1916 | RSS: Main: 233037824 23:11:34 INFO - PROCESS | 1916 | 23:14:37 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:56524/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56524/tests/dromaeo/cssquery-ext.html) 23:14:39 INFO - PROCESS | 1916 | RSS: Main: 255840256 23:14:39 INFO - PROCESS | 1916 | 23:16:22 INFO - PROCESS | 1916 | ************************* 23:16:22 INFO - PROCESS | 1916 | A coding exception was thrown and uncaught in a Task. 23:16:22 INFO - PROCESS | 1916 | 23:16:22 ERROR - PROCESS | 1916 | Full message: TypeError: NetworkError when attempting to fetch resource. 23:16:22 INFO - PROCESS | 1916 | Full stack: 23:16:22 INFO - PROCESS | 1916 | ************************* 23:17:41 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:56524/tests/dromaeo/cssquery-ext.html (next: http://localhost:56524/tests/dromaeo/cssquery-jquery.html) 23:17:42 INFO - PROCESS | 1916 | RSS: Main: 267042816 23:17:42 INFO - PROCESS | 1916 | 23:20:44 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:56524/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56524/tests/dromaeo/cssquery-mootools.html) 23:20:44 INFO - PROCESS | 1916 | RSS: Main: 274493440 23:20:44 INFO - PROCESS | 1916 | 23:23:46 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:56524/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56524/tests/dromaeo/cssquery-prototype.html) 23:23:47 INFO - PROCESS | 1916 | RSS: Main: 270757888 23:23:47 INFO - PROCESS | 1916 | 23:26:50 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:56524/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56524/tests/dromaeo/cssquery-yui.html) 23:26:50 INFO - PROCESS | 1916 | RSS: Main: 275795968 23:26:50 INFO - PROCESS | 1916 | 23:29:53 INFO - PROCESS | 1916 | Cycle 1(1): loaded http://localhost:56524/tests/dromaeo/cssquery-yui.html (next: http://localhost:56524/tests/dromaeo/cssquery-dojo.html) 23:29:53 INFO - PROCESS | 1916 | RSS: Main: 278122496 23:29:53 INFO - PROCESS | 1916 | 23:29:53 INFO - PROCESS | 1916 | __start_tp_report 23:29:53 INFO - PROCESS | 1916 | _x_x_mozilla_page_load 23:29:53 INFO - PROCESS | 1916 | _x_x_mozilla_page_load_details 23:29:53 INFO - PROCESS | 1916 | |i|pagename|runs| 23:29:53 INFO - PROCESS | 1916 | |0;dojo.html;3324.983375083126;3346.3140056288457;3363.4282172030785;3364.461686130219;3379.205886616645;5590.66456012639;5595.38450770415;5645.802396916109;5648.181013753008;5699.259096317483;5611.2144299798065;5639.407862174474;5666.716664166795;5689.8577535561635;5725.770969161239;4256.276433006381;5142.202958541432;5156.1234590119675;5187.299714538545;5226.738663066851;5145;5250.107481728096;5276.55149312307;5291.497307755757;5302.443243459439;5071.985602879402;5089.491050894918;5194.844154675366;5216.347956505437;5219.556337711299;5103.387593488768;5132.94774571212;5155.71643559604;5189.714565698885;5257.185136303879;5114.667546609458;5121.718305493197;5125.128728116192;5132.40977287613;5138.460461651547;5553.611247212697;5598.684309187338;5624.71876406178;5636.18275350072;5656.095024796013;5582.441755824426;6058.091286307045;6149.877002459926;6158.137860699506;6207.441330280251;6196.752129914798;6204.317525072239;6229.968850155766;6230.096635987757;6232.065190221458;4204.726692764961;4483.8790302424395;5858.238429004202;5866.794662186846;5949.1076338549565;6171.47542458896;6186.659733714599;6203.286622038433;6209.037599172136;6223.906641400383;6103.609672365017;6105.8540098850235;6175.783847565313;6178.320384757673;6200.906986395207;6118.898598252357;6158.599691020069;6219.191505104308;6249.25008998923;6266.185395898504;5750.108733146369;6074.90887636677;6147.262328520606;6154.476869466145;6239.188905442264;5152.355955505562;6139.723712432951;6212.658303793244;6219.973704338733;6257.530685198628;4982.9252561211615;4989.201727723619;4995.100881841231;4998.775055122528;5023.472535383805;4374.12517496502;4398.340248962617;4409.713368631029;4416.933745993812;4455.554444555519;5749.827505174768;5791.986402379651;5820.534357251409;5839.883202335929;5862.179294898718;6182.721777520022;6188.4739797116845;6238.40735130171;6241.469475094577;6265.561410701207;5670.631408958405;5746.856328591735;5773.480386765127;5796.333421656479;5811.389804070596;5418.485243901901;5501.229827824108;5524.806631767869;5541.473560011793;5550.47270509301;6395.744170233139;6414.198225221754;6414.807555773335;6426.453751430994;6441.67791610427;6137.989923150331;6140.600860944024;6252.812415627538;6254.843628909131;6277.874442511033;6150.723217455315;6185.474234690102;6191.4118158774845;6206.317305096526;6262;5059.696418214919;6163.260408750979;6165.907511387243;6196.907046394217;6207.472364848858;6079.452849243589;6215.627062376091;6223.906641400292;6225.066240064027;6231.9688401557705;4736.265878788793;5969.791057312973;6067.544934130046;6121.602095863755;6124.716609780976;6041.758329666764;6102.115193296859;6116.3272040076445;6116.877662446637;6138.109974053648;4708.293755936637;5852.18069470283;5926.822195334147;5938.020226662725;5973.074173503114;6083.057126145454;6116.853194527988;6117.857853872152;6133.120447061136;6159.969200153971;5931.288245410578;5950.137230101524;5963.254593175853;5977.820665380045;6020.157177994996 23:29:53 INFO - PROCESS | 1916 | |1;ext.html;436.60923473491226;482.0431443584486;487.3299213581326;488.8024340366103;544.300573762715;40706;43279.78360108203;43380.566194338084;43392.91517712059;43439.565604343945;36160.81919590398;43067.78466107665;43636.345454818205;43870.90322741933;43881.780591097;9269.814603707939;9488.762780930463;9493.193078588294;9517.952410237938;11119.833202501968;11223.494942727597;11493.482793274323;11538.076953843714;11542.961133497984;11564.71088222797;11516.424178791069;11549.826752598718;11557;11566.363849988247;11579.768404631903;11485.59800406986;11548.845115488468;11553;11568.942155289255;11574.6527604172;11141.997220250136;11197.49611267495;11240.718982025433;11294.040006599367;11349.659510214708;31958.6804131958;38270.08649567539;38444.80777596122;38548.807255963824;38944.22111557781;3918.7060970427046;4011.0373510357304;4011.4183443400834;4025.0943537704075;4033.0320723026557;29333.413331733245;29800.275277505487;30216;30496.085117446513;30497.390052199054;3162.4623813951684;3282.2122690554097;3295.703386695209;3350.4471762159214;3352.6982571568433;425248;430200;431476;431604.52593211125;432921.99999999686;56554.7172264136;56654.43345566492;56929;57073;57230;56611.86776264451;56941.43058569444;56973.86052278931;56998.43001569931;57275.56811079756;14901.180435076174;15323.31045102973;15359;15372.154531500875;15385.9230703848;9832.360896541702;10096.848547271797;11490.942545287387;11676.416179191006;11726.589569365031;17908.91045544764;18060.819391806177;18103.72844407335;18112.909435452737;18214;6519.967400163064;7127.218195451137;7279.818004549928;7501.399888008947;7726.536407815549;870.5299138465284;873.7990262239593;878.7934835313696;907.6324088744069;912.8265629530368;31399.058028259187;31490;31801;31915.36169276648;32001;921.0283151275469;945.7682867697458;988.3625061835077;1086.9293495922739;1249.5314257153568;15898.284577194054;16491.34034638625;16517.256723447474;16637.33450661984;16667.497582036216;647896;652822;653456;654015;655655;62212.53449586079;62947.6852615734;63434.365656342845;63652;63723.681381592796;1414.7524183267888;1449.6013596260902;1472.4625511688157;1473.0425223604743;1475.513080683372;29411.11766647004;30382.84808575943;30438.543421849132;30566.694333056384;31059.844700777256;53813.92372152613;54100;54111.188332175836;54191;54419.55804419526;537431;539270;539510;540043;540852;22099.779002210416;23966.40083997914;24578.38554036092;26557.734422655525;27424.45151096927;49348.766280843265;49806;50136;50245.74877125591;50643.24035139402;30327;35161.94514164579;37310.62689373072;39136.87747486152;43315.71922071345;33315.83342083274;36756.08109797276;37121.886343409744;37503.81248093742;40673.79663101665;30697.846510767304;37122.628773713;38189.2362152761;38673.71273441677;39557;34500.654993449745;35606.821965890005;35648.9265150047;36045;36133;25674.22977310609;25836.8708156458;25895.611565826905;26317.868410657826;26412.735872641028 23:29:53 INFO - PROCESS | 1916 | |2;jquery.html;339.51957979459064;383.31960769633895;392.68977507768864;399.44078290393463;413.36548398208754;19568.31510897119;20965.47586310343;21724.782752172472;21762.347129586116;22074.779252207492;22083.227087051917;22226.999785009673;22266.443338916535;22484.213052543164;22584.096636134575;5455.918161227575;5875.941240587604;5883.588148829584;5939.198208241875;5954.70226488676;4155.896102597444;4368.97815510922;4987.75061246938;5854.648721076728;5889.941100589003;4358.302671572549;6606.372394622503;6608.107905432753;6697.263301036882;6703.564268322568;6517.4460170885295;6605.9339406605795;6652.168478940132;6657;6669.233038200597;5807.89649966505;5823.970880145572;5838.153467747153;5839.445252701029;5847.415258474119;25598.23205303844;25801.483970320674;25836.224913252634;25860.74139258602;26155.476890462276;2697.8381297122237;2754.807163498556;2766.9308267293277;2772.9722702772915;2775.9583606245924;13324.200547967055;14826.258687065603;15876.52370428889;15915.681686366208;16094.275757590936;1839.8068202838642;1881.2663061406063;1893.2427029188298;1895.649304878602;1910.2072639854443;85557.2888542232;116100;117226;118098.22852657217;118197;9342;11547.942260288813;11596.652100437;11658.358790266617;11869.643910682693;13255.005874559447;13595.932020339835;14323.78514322286;14662.266886655625;14721.484748033767;7031.92968070323;8295.087540370554;8429.452085614415;8493.617787199591;8521.060234219185;7384.593847338448;7910.802229944297;8228.876566851503;8366;8368.83262334762;7286.198518162998;7382.667779949915;7407.85184296311;7424.591647459333;7456.850862982818;1678.5048410718866;1912.8278454939123;1939.9612007759765;1956.178405069874;1966.9409917702492;1032.5270976012407;1052.2003277508995;1063.3285556288326;1082.118073769875;1116.916231282657;18829.52926176829;20039.797612143317;20555.8972205138;22570.435739106324;22874.313770586577;882.4176043811054;1015.7100482387474;1063.1813503602184;1071.2501249125644;1086.21792309537;8800.515971621622;9980.351277167107;10561.313514621525;11972.880271197177;12139.393030348447;393873;401931;675857;714417;727717;132724;138781;156483;157288;157821;773.8645736996003;1049.0801832646391;1056.9788604228027;1056.9947150264354;1058.7141471802724;19568.510787230432;20305.109387291544;20402.79597204009;20456.897715511328;20459.69310460374;58887.41112588819;60680;60991;61267.693661531404;61301.693491532256;89106.10893891238;92819;93850;93878.53060734925;94524.52737736267;8005.39959503028;8056.154103819249;8057.677692892219;8459.154084591491;8481.660733570588;9055.275577953616;12363.69090772702;13188.472461101448;13495.797563036365;13542.932285338511;8967.820643587222;10820.242583019113;11194.83207751868;11255.212135150461;11706.648800535997;10686.8931310689;10891.346519208873;11082.501287441923;11146.832797507881;11267.830982535104;11311.547538098714;11466.94266528662;11898.286102833858;11940.820887686517;11956.402179890796;6833.487488438288;8149.79625509367;8181.754547363588;8393.83212335762;8448.662053518035;10447.84328235062;11225.550977960791;11297.548098075986;11298.717532061764;11398.658040258806 23:29:53 INFO - PROCESS | 1916 | |3;mootools.html;1247.7941139711947;1380.4685196199466;1397.4410235905643;1405.578326502049;1417.7873319002144;7398.408127349812;7650.158482566913;7819.530828150308;8218.465799723013;8259.752207433772;6661.500387470935;7826.25650563197;8087.514749115057;8213.178682131784;8233.794155146124;2211.8672879627193;2275.6131457652154;2279.544091181762;2279.760625134362;2281.2813963601498;2014.4057503036565;2257.9435514112115;2290.1068583252545;2295.7589453107435;2299.5056062946464;2033.806788355104;2176.9564608707815;2257.7064981552458;2263.807576356012;2271.284545368204;2209.8674079555276;2216.512367279196;2229.8962013803107;2233.519793244444;2240.8879556022216;2230.11910295434;2258.9661155082686;2282.132789539981;2285.074544809355;2288.3821368230483;8276.668933242723;8309.335253179792;8322.542260175687;8331.50010999342;8364.079951205362;4893.7063776173545;4896.902061958741;5055.5702765265;5057.822976195852;5068.670536415121;3212.6144862616634;3307.7353811695248;3440.673136052071;3443.9827800861085;3451.758376913617;1038.7143535527714;1172.5602898912907;1236.5098960614841;1240.1876770715196;1255.6798016505825;10653.094486968617;10721.410322432263;10781.08360789326;10784.406857622827;10805;3991.002249437641;4041.7372870763306;4505.121501307279;4551.180787458289;4571.542845715402;7877.803054923673;9188.724338269863;9296.209822165192;9355;9364.859527107099;5009.198528235464;5103.8724031898755;5113.897722045613;5115.820946266863;5119.846404607868;4460.219461594208;4587.587117159472;4641.187792136363;4676.041411510632;4677.7193368398;3614.710823134143;3650.2699460108083;3658.1951970566433;3675.503806986076;3707.2214834884435;959.2709540749081;986.3243678080538;1065.733566608348;1090.2477290669412;1144.347721798567;773.7407968330559;858.2876212743408;858.6136238692613;861.6294993153004;917.4449458077901;4838.225883858566;5394.865128371822;5551.111822108443;5603.831885043455;5812.360640329645;961.2646325560952;975.8487434447673;984.8473486609587;1002.4236064263077;1008.1380419741163;3558.5195998540385;3608.729345299113;3610.3862343401684;3696.8706095286534;3745.419459983707;9356.812863742687;9639.855402168972;9963.900360996442;9982.251331150193;10005.349652272578;9839.311248212556;9945.950270248602;9953.203743700487;9962.551685174185;9971.351862128939;7684;7782.883256751154;7812.84374312522;7822;7830.334421574116;4622.445306563234;5149.304843846033;5375.865603359947;5397.271368365377;5399.70301633414;9001.32490063261;9073.047330030251;9102.31732620043;9161.358704890594;9202.35583509148;9258.95370523143;9279.283332583494;9286.95356523213;9312.20846228051;9386.859197111913;5601.131824567198;6108.778244351236;6162.322144564005;6789.558678685869;6803.115594972622;7750;7756.224377562425;7785.844283114419;7837.412194085352;7846.215378462108;6283.183186185766;7033.859322813618;7074.115735533059;7096.574205547684;7101.041359416413;7038.01467794499;7049.048378468841;7050.929490705027;7128.536645118051;7130.001799748143;6160.966880929373;6285.905711414241;6391.936080639134;6403.679816009274;6406.487481001602;4831.130396528659;4834.854954351375;4864.562189402829;4905.583025442877;5033.2701758245585;4863.537963893425;4870.95129048705;4938.7530623467965;4986.675866068694;5037.042961837239 23:29:53 INFO - PROCESS | 1916 | |4;prototype.html;355.2646022732944;358.6592736899945;366.9174435751956;369.5214696967427;371.96838268747155;4262.9147417051645;5347.25138480613;5420.403755586887;5493.857395615713;5531;4671.462781780087;4721.984773273745;5179.378474583055;5366.570674346043;5393.460653934614;3084.722374986251;3678.7792732436005;4113.629773320401;4258.063226090255;4284.657227421814;3857.9614203858023;3981.28336899358;4053.736507127042;4242.936355954663;4304.385855304261;2991.8952836650724;3524.1365865363023;3801.5058042454443;4092.283850326196;4117.691173162025;3338.3490219407227;3548.769329993542;3884.281407939526;4074.2666320062376;4121.690873184523;2950.409918016384;3246.0099669600763;3357.143928298293;3528.929421411583;3545.2170046513365;4475.485319188302;5007.593317880279;5221.164613661796;5229.411441336525;5259.316288882421;4605.263157894754;4609.67732258742;4612.884677883079;4639.744814035227;4676.445901115177;4925.867050578387;5229.712365819878;5450.53890779354;5616.271403168745;5636.943630563683;3731.925361492782;3829.329867273216;3849.2493963677093;3862.884113476572;3881.068543549556;5614.9157762633595;5864.941350586482;5895.349302195392;5938;5962.672053037081;3203.8237896915894;3435.886929538316;3924.3321588324848;3932.941005884914;3958.2083583283456;3177.7298929591243;3700.962990370062;3969.8412063517135;3985.9202815943518;4036.8183431745642;3870.6903447724117;3954.4859168307935;4441.400410944481;4605.286180642006;4610.216263235258;4920.6801557898625;5252.474752524717;5558.333000040021;5748.396418376094;5774.624649397776;1195.4859410453414;1225.5159212111166;1258.2010423381037;1270.30133426615;1290.6321698315935;795.9124496305402;796.3828033274143;805.6898094233762;851.6465666748354;884.7611144990946;5053.444121146671;5074.391073071255;5081.74591270435;5106.744662766847;5137.023965446553;5151.4333423323405;5556.694381809039;5568.331800184003;5583.860403489945;5612.961602103624;4379;4564.954350456519;4828.927566086511;4927.7536123193695;5026.165449610939;2891.6433107017715;2949.454350945082;2950.9557356639666;2966.1991262359475;2970.1288188441144;4690.577847993697;4798.2802579613335;5286.682799032071;5545.861353466195;5774.422557744276;4686.625069994392;4717.575418212377;5279.076161671692;5310.681359118388;5716.627599546142;4803.735794531334;5694.4590263924865;5748.741306641211;5793.798356624456;5825.271841019872;4736;4880.92678609814;5530;5530.446955304598;5535.750891209825;4832.299316599144;5082.695038297714;5774.220480235114;5791.34946540234;5857.94142058591;5838.503727183237;6046.3046749624355;6062.242219722535;6092.39076092405;6133.50931925436;3407.965920340765;3460;3729.0304520825325;3909.4135879617147;3954.387070004154;4405.625521830552;4962.528559786814;5278.920816187837;5828.766849326149;5923.526117910643;4175.659472421989;4765.523447655345;5132.743362831919;5734.168545560954;5748.338941021669;4783.689060211075;4813.614910807057;4957.454679985127;5562.3047119110115;5735.627184233012;5173.991071740975;5236.266922630911;5525.806596769094;5587.99416105105;5600.35595906476;4911.4106307243355;5013.849584512471;5181.53366197044;5245.370555533361;5647.1599312467915;4688.906221875475;5285.339332583427;5451.001948927995;5578.414266502121;5605.579581531483 23:29:53 INFO - PROCESS | 1916 | |5;yui.html;702.9789106326812;725.4414101142117;731.4404480572363;773.4122067228908;825.6243409248792;14655.780163297546;15552.533423997284;16974.91512542437;17573.472795816113;17649.470515884514;16770.74843877343;16892.901961372532;16932.83067169328;16943.068131252807;16993.490195294162;603.6468665830472;853.5774791478227;875.1729615513342;875.5665945357025;878.14819624964;655.619740550482;843.3927572148043;863.5034854958393;880.0363601855958;881.0484676549312;916.331078312832;919.0396036142214;922.1884741427534;922.492629054021;923.995380023099;903.8734577159203;909.1726528858773;909.3043821476575;910.094456016268;911.2800887299026;568.5423234296389;672.1698702102899;678.5724993254267;685.6331862453613;687.4397366146575;13005.544805931748;15178.924105379403;15237.847621523864;15401.922990384975;15671.138087405188;14147.64630884236;14310.069845460011;14420.062695924731;14469.49356772508;14544.70910581793;11783.585969683692;11894.464749086312;11917.344546049964;11946.522139114424;11987.82018269718;3603.8738644147593;3648.6168952259895;3652.7808331500196;3687.4284485904727;3770.7926064066464;101043;107225.21750396138;107764.30589235254;112849;113524;11005.449727513593;11049.723756906142;11068.557257709763;11080.5567777288;11267.830982535268;22435.19560054993;22647.88676056642;22728.43178920507;22827.31518054428;22835.543289134122;6519.512061047317;7632.389408847279;7642.4650274480255;7687.961560192163;7716.7684969451;4179.644730197906;4229.577042295746;4247.915041699149;4275.722078064916;4367.58507941745;3490.3717330880686;3519.8416071276856;3559.893203203908;3567.6432356764117;3573.9642603574152;606.3572613030202;648.9870202595922;649.6491894376984;654.6857508395997;665.4310564467407;650.203500711634;653.6372313366032;682.7985744205372;690.6926417744056;701.6667083135571;9989.80020399588;10212.285140040274;10392.844107338396;10485.580576776996;10553.84169237462;654.5418207254845;668.7204520312108;687.9105716256854;706.2055187913597;707.4128473367093;3999.9203995940215;4220.915581688349;4402.823887044547;4970.229614409773;5024.773885175176;76962;80119.19880801234;88724.11275887159;99717;107905;73483.63258183675;90694;90814.54592726994;90926;96963.51518242505;50619;53509.73245133749;63329.05006425;65150.34849651538;80097.99755006055;10806.531912775192;10873.401962891801;10932.414989210387;10933.343999360064;11212;49758.50241497539;50756.679889230734;51153;52783.47216527939;53852.19221711599;55626.721866390406;59128.40871591401;66336;68474.65762671155;69400.30599693938;12869.420876060787;12930.482780688668;12995.480180792661;13002.609921702364;13298.734025319633;14452.855471445151;14574.271286435423;14652.853471465576;14674.486392976622;14906.552803415481;13375.53185638498;13408.32958352098;13642.386092625657;13663.316834158053;14044;13520.79718804199;13615.591532254048;13671.316434178052;13832.100913440594;14141.292935352985;13299.135556188818;13304.13523120994;13492.49195209233;13567.879387740677;13892.652683682989;9330.860037099314;11033.613823516138;11117.444127793417;11259.605913792979;11698.883011169779;9407;10261.486925653538;10462.358773633467;10712.89287107119;10743.65756584053 23:29:53 INFO - PROCESS | 1916 | __end_tp_report 23:29:53 INFO - PROCESS | 1916 | __start_cc_report 23:29:53 INFO - PROCESS | 1916 | _x_x_mozilla_cycle_collect,377 23:29:53 INFO - PROCESS | 1916 | __end_cc_report 23:29:53 INFO - PROCESS | 1916 | __startTimestamp1485329393482__endTimestamp 23:29:53 INFO - PROCESS | 1916 | 23:29:53 INFO - PROCESS | 1916 | ------- Summary: start ------- 23:29:53 INFO - PROCESS | 1916 | Number of tests: 6 23:29:53 INFO - PROCESS | 1916 | 23:29:53 INFO - PROCESS | 1916 | [#0] dojo.html Cycles:165 Average:5681.57 Median:5931.29 stddev:666.98 (11.2%) stddev-sans-first:642.89 23:29:53 INFO - PROCESS | 1916 | Values: 3325.0 3346.3 3363.4 3364.5 3379.2 5590.7 5595.4 5645.8 5648.2 5699.3 5611.2 5639.4 5666.7 5689.9 5725.8 4256.3 5142.2 5156.1 5187.3 5226.7 5145.0 5250.1 5276.6 5291.5 5302.4 5072.0 5089.5 5194.8 5216.3 5219.6 5103.4 5132.9 5155.7 5189.7 5257.2 5114.7 5121.7 5125.1 5132.4 5138.5 5553.6 5598.7 5624.7 5636.2 5656.1 5582.4 6058.1 6149.9 6158.1 6207.4 6196.8 6204.3 6230.0 6230.1 6232.1 4204.7 4483.9 5858.2 5866.8 5949.1 6171.5 6186.7 6203.3 6209.0 6223.9 6103.6 6105.9 6175.8 6178.3 6200.9 6118.9 6158.6 6219.2 6249.3 6266.2 5750.1 6074.9 6147.3 6154.5 6239.2 5152.4 6139.7 6212.7 6220.0 6257.5 4982.9 4989.2 4995.1 4998.8 5023.5 4374.1 4398.3 4409.7 4416.9 4455.6 5749.8 5792.0 5820.5 5839.9 5862.2 6182.7 6188.5 6238.4 6241.5 6265.6 5670.6 5746.9 5773.5 5796.3 5811.4 5418.5 5501.2 5524.8 5541.5 5550.5 6395.7 6414.2 6414.8 6426.5 6441.7 6138.0 6140.6 6252.8 6254.8 6277.9 6150.7 6185.5 6191.4 6206.3 6262.0 5059.7 6163.3 6165.9 6196.9 6207.5 6079.5 6215.6 6223.9 6225.1 6232.0 4736.3 5969.8 6067.5 6121.6 6124.7 6041.8 6102.1 6116.3 6116.9 6138.1 4708.3 5852.2 5926.8 5938.0 5973.1 6083.1 6116.9 6117.9 6133.1 6160.0 5931.3 5950.1 5963.3 5977.8 6020.2 23:29:53 INFO - PROCESS | 1916 | 23:29:53 INFO - PROCESS | 1916 | [#1] ext.html Cycles:180 Average:68063.85 Median:37216.63 stddev:146427.58 (393.4%) stddev-sans-first:146750.31 23:29:53 INFO - PROCESS | 1916 | Values: 436.6 482.0 487.3 488.8 544.3 40706.0 43279.8 43380.6 43392.9 43439.6 36160.8 43067.8 43636.3 43870.9 43881.8 9269.8 9488.8 9493.2 9518.0 11119.8 11223.5 11493.5 11538.1 11543.0 11564.7 11516.4 11549.8 11557.0 11566.4 11579.8 11485.6 11548.8 11553.0 11568.9 11574.7 11142.0 11197.5 11240.7 11294.0 11349.7 31958.7 38270.1 38444.8 38548.8 38944.2 3918.7 4011.0 4011.4 4025.1 4033.0 29333.4 29800.3 30216.0 30496.1 30497.4 3162.5 3282.2 3295.7 3350.4 3352.7 425248.0 430200.0 431476.0 431604.5 432922.0 56554.7 56654.4 56929.0 57073.0 57230.0 56611.9 56941.4 56973.9 56998.4 57275.6 14901.2 15323.3 15359.0 15372.2 15385.9 9832.4 10096.8 11490.9 11676.4 11726.6 17908.9 18060.8 18103.7 18112.9 18214.0 6520.0 7127.2 7279.8 7501.4 7726.5 870.5 873.8 878.8 907.6 912.8 31399.1 31490.0 31801.0 31915.4 32001.0 921.0 945.8 988.4 1086.9 1249.5 15898.3 16491.3 16517.3 16637.3 16667.5 647896.0 652822.0 653456.0 654015.0 655655.0 62212.5 62947.7 63434.4 63652.0 63723.7 1414.8 1449.6 1472.5 1473.0 1475.5 29411.1 30382.8 30438.5 30566.7 31059.8 53813.9 54100.0 54111.2 54191.0 54419.6 537431.0 539270.0 539510.0 540043.0 540852.0 22099.8 23966.4 24578.4 26557.7 27424.5 49348.8 49806.0 50136.0 50245.7 50643.2 30327.0 35161.9 37310.6 39136.9 43315.7 33315.8 36756.1 37121.9 37503.8 40673.8 30697.8 37122.6 38189.2 38673.7 39557.0 34500.7 35606.8 35648.9 36045.0 36133.0 25674.2 25836.9 25895.6 26317.9 26412.7 23:29:53 INFO - PROCESS | 1916 | 23:29:53 INFO - PROCESS | 1916 | [#2] jquery.html Cycles:180 Average:35944.96 Median:22729.21 stddev:101315.00 (445.7%) stddev-sans-first:101563.94 23:29:53 INFO - PROCESS | 1916 | Values: 339.5 383.3 392.7 399.4 413.4 19568.3 20965.5 21724.8 21762.3 22074.8 22083.2 22227.0 22266.4 22484.2 22584.1 5455.9 5875.9 5883.6 5939.2 5954.7 4155.9 4369.0 4987.8 5854.6 5889.9 4358.3 6606.4 6608.1 6697.3 6703.6 6517.4 6605.9 6652.2 6657.0 6669.2 5807.9 5824.0 5838.2 5839.4 5847.4 25598.2 25801.5 25836.2 25860.7 26155.5 2697.8 2754.8 2766.9 2773.0 2776.0 13324.2 14826.3 15876.5 15915.7 16094.3 1839.8 1881.3 1893.2 1895.6 1910.2 85557.3 116100.0 117226.0 118098.2 118197.0 9342.0 11547.9 11596.7 11658.4 11869.6 13255.0 13595.9 14323.8 14662.3 14721.5 7031.9 8295.1 8429.5 8493.6 8521.1 7384.6 7910.8 8228.9 8366.0 8368.8 7286.2 7382.7 7407.9 7424.6 7456.9 1678.5 1912.8 1940.0 1956.2 1966.9 1032.5 1052.2 1063.3 1082.1 1116.9 18829.5 20039.8 20555.9 22570.4 22874.3 882.4 1015.7 1063.2 1071.3 1086.2 8800.5 9980.4 10561.3 11972.9 12139.4 393873.0 401931.0 675857.0 714417.0 727717.0 132724.0 138781.0 156483.0 157288.0 157821.0 773.9 1049.1 1057.0 1057.0 1058.7 19568.5 20305.1 20402.8 20456.9 20459.7 58887.4 60680.0 60991.0 61267.7 61301.7 89106.1 92819.0 93850.0 93878.5 94524.5 8005.4 8056.2 8057.7 8459.2 8481.7 9055.3 12363.7 13188.5 13495.8 13542.9 8967.8 10820.2 11194.8 11255.2 11706.6 10686.9 10891.3 11082.5 11146.8 11267.8 11311.5 11466.9 11898.3 11940.8 11956.4 6833.5 8149.8 8181.8 8393.8 8448.7 10447.8 11225.6 11297.5 11298.7 11398.7 23:29:53 INFO - PROCESS | 1916 | 23:29:53 INFO - PROCESS | 1916 | [#3] mootools.html Cycles:180 Average:5302.95 Median:5114.86 stddev:2924.22 (57.2%) stddev-sans-first:2916.54 23:29:53 INFO - PROCESS | 1916 | Values: 1247.8 1380.5 1397.4 1405.6 1417.8 7398.4 7650.2 7819.5 8218.5 8259.8 6661.5 7826.3 8087.5 8213.2 8233.8 2211.9 2275.6 2279.5 2279.8 2281.3 2014.4 2257.9 2290.1 2295.8 2299.5 2033.8 2177.0 2257.7 2263.8 2271.3 2209.9 2216.5 2229.9 2233.5 2240.9 2230.1 2259.0 2282.1 2285.1 2288.4 8276.7 8309.3 8322.5 8331.5 8364.1 4893.7 4896.9 5055.6 5057.8 5068.7 3212.6 3307.7 3440.7 3444.0 3451.8 1038.7 1172.6 1236.5 1240.2 1255.7 10653.1 10721.4 10781.1 10784.4 10805.0 3991.0 4041.7 4505.1 4551.2 4571.5 7877.8 9188.7 9296.2 9355.0 9364.9 5009.2 5103.9 5113.9 5115.8 5119.8 4460.2 4587.6 4641.2 4676.0 4677.7 3614.7 3650.3 3658.2 3675.5 3707.2 959.3 986.3 1065.7 1090.2 1144.3 773.7 858.3 858.6 861.6 917.4 4838.2 5394.9 5551.1 5603.8 5812.4 961.3 975.8 984.8 1002.4 1008.1 3558.5 3608.7 3610.4 3696.9 3745.4 9356.8 9639.9 9963.9 9982.3 10005.3 9839.3 9946.0 9953.2 9962.6 9971.4 7684.0 7782.9 7812.8 7822.0 7830.3 4622.4 5149.3 5375.9 5397.3 5399.7 9001.3 9073.0 9102.3 9161.4 9202.4 9259.0 9279.3 9287.0 9312.2 9386.9 5601.1 6108.8 6162.3 6789.6 6803.1 7750.0 7756.2 7785.8 7837.4 7846.2 6283.2 7033.9 7074.1 7096.6 7101.0 7038.0 7049.0 7050.9 7128.5 7130.0 6161.0 6285.9 6391.9 6403.7 6406.5 4831.1 4834.9 4864.6 4905.6 5033.3 4863.5 4871.0 4938.8 4986.7 5037.0 23:29:53 INFO - PROCESS | 1916 | 23:29:53 INFO - PROCESS | 1916 | [#4] prototype.html Cycles:180 Average:4433.55 Median:4896.17 stddev:1374.73 (28.1%) stddev-sans-first:1344.08 23:29:53 INFO - PROCESS | 1916 | Values: 355.3 358.7 366.9 369.5 372.0 4262.9 5347.3 5420.4 5493.9 5531.0 4671.5 4722.0 5179.4 5366.6 5393.5 3084.7 3678.8 4113.6 4258.1 4284.7 3858.0 3981.3 4053.7 4242.9 4304.4 2991.9 3524.1 3801.5 4092.3 4117.7 3338.3 3548.8 3884.3 4074.3 4121.7 2950.4 3246.0 3357.1 3528.9 3545.2 4475.5 5007.6 5221.2 5229.4 5259.3 4605.3 4609.7 4612.9 4639.7 4676.4 4925.9 5229.7 5450.5 5616.3 5636.9 3731.9 3829.3 3849.2 3862.9 3881.1 5614.9 5864.9 5895.3 5938.0 5962.7 3203.8 3435.9 3924.3 3932.9 3958.2 3177.7 3701.0 3969.8 3985.9 4036.8 3870.7 3954.5 4441.4 4605.3 4610.2 4920.7 5252.5 5558.3 5748.4 5774.6 1195.5 1225.5 1258.2 1270.3 1290.6 795.9 796.4 805.7 851.6 884.8 5053.4 5074.4 5081.7 5106.7 5137.0 5151.4 5556.7 5568.3 5583.9 5613.0 4379.0 4565.0 4828.9 4927.8 5026.2 2891.6 2949.5 2951.0 2966.2 2970.1 4690.6 4798.3 5286.7 5545.9 5774.4 4686.6 4717.6 5279.1 5310.7 5716.6 4803.7 5694.5 5748.7 5793.8 5825.3 4736.0 4880.9 5530.0 5530.4 5535.8 4832.3 5082.7 5774.2 5791.3 5857.9 5838.5 6046.3 6062.2 6092.4 6133.5 3408.0 3460.0 3729.0 3909.4 3954.4 4405.6 4962.5 5278.9 5828.8 5923.5 4175.7 4765.5 5132.7 5734.2 5748.3 4783.7 4813.6 4957.5 5562.3 5735.6 5174.0 5236.3 5525.8 5588.0 5600.4 4911.4 5013.8 5181.5 5245.4 5647.2 4688.9 5285.3 5451.0 5578.4 5605.6 23:29:53 INFO - PROCESS | 1916 | 23:29:53 INFO - PROCESS | 1916 | [#5] yui.html Cycles:180 Average:19874.29 Median:3670.10 stddev:27924.35 (760.9%) stddev-sans-first:27965.58 23:29:53 INFO - PROCESS | 1916 | Values: 703.0 725.4 731.4 773.4 825.6 14655.8 15552.5 16974.9 17573.5 17649.5 16770.7 16892.9 16932.8 16943.1 16993.5 603.6 853.6 875.2 875.6 878.1 655.6 843.4 863.5 880.0 881.0 916.3 919.0 922.2 922.5 924.0 903.9 909.2 909.3 910.1 911.3 568.5 672.2 678.6 685.6 687.4 13005.5 15178.9 15237.8 15401.9 15671.1 14147.6 14310.1 14420.1 14469.5 14544.7 11783.6 11894.5 11917.3 11946.5 11987.8 3603.9 3648.6 3652.8 3687.4 3770.8 101043.0 107225.2 107764.3 112849.0 113524.0 11005.4 11049.7 11068.6 11080.6 11267.8 22435.2 22647.9 22728.4 22827.3 22835.5 6519.5 7632.4 7642.5 7688.0 7716.8 4179.6 4229.6 4247.9 4275.7 4367.6 3490.4 3519.8 3559.9 3567.6 3574.0 606.4 649.0 649.6 654.7 665.4 650.2 653.6 682.8 690.7 701.7 9989.8 10212.3 10392.8 10485.6 10553.8 654.5 668.7 687.9 706.2 707.4 3999.9 4220.9 4402.8 4970.2 5024.8 76962.0 80119.2 88724.1 99717.0 107905.0 73483.6 90694.0 90814.5 90926.0 96963.5 50619.0 53509.7 63329.1 65150.3 80098.0 10806.5 10873.4 10932.4 10933.3 11212.0 49758.5 50756.7 51153.0 52783.5 53852.2 55626.7 59128.4 66336.0 68474.7 69400.3 12869.4 12930.5 12995.5 13002.6 13298.7 14452.9 14574.3 14652.9 14674.5 14906.6 13375.5 13408.3 13642.4 13663.3 14044.0 13520.8 13615.6 13671.3 13832.1 14141.3 13299.1 13304.1 13492.5 13567.9 13892.7 9330.9 11033.6 11117.4 11259.6 11698.9 9407.0 10261.5 10462.4 10712.9 10743.7 23:29:53 INFO - PROCESS | 1916 | -------- Summary: end -------- 23:29:53 INFO - PROCESS | 1916 | 23:29:54 INFO - TEST-INFO | 1916: exit 0 23:29:54 INFO - TEST-OK | dromaeo_css | took 1115573ms 23:29:54 INFO - TEST-START | kraken 23:29:54 INFO - operating with platform_type : mac_ 23:29:54 INFO - Initialising browser for kraken test... 23:29:54 INFO - TEST-INFO | started process 1922 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmp1CIkdA/profile http://localhost:56524/getInfo.html) 23:29:56 INFO - PROCESS | 1922 | 2017-01-24 23:29:56.923 plugin-container[1923:20784] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x484b, name = 'com.apple.tsm.portname' 23:29:56 INFO - PROCESS | 1922 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:29:56 INFO - PROCESS | 1922 | 2017-01-24 23:29:56.926 plugin-container[1923:20784] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 23:29:56 INFO - PROCESS | 1922 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:29:56 INFO - PROCESS | 1922 | 2017-01-24 23:29:56.926 plugin-container[1923:20784] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:29:56 INFO - PROCESS | 1922 | __metrics Screen width/height:1600/1200 23:29:56 INFO - PROCESS | 1922 | colorDepth:24 23:29:56 INFO - PROCESS | 1922 | Browser inner width/height: 1024/689 23:29:56 INFO - PROCESS | 1922 | __metrics 23:29:57 ERROR - PROCESS | 1922 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3280: TypeError: this.tabs is undefined 23:29:57 INFO - PROCESS | 1922 | JavaScript error: resource://app/modules/webrtcUI.jsm, line 66: NS_ERROR_XPC_NOT_ENOUGH_ARGS: Not enough arguments [nsIMessageListenerManager.removeMessageListener] 23:29:57 INFO - TEST-INFO | 1922: exit 0 23:29:57 INFO - Browser initialized. 23:29:57 INFO - Running cycle 1/1 for kraken test... 23:29:57 INFO - TEST-INFO | started process 1924 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmp1CIkdA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:30:00 INFO - PROCESS | 1924 | 2017-01-24 23:30:00.284 plugin-container[1925:20901] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883b, name = 'com.apple.tsm.portname' 23:30:00 INFO - PROCESS | 1924 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:30:00 INFO - PROCESS | 1924 | 2017-01-24 23:30:00.285 plugin-container[1925:20901] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 23:30:00 INFO - PROCESS | 1924 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:30:00 INFO - PROCESS | 1924 | 2017-01-24 23:30:00.285 plugin-container[1925:20901] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:30:10 INFO - PROCESS | 1924 | RSS: Main: 233144320 23:30:10 INFO - PROCESS | 1924 | 23:30:13 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56524/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56524/tests/kraken/driver.html?testName=audio-beat-detection) 23:30:13 INFO - PROCESS | 1924 | RSS: Main: 246779904 23:30:13 INFO - PROCESS | 1924 | 23:30:16 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56524/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56524/tests/kraken/driver.html?testName=audio-dft) 23:30:17 INFO - PROCESS | 1924 | RSS: Main: 254464000 23:30:17 INFO - PROCESS | 1924 | 23:30:20 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56524/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56524/tests/kraken/driver.html?testName=audio-fft) 23:30:21 INFO - PROCESS | 1924 | RSS: Main: 257093632 23:30:21 INFO - PROCESS | 1924 | 23:30:23 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56524/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56524/tests/kraken/driver.html?testName=audio-oscillator) 23:30:24 INFO - PROCESS | 1924 | RSS: Main: 259330048 23:30:24 INFO - PROCESS | 1924 | 23:30:26 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56524/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56524/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:30:26 INFO - PROCESS | 1924 | RSS: Main: 259989504 23:30:26 INFO - PROCESS | 1924 | 23:30:29 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56524/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56524/tests/kraken/driver.html?testName=imaging-darkroom) 23:30:30 INFO - PROCESS | 1924 | RSS: Main: 265568256 23:30:30 INFO - PROCESS | 1924 | 23:30:33 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56524/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56524/tests/kraken/driver.html?testName=imaging-desaturate) 23:30:34 INFO - PROCESS | 1924 | RSS: Main: 265265152 23:30:34 INFO - PROCESS | 1924 | 23:30:37 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56524/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56524/tests/kraken/driver.html?testName=json-parse-financial) 23:30:37 INFO - PROCESS | 1924 | RSS: Main: 265052160 23:30:37 INFO - PROCESS | 1924 | 23:30:39 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56524/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56524/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:30:40 INFO - PROCESS | 1924 | RSS: Main: 264208384 23:30:40 INFO - PROCESS | 1924 | 23:30:42 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56524/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56524/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:30:43 INFO - PROCESS | 1924 | RSS: Main: 264716288 23:30:43 INFO - PROCESS | 1924 | 23:30:45 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56524/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56524/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:30:45 INFO - PROCESS | 1924 | RSS: Main: 264761344 23:30:45 INFO - PROCESS | 1924 | 23:30:48 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56524/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56524/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:30:49 INFO - PROCESS | 1924 | RSS: Main: 264581120 23:30:49 INFO - PROCESS | 1924 | 23:30:52 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56524/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56524/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:30:53 INFO - PROCESS | 1924 | RSS: Main: 264470528 23:30:53 INFO - PROCESS | 1924 | 23:30:55 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:56524/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56524/tests/kraken/driver.html?testName=ai-astar) 23:30:55 INFO - PROCESS | 1924 | RSS: Main: 267665408 23:30:55 INFO - PROCESS | 1924 | 23:30:55 INFO - PROCESS | 1924 | __start_tp_report 23:30:55 INFO - PROCESS | 1924 | _x_x_mozilla_page_load 23:30:55 INFO - PROCESS | 1924 | _x_x_mozilla_page_load_details 23:30:55 INFO - PROCESS | 1924 | |i|pagename|runs| 23:30:55 INFO - PROCESS | 1924 | |0;ai-astar;106;117;119;118;117;123;118;118;117;118 23:30:55 INFO - PROCESS | 1924 | |1;audio-beat-detection;133;127;146;188;118;117;114;116;132;121 23:30:55 INFO - PROCESS | 1924 | |2;audio-dft;203;210;275;234;250;281;198;202;191;198 23:30:55 INFO - PROCESS | 1924 | |3;audio-fft;85;61;61;88;85;52;70;84;100;90 23:30:55 INFO - PROCESS | 1924 | |4;audio-oscillator;87;73;83;74;74;106;118;103;72;82 23:30:55 INFO - PROCESS | 1924 | |5;imaging-gaussian-blur;91;132;89;104;76;132;135;87;88;85 23:30:55 INFO - PROCESS | 1924 | |6;imaging-darkroom;110;140;118;144;93;174;171;102;101;104 23:30:55 INFO - PROCESS | 1924 | |7;imaging-desaturate;91;92;92;92;104;91;93;94;91;92 23:30:55 INFO - PROCESS | 1924 | |8;json-parse-financial;61;61;59;71;100;96;96;89;61;64 23:30:55 INFO - PROCESS | 1924 | |9;json-stringify-tinderbox;75;80;73;89;63;90;111;110;113;76 23:30:55 INFO - PROCESS | 1924 | |10;stanford-crypto-aes;104;109;92;90;90;87;89;91;91;90 23:30:55 INFO - PROCESS | 1924 | |11;stanford-crypto-ccm;176;163;153;140;144;161;155;163;208;180 23:30:55 INFO - PROCESS | 1924 | |12;stanford-crypto-pbkdf2;179;180;179;176;176;242;193;174;181;232 23:30:55 INFO - PROCESS | 1924 | |13;stanford-crypto-sha256-iterative;67;70;70;69;70;67;68;66;67;67 23:30:55 INFO - PROCESS | 1924 | __end_tp_report 23:30:55 INFO - PROCESS | 1924 | __start_cc_report 23:30:55 INFO - PROCESS | 1924 | _x_x_mozilla_cycle_collect,955 23:30:55 INFO - PROCESS | 1924 | __end_cc_report 23:30:55 INFO - PROCESS | 1924 | __startTimestamp1485329455415__endTimestamp 23:30:55 INFO - PROCESS | 1924 | 23:30:55 INFO - PROCESS | 1924 | ------- Summary: start ------- 23:30:55 INFO - PROCESS | 1924 | Number of tests: 14 23:30:55 INFO - PROCESS | 1924 | 23:30:55 INFO - PROCESS | 1924 | [#0] ai-astar Cycles:10 Average:117.10 Median:118.00 stddev:4.28 (3.6%) stddev-sans-first:1.87 23:30:55 INFO - PROCESS | 1924 | Values: 106.0 117.0 119.0 118.0 117.0 123.0 118.0 118.0 117.0 118.0 23:30:55 INFO - PROCESS | 1924 | 23:30:55 INFO - PROCESS | 1924 | [#1] audio-beat-detection Cycles:10 Average:131.20 Median:129.50 stddev:22.29 (17.2%) stddev-sans-first:23.64 23:30:55 INFO - PROCESS | 1924 | Values: 133.0 127.0 146.0 188.0 118.0 117.0 114.0 116.0 132.0 121.0 23:30:55 INFO - PROCESS | 1924 | 23:30:55 INFO - PROCESS | 1924 | [#2] audio-dft Cycles:10 Average:224.20 Median:222.00 stddev:33.64 (15.2%) stddev-sans-first:34.80 23:30:55 INFO - PROCESS | 1924 | Values: 203.0 210.0 275.0 234.0 250.0 281.0 198.0 202.0 191.0 198.0 23:30:55 INFO - PROCESS | 1924 | 23:30:55 INFO - PROCESS | 1924 | [#3] audio-fft Cycles:10 Average:77.60 Median:84.50 stddev:15.56 (18.4%) stddev-sans-first:16.27 23:30:55 INFO - PROCESS | 1924 | Values: 85.0 61.0 61.0 88.0 85.0 52.0 70.0 84.0 100.0 90.0 23:30:55 INFO - PROCESS | 1924 | 23:30:55 INFO - PROCESS | 1924 | [#4] audio-oscillator Cycles:10 Average:87.20 Median:74.00 stddev:16.25 (22.0%) stddev-sans-first:17.24 23:30:55 INFO - PROCESS | 1924 | Values: 87.0 73.0 83.0 74.0 74.0 106.0 118.0 103.0 72.0 82.0 23:30:55 INFO - PROCESS | 1924 | 23:30:55 INFO - PROCESS | 1924 | [#5] imaging-gaussian-blur Cycles:10 Average:101.90 Median:86.00 stddev:22.53 (26.2%) stddev-sans-first:23.55 23:30:55 INFO - PROCESS | 1924 | Values: 91.0 132.0 89.0 104.0 76.0 132.0 135.0 87.0 88.0 85.0 23:30:55 INFO - PROCESS | 1924 | 23:30:55 INFO - PROCESS | 1924 | [#6] imaging-darkroom Cycles:10 Average:125.70 Median:142.00 stddev:29.71 (20.9%) stddev-sans-first:30.96 23:30:55 INFO - PROCESS | 1924 | Values: 110.0 140.0 118.0 144.0 93.0 174.0 171.0 102.0 101.0 104.0 23:30:55 INFO - PROCESS | 1924 | 23:30:55 INFO - PROCESS | 1924 | [#7] imaging-desaturate Cycles:10 Average:93.20 Median:92.00 stddev:3.91 (4.3%) stddev-sans-first:4.07 23:30:55 INFO - PROCESS | 1924 | Values: 91.0 92.0 92.0 92.0 104.0 91.0 93.0 94.0 91.0 92.0 23:30:55 INFO - PROCESS | 1924 | 23:30:55 INFO - PROCESS | 1924 | [#8] json-parse-financial Cycles:10 Average:75.80 Median:67.50 stddev:17.25 (25.6%) stddev-sans-first:17.44 23:30:55 INFO - PROCESS | 1924 | Values: 61.0 61.0 59.0 71.0 100.0 96.0 96.0 89.0 61.0 64.0 23:30:55 INFO - PROCESS | 1924 | 23:30:55 INFO - PROCESS | 1924 | [#9] json-stringify-tinderbox Cycles:10 Average:88.00 Median:75.50 stddev:17.86 (23.7%) stddev-sans-first:18.31 23:30:55 INFO - PROCESS | 1924 | Values: 75.0 80.0 73.0 89.0 63.0 90.0 111.0 110.0 113.0 76.0 23:30:55 INFO - PROCESS | 1924 | 23:30:55 INFO - PROCESS | 1924 | [#10] stanford-crypto-aes Cycles:10 Average:93.30 Median:90.00 stddev:7.18 (8.0%) stddev-sans-first:6.49 23:30:55 INFO - PROCESS | 1924 | Values: 104.0 109.0 92.0 90.0 90.0 87.0 89.0 91.0 91.0 90.0 23:30:55 INFO - PROCESS | 1924 | 23:30:55 INFO - PROCESS | 1924 | [#11] stanford-crypto-ccm Cycles:10 Average:164.30 Median:163.00 stddev:19.79 (12.1%) stddev-sans-first:20.53 23:30:55 INFO - PROCESS | 1924 | Values: 176.0 163.0 153.0 140.0 144.0 161.0 155.0 163.0 208.0 180.0 23:30:55 INFO - PROCESS | 1924 | 23:30:55 INFO - PROCESS | 1924 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.20 Median:180.50 stddev:24.80 (13.7%) stddev-sans-first:25.90 23:30:55 INFO - PROCESS | 1924 | Values: 179.0 180.0 179.0 176.0 176.0 242.0 193.0 174.0 181.0 232.0 23:30:55 INFO - PROCESS | 1924 | 23:30:55 INFO - PROCESS | 1924 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.50 stddev:1.52 (2.2%) stddev-sans-first:1.56 23:30:55 INFO - PROCESS | 1924 | Values: 67.0 70.0 70.0 69.0 70.0 67.0 68.0 66.0 67.0 67.0 23:30:55 INFO - PROCESS | 1924 | -------- Summary: end -------- 23:30:55 INFO - PROCESS | 1924 | 23:30:56 INFO - TEST-INFO | 1924: exit 0 23:30:56 INFO - TEST-OK | kraken | took 62117ms 23:30:56 INFO - SUITE-END | took 1177s 23:30:56 INFO - Completed test suite (00:19:37) 23:30:56 INFO - javascript benchmark 23:30:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7275.674206030923, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3324.983375083126, 3346.3140056288457, 3363.4282172030785, 3364.461686130219, 3379.205886616645, 5590.66456012639, 5595.38450770415, 5645.802396916109, 5648.181013753008, 5699.259096317483, 5611.2144299798065, 5639.407862174474, 5666.716664166795, 5689.8577535561635, 5725.770969161239, 4256.276433006381, 5142.202958541432, 5156.1234590119675, 5187.299714538545, 5226.738663066851, 5145.0, 5250.107481728096, 5276.55149312307, 5291.497307755757, 5302.443243459439, 5071.985602879402, 5089.491050894918, 5194.844154675366, 5216.347956505437, 5219.556337711299, 5103.387593488768, 5132.94774571212, 5155.71643559604, 5189.714565698885, 5257.185136303879, 5114.667546609458, 5121.718305493197, 5125.128728116192, 5132.40977287613, 5138.460461651547, 5553.611247212697, 5598.684309187338, 5624.71876406178, 5636.18275350072, 5656.095024796013, 5582.441755824426, 6058.091286307045, 6149.877002459926, 6158.137860699506, 6207.441330280251, 6196.752129914798, 6204.317525072239, 6229.968850155766, 6230.096635987757, 6232.065190221458, 4204.726692764961, 4483.8790302424395, 5858.238429004202, 5866.794662186846, 5949.1076338549565, 6171.47542458896, 6186.659733714599, 6203.286622038433, 6209.037599172136, 6223.906641400383, 6103.609672365017, 6105.8540098850235, 6175.783847565313, 6178.320384757673, 6200.906986395207, 6118.898598252357, 6158.599691020069, 6219.191505104308, 6249.25008998923, 6266.185395898504, 5750.108733146369, 6074.90887636677, 6147.262328520606, 6154.476869466145, 6239.188905442264, 5152.355955505562, 6139.723712432951, 6212.658303793244, 6219.973704338733, 6257.530685198628, 4982.9252561211615, 4989.201727723619, 4995.100881841231, 4998.775055122528, 5023.472535383805, 4374.12517496502, 4398.340248962617, 4409.713368631029, 4416.933745993812, 4455.554444555519, 5749.827505174768, 5791.986402379651, 5820.534357251409, 5839.883202335929, 5862.179294898718, 6182.721777520022, 6188.4739797116845, 6238.40735130171, 6241.469475094577, 6265.561410701207, 5670.631408958405, 5746.856328591735, 5773.480386765127, 5796.333421656479, 5811.389804070596, 5418.485243901901, 5501.229827824108, 5524.806631767869, 5541.473560011793, 5550.47270509301, 6395.744170233139, 6414.198225221754, 6414.807555773335, 6426.453751430994, 6441.67791610427, 6137.989923150331, 6140.600860944024, 6252.812415627538, 6254.843628909131, 6277.874442511033, 6150.723217455315, 6185.474234690102, 6191.4118158774845, 6206.317305096526, 6262.0, 5059.696418214919, 6163.260408750979, 6165.907511387243, 6196.907046394217, 6207.472364848858, 6079.452849243589, 6215.627062376091, 6223.906641400292, 6225.066240064027, 6231.9688401557705, 4736.265878788793, 5969.791057312973, 6067.544934130046, 6121.602095863755, 6124.716609780976, 6041.758329666764, 6102.115193296859, 6116.3272040076445, 6116.877662446637, 6138.109974053648, 4708.293755936637, 5852.18069470283, 5926.822195334147, 5938.020226662725, 5973.074173503114, 6083.057126145454, 6116.853194527988, 6117.857853872152, 6133.120447061136, 6159.969200153971, 5931.288245410578, 5950.137230101524, 5963.254593175853, 5977.820665380045, 6020.157177994996], "value": 5641.067543160633, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [436.60923473491226, 482.0431443584486, 487.3299213581326, 488.8024340366103, 544.300573762715, 40706.0, 43279.78360108203, 43380.566194338084, 43392.91517712059, 43439.565604343945, 36160.81919590398, 43067.78466107665, 43636.345454818205, 43870.90322741933, 43881.780591097, 9269.814603707939, 9488.762780930463, 9493.193078588294, 9517.952410237938, 11119.833202501968, 11223.494942727597, 11493.482793274323, 11538.076953843714, 11542.961133497984, 11564.71088222797, 11516.424178791069, 11549.826752598718, 11557.0, 11566.363849988247, 11579.768404631903, 11485.59800406986, 11548.845115488468, 11553.0, 11568.942155289255, 11574.6527604172, 11141.997220250136, 11197.49611267495, 11240.718982025433, 11294.040006599367, 11349.659510214708, 31958.6804131958, 38270.08649567539, 38444.80777596122, 38548.807255963824, 38944.22111557781, 3918.7060970427046, 4011.0373510357304, 4011.4183443400834, 4025.0943537704075, 4033.0320723026557, 29333.413331733245, 29800.275277505487, 30216.0, 30496.085117446513, 30497.390052199054, 3162.4623813951684, 3282.2122690554097, 3295.703386695209, 3350.4471762159214, 3352.6982571568433, 425248.0, 430200.0, 431476.0, 431604.52593211125, 432921.99999999686, 56554.7172264136, 56654.43345566492, 56929.0, 57073.0, 57230.0, 56611.86776264451, 56941.43058569444, 56973.86052278931, 56998.43001569931, 57275.56811079756, 14901.180435076174, 15323.31045102973, 15359.0, 15372.154531500875, 15385.9230703848, 9832.360896541702, 10096.848547271797, 11490.942545287387, 11676.416179191006, 11726.589569365031, 17908.91045544764, 18060.819391806177, 18103.72844407335, 18112.909435452737, 18214.0, 6519.967400163064, 7127.218195451137, 7279.818004549928, 7501.399888008947, 7726.536407815549, 870.5299138465284, 873.7990262239593, 878.7934835313696, 907.6324088744069, 912.8265629530368, 31399.058028259187, 31490.0, 31801.0, 31915.36169276648, 32001.0, 921.0283151275469, 945.7682867697458, 988.3625061835077, 1086.9293495922739, 1249.5314257153568, 15898.284577194054, 16491.34034638625, 16517.256723447474, 16637.33450661984, 16667.497582036216, 647896.0, 652822.0, 653456.0, 654015.0, 655655.0, 62212.53449586079, 62947.6852615734, 63434.365656342845, 63652.0, 63723.681381592796, 1414.7524183267888, 1449.6013596260902, 1472.4625511688157, 1473.0425223604743, 1475.513080683372, 29411.11766647004, 30382.84808575943, 30438.543421849132, 30566.694333056384, 31059.844700777256, 53813.92372152613, 54100.0, 54111.188332175836, 54191.0, 54419.55804419526, 537431.0, 539270.0, 539510.0, 540043.0, 540852.0, 22099.779002210416, 23966.40083997914, 24578.38554036092, 26557.734422655525, 27424.45151096927, 49348.766280843265, 49806.0, 50136.0, 50245.74877125591, 50643.24035139402, 30327.0, 35161.94514164579, 37310.62689373072, 39136.87747486152, 43315.71922071345, 33315.83342083274, 36756.08109797276, 37121.886343409744, 37503.81248093742, 40673.79663101665, 30697.846510767304, 37122.628773713, 38189.2362152761, 38673.71273441677, 39557.0, 34500.654993449745, 35606.821965890005, 35648.9265150047, 36045.0, 36133.0, 25674.22977310609, 25836.8708156458, 25895.611565826905, 26317.868410657826, 26412.735872641028], "value": 20211.44705961611, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.51957979459064, 383.31960769633895, 392.68977507768864, 399.44078290393463, 413.36548398208754, 19568.31510897119, 20965.47586310343, 21724.782752172472, 21762.347129586116, 22074.779252207492, 22083.227087051917, 22226.999785009673, 22266.443338916535, 22484.213052543164, 22584.096636134575, 5455.918161227575, 5875.941240587604, 5883.588148829584, 5939.198208241875, 5954.70226488676, 4155.896102597444, 4368.97815510922, 4987.75061246938, 5854.648721076728, 5889.941100589003, 4358.302671572549, 6606.372394622503, 6608.107905432753, 6697.263301036882, 6703.564268322568, 6517.4460170885295, 6605.9339406605795, 6652.168478940132, 6657.0, 6669.233038200597, 5807.89649966505, 5823.970880145572, 5838.153467747153, 5839.445252701029, 5847.415258474119, 25598.23205303844, 25801.483970320674, 25836.224913252634, 25860.74139258602, 26155.476890462276, 2697.8381297122237, 2754.807163498556, 2766.9308267293277, 2772.9722702772915, 2775.9583606245924, 13324.200547967055, 14826.258687065603, 15876.52370428889, 15915.681686366208, 16094.275757590936, 1839.8068202838642, 1881.2663061406063, 1893.2427029188298, 1895.649304878602, 1910.2072639854443, 85557.2888542232, 116100.0, 117226.0, 118098.22852657217, 118197.0, 9342.0, 11547.942260288813, 11596.652100437, 11658.358790266617, 11869.643910682693, 13255.005874559447, 13595.932020339835, 14323.78514322286, 14662.266886655625, 14721.484748033767, 7031.92968070323, 8295.087540370554, 8429.452085614415, 8493.617787199591, 8521.060234219185, 7384.593847338448, 7910.802229944297, 8228.876566851503, 8366.0, 8368.83262334762, 7286.198518162998, 7382.667779949915, 7407.85184296311, 7424.591647459333, 7456.850862982818, 1678.5048410718866, 1912.8278454939123, 1939.9612007759765, 1956.178405069874, 1966.9409917702492, 1032.5270976012407, 1052.2003277508995, 1063.3285556288326, 1082.118073769875, 1116.916231282657, 18829.52926176829, 20039.797612143317, 20555.8972205138, 22570.435739106324, 22874.313770586577, 882.4176043811054, 1015.7100482387474, 1063.1813503602184, 1071.2501249125644, 1086.21792309537, 8800.515971621622, 9980.351277167107, 10561.313514621525, 11972.880271197177, 12139.393030348447, 393873.0, 401931.0, 675857.0, 714417.0, 727717.0, 132724.0, 138781.0, 156483.0, 157288.0, 157821.0, 773.8645736996003, 1049.0801832646391, 1056.9788604228027, 1056.9947150264354, 1058.7141471802724, 19568.510787230432, 20305.109387291544, 20402.79597204009, 20456.897715511328, 20459.69310460374, 58887.41112588819, 60680.0, 60991.0, 61267.693661531404, 61301.693491532256, 89106.10893891238, 92819.0, 93850.0, 93878.53060734925, 94524.52737736267, 8005.39959503028, 8056.154103819249, 8057.677692892219, 8459.154084591491, 8481.660733570588, 9055.275577953616, 12363.69090772702, 13188.472461101448, 13495.797563036365, 13542.932285338511, 8967.820643587222, 10820.242583019113, 11194.83207751868, 11255.212135150461, 11706.648800535997, 10686.8931310689, 10891.346519208873, 11082.501287441923, 11146.832797507881, 11267.830982535104, 11311.547538098714, 11466.94266528662, 11898.286102833858, 11940.820887686517, 11956.402179890796, 6833.487488438288, 8149.79625509367, 8181.754547363588, 8393.83212335762, 8448.662053518035, 10447.84328235062, 11225.550977960791, 11297.548098075986, 11298.717532061764, 11398.658040258806], "value": 10071.09627399377, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1247.7941139711947, 1380.4685196199466, 1397.4410235905643, 1405.578326502049, 1417.7873319002144, 7398.408127349812, 7650.158482566913, 7819.530828150308, 8218.465799723013, 8259.752207433772, 6661.500387470935, 7826.25650563197, 8087.514749115057, 8213.178682131784, 8233.794155146124, 2211.8672879627193, 2275.6131457652154, 2279.544091181762, 2279.760625134362, 2281.2813963601498, 2014.4057503036565, 2257.9435514112115, 2290.1068583252545, 2295.7589453107435, 2299.5056062946464, 2033.806788355104, 2176.9564608707815, 2257.7064981552458, 2263.807576356012, 2271.284545368204, 2209.8674079555276, 2216.512367279196, 2229.8962013803107, 2233.519793244444, 2240.8879556022216, 2230.11910295434, 2258.9661155082686, 2282.132789539981, 2285.074544809355, 2288.3821368230483, 8276.668933242723, 8309.335253179792, 8322.542260175687, 8331.50010999342, 8364.079951205362, 4893.7063776173545, 4896.902061958741, 5055.5702765265, 5057.822976195852, 5068.670536415121, 3212.6144862616634, 3307.7353811695248, 3440.673136052071, 3443.9827800861085, 3451.758376913617, 1038.7143535527714, 1172.5602898912907, 1236.5098960614841, 1240.1876770715196, 1255.6798016505825, 10653.094486968617, 10721.410322432263, 10781.08360789326, 10784.406857622827, 10805.0, 3991.002249437641, 4041.7372870763306, 4505.121501307279, 4551.180787458289, 4571.542845715402, 7877.803054923673, 9188.724338269863, 9296.209822165192, 9355.0, 9364.859527107099, 5009.198528235464, 5103.8724031898755, 5113.897722045613, 5115.820946266863, 5119.846404607868, 4460.219461594208, 4587.587117159472, 4641.187792136363, 4676.041411510632, 4677.7193368398, 3614.710823134143, 3650.2699460108083, 3658.1951970566433, 3675.503806986076, 3707.2214834884435, 959.2709540749081, 986.3243678080538, 1065.733566608348, 1090.2477290669412, 1144.347721798567, 773.7407968330559, 858.2876212743408, 858.6136238692613, 861.6294993153004, 917.4449458077901, 4838.225883858566, 5394.865128371822, 5551.111822108443, 5603.831885043455, 5812.360640329645, 961.2646325560952, 975.8487434447673, 984.8473486609587, 1002.4236064263077, 1008.1380419741163, 3558.5195998540385, 3608.729345299113, 3610.3862343401684, 3696.8706095286534, 3745.419459983707, 9356.812863742687, 9639.855402168972, 9963.900360996442, 9982.251331150193, 10005.349652272578, 9839.311248212556, 9945.950270248602, 9953.203743700487, 9962.551685174185, 9971.351862128939, 7684.0, 7782.883256751154, 7812.84374312522, 7822.0, 7830.334421574116, 4622.445306563234, 5149.304843846033, 5375.865603359947, 5397.271368365377, 5399.70301633414, 9001.32490063261, 9073.047330030251, 9102.31732620043, 9161.358704890594, 9202.35583509148, 9258.95370523143, 9279.283332583494, 9286.95356523213, 9312.20846228051, 9386.859197111913, 5601.131824567198, 6108.778244351236, 6162.322144564005, 6789.558678685869, 6803.115594972622, 7750.0, 7756.224377562425, 7785.844283114419, 7837.412194085352, 7846.215378462108, 6283.183186185766, 7033.859322813618, 7074.115735533059, 7096.574205547684, 7101.041359416413, 7038.01467794499, 7049.048378468841, 7050.929490705027, 7128.536645118051, 7130.001799748143, 6160.966880929373, 6285.905711414241, 6391.936080639134, 6403.679816009274, 6406.487481001602, 4831.130396528659, 4834.854954351375, 4864.562189402829, 4905.583025442877, 5033.2701758245585, 4863.537963893425, 4870.95129048705, 4938.7530623467965, 4986.675866068694, 5037.042961837239], "value": 4294.834758413522, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.2646022732944, 358.6592736899945, 366.9174435751956, 369.5214696967427, 371.96838268747155, 4262.9147417051645, 5347.25138480613, 5420.403755586887, 5493.857395615713, 5531.0, 4671.462781780087, 4721.984773273745, 5179.378474583055, 5366.570674346043, 5393.460653934614, 3084.722374986251, 3678.7792732436005, 4113.629773320401, 4258.063226090255, 4284.657227421814, 3857.9614203858023, 3981.28336899358, 4053.736507127042, 4242.936355954663, 4304.385855304261, 2991.8952836650724, 3524.1365865363023, 3801.5058042454443, 4092.283850326196, 4117.691173162025, 3338.3490219407227, 3548.769329993542, 3884.281407939526, 4074.2666320062376, 4121.690873184523, 2950.409918016384, 3246.0099669600763, 3357.143928298293, 3528.929421411583, 3545.2170046513365, 4475.485319188302, 5007.593317880279, 5221.164613661796, 5229.411441336525, 5259.316288882421, 4605.263157894754, 4609.67732258742, 4612.884677883079, 4639.744814035227, 4676.445901115177, 4925.867050578387, 5229.712365819878, 5450.53890779354, 5616.271403168745, 5636.943630563683, 3731.925361492782, 3829.329867273216, 3849.2493963677093, 3862.884113476572, 3881.068543549556, 5614.9157762633595, 5864.941350586482, 5895.349302195392, 5938.0, 5962.672053037081, 3203.8237896915894, 3435.886929538316, 3924.3321588324848, 3932.941005884914, 3958.2083583283456, 3177.7298929591243, 3700.962990370062, 3969.8412063517135, 3985.9202815943518, 4036.8183431745642, 3870.6903447724117, 3954.4859168307935, 4441.400410944481, 4605.286180642006, 4610.216263235258, 4920.6801557898625, 5252.474752524717, 5558.333000040021, 5748.396418376094, 5774.624649397776, 1195.4859410453414, 1225.5159212111166, 1258.2010423381037, 1270.30133426615, 1290.6321698315935, 795.9124496305402, 796.3828033274143, 805.6898094233762, 851.6465666748354, 884.7611144990946, 5053.444121146671, 5074.391073071255, 5081.74591270435, 5106.744662766847, 5137.023965446553, 5151.4333423323405, 5556.694381809039, 5568.331800184003, 5583.860403489945, 5612.961602103624, 4379.0, 4564.954350456519, 4828.927566086511, 4927.7536123193695, 5026.165449610939, 2891.6433107017715, 2949.454350945082, 2950.9557356639666, 2966.1991262359475, 2970.1288188441144, 4690.577847993697, 4798.2802579613335, 5286.682799032071, 5545.861353466195, 5774.422557744276, 4686.625069994392, 4717.575418212377, 5279.076161671692, 5310.681359118388, 5716.627599546142, 4803.735794531334, 5694.4590263924865, 5748.741306641211, 5793.798356624456, 5825.271841019872, 4736.0, 4880.92678609814, 5530.0, 5530.446955304598, 5535.750891209825, 4832.299316599144, 5082.695038297714, 5774.220480235114, 5791.34946540234, 5857.94142058591, 5838.503727183237, 6046.3046749624355, 6062.242219722535, 6092.39076092405, 6133.50931925436, 3407.965920340765, 3460.0, 3729.0304520825325, 3909.4135879617147, 3954.387070004154, 4405.625521830552, 4962.528559786814, 5278.920816187837, 5828.766849326149, 5923.526117910643, 4175.659472421989, 4765.523447655345, 5132.743362831919, 5734.168545560954, 5748.338941021669, 4783.689060211075, 4813.614910807057, 4957.454679985127, 5562.3047119110115, 5735.627184233012, 5173.991071740975, 5236.266922630911, 5525.806596769094, 5587.99416105105, 5600.35595906476, 4911.4106307243355, 5013.849584512471, 5181.53366197044, 5245.370555533361, 5647.1599312467915, 4688.906221875475, 5285.339332583427, 5451.001948927995, 5578.414266502121, 5605.579581531483], "value": 4012.902892688159, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.9789106326812, 725.4414101142117, 731.4404480572363, 773.4122067228908, 825.6243409248792, 14655.780163297546, 15552.533423997284, 16974.91512542437, 17573.472795816113, 17649.470515884514, 16770.74843877343, 16892.901961372532, 16932.83067169328, 16943.068131252807, 16993.490195294162, 603.6468665830472, 853.5774791478227, 875.1729615513342, 875.5665945357025, 878.14819624964, 655.619740550482, 843.3927572148043, 863.5034854958393, 880.0363601855958, 881.0484676549312, 916.331078312832, 919.0396036142214, 922.1884741427534, 922.492629054021, 923.995380023099, 903.8734577159203, 909.1726528858773, 909.3043821476575, 910.094456016268, 911.2800887299026, 568.5423234296389, 672.1698702102899, 678.5724993254267, 685.6331862453613, 687.4397366146575, 13005.544805931748, 15178.924105379403, 15237.847621523864, 15401.922990384975, 15671.138087405188, 14147.64630884236, 14310.069845460011, 14420.062695924731, 14469.49356772508, 14544.70910581793, 11783.585969683692, 11894.464749086312, 11917.344546049964, 11946.522139114424, 11987.82018269718, 3603.8738644147593, 3648.6168952259895, 3652.7808331500196, 3687.4284485904727, 3770.7926064066464, 101043.0, 107225.21750396138, 107764.30589235254, 112849.0, 113524.0, 11005.449727513593, 11049.723756906142, 11068.557257709763, 11080.5567777288, 11267.830982535268, 22435.19560054993, 22647.88676056642, 22728.43178920507, 22827.31518054428, 22835.543289134122, 6519.512061047317, 7632.389408847279, 7642.4650274480255, 7687.961560192163, 7716.7684969451, 4179.644730197906, 4229.577042295746, 4247.915041699149, 4275.722078064916, 4367.58507941745, 3490.3717330880686, 3519.8416071276856, 3559.893203203908, 3567.6432356764117, 3573.9642603574152, 606.3572613030202, 648.9870202595922, 649.6491894376984, 654.6857508395997, 665.4310564467407, 650.203500711634, 653.6372313366032, 682.7985744205372, 690.6926417744056, 701.6667083135571, 9989.80020399588, 10212.285140040274, 10392.844107338396, 10485.580576776996, 10553.84169237462, 654.5418207254845, 668.7204520312108, 687.9105716256854, 706.2055187913597, 707.4128473367093, 3999.9203995940215, 4220.915581688349, 4402.823887044547, 4970.229614409773, 5024.773885175176, 76962.0, 80119.19880801234, 88724.11275887159, 99717.0, 107905.0, 73483.63258183675, 90694.0, 90814.54592726994, 90926.0, 96963.51518242505, 50619.0, 53509.73245133749, 63329.05006425, 65150.34849651538, 80097.99755006055, 10806.531912775192, 10873.401962891801, 10932.414989210387, 10933.343999360064, 11212.0, 49758.50241497539, 50756.679889230734, 51153.0, 52783.47216527939, 53852.19221711599, 55626.721866390406, 59128.40871591401, 66336.0, 68474.65762671155, 69400.30599693938, 12869.420876060787, 12930.482780688668, 12995.480180792661, 13002.609921702364, 13298.734025319633, 14452.855471445151, 14574.271286435423, 14652.853471465576, 14674.486392976622, 14906.552803415481, 13375.53185638498, 13408.32958352098, 13642.386092625657, 13663.316834158053, 14044.0, 13520.79718804199, 13615.591532254048, 13671.316434178052, 13832.100913440594, 14141.292935352985, 13299.135556188818, 13304.13523120994, 13492.49195209233, 13567.879387740677, 13892.652683682989, 9330.860037099314, 11033.613823516138, 11117.444127793417, 11259.605913792979, 11698.883011169779, 9407.0, 10261.486925653538, 10462.358773633467, 10712.89287107119, 10743.65756584053], "value": 7494.5949849016615, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1638.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 117.0, 119.0, 118.0, 117.0, 123.0, 118.0, 118.0, 117.0, 118.0], "value": 117.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 127.0, 146.0, 188.0, 118.0, 117.0, 114.0, 116.0, 132.0, 121.0], "value": 131.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 210.0, 275.0, 234.0, 250.0, 281.0, 198.0, 202.0, 191.0, 198.0], "value": 224.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 61.0, 61.0, 88.0, 85.0, 52.0, 70.0, 84.0, 100.0, 90.0], "value": 77.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 73.0, 83.0, 74.0, 74.0, 106.0, 118.0, 103.0, 72.0, 82.0], "value": 87.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 132.0, 89.0, 104.0, 76.0, 132.0, 135.0, 87.0, 88.0, 85.0], "value": 101.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 140.0, 118.0, 144.0, 93.0, 174.0, 171.0, 102.0, 101.0, 104.0], "value": 125.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 92.0, 92.0, 104.0, 91.0, 93.0, 94.0, 91.0, 92.0], "value": 93.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 59.0, 71.0, 100.0, 96.0, 96.0, 89.0, 61.0, 64.0], "value": 75.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 80.0, 73.0, 89.0, 63.0, 90.0, 111.0, 110.0, 113.0, 76.0], "value": 88.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 109.0, 92.0, 90.0, 90.0, 87.0, 89.0, 91.0, 91.0, 90.0], "value": 93.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 163.0, 153.0, 140.0, 144.0, 161.0, 155.0, 163.0, 208.0, 180.0], "value": 164.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 180.0, 179.0, 176.0, 176.0, 242.0, 193.0, 174.0, 181.0, 232.0], "value": 191.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 70.0, 69.0, 70.0, 67.0, 68.0, 66.0, 67.0, 67.0], "value": 68.1, "unit": "score"}]}]} 23:30:56 INFO - Return code: 0 23:30:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:30:56 ERROR - # TBPL SUCCESS # 23:30:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:30:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:30:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:30:56 INFO - 2017-01-24 23:30:56.726 screenresolution[1930:21283] starting screenresolution argv=screenresolution get 23:30:56 INFO - 2017-01-24 23:30:56.728 screenresolution[1930:21283] Display 0: 1600x1200x32@60 23:30:56 INFO - 2017-01-24 23:30:56.736 screenresolution[1931:21286] starting screenresolution argv=screenresolution list 23:30:56 INFO - Available Modes on Display 0 23:30:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:30:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:30:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:30:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:30:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:30:56 INFO - Intel Iris: 23:30:56 INFO - Chipset Model: Intel Iris 23:30:56 INFO - Type: GPU 23:30:56 INFO - Bus: Built-In 23:30:56 INFO - VRAM (Dynamic, Max): 1536 MB 23:30:56 INFO - Vendor: Intel (0x8086) 23:30:56 INFO - Device ID: 0x0a2e 23:30:56 INFO - Revision ID: 0x0009 23:30:56 INFO - Displays: 23:30:56 INFO - Display: 23:30:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:30:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:30:56 INFO - Display Serial Number: 1600x1200 60 23:30:56 INFO - Main Display: Yes 23:30:56 INFO - Mirror: Off 23:30:56 INFO - Online: Yes 23:30:56 INFO - Rotation: Supported 23:30:56 INFO - Return code: 0 23:30:56 INFO - Running post-action listener: _resource_record_post_action 23:30:56 INFO - [mozharness: 2017-01-25 07:30:56.980122Z] Finished run-tests step (success) 23:30:56 INFO - Running post-run listener: _resource_record_post_run 23:30:57 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 1041920; Write bytes: 554379776; Read time: 13; Write time: 1689 23:30:57 INFO - TinderboxPrint: CPU usage
54.3% 23:30:57 INFO - TinderboxPrint: I/O read bytes / time
1,041,920 / 13 23:30:57 INFO - TinderboxPrint: I/O write bytes / time
554,379,776 / 1,689 23:30:57 INFO - TinderboxPrint: CPU idle
2,198.3 (45.7%) 23:30:57 INFO - TinderboxPrint: CPU system
965.7 (20.1%) 23:30:57 INFO - TinderboxPrint: CPU user
1,641.7 (34.2%) 23:30:57 INFO - TinderboxPrint: Swap in / out
252,641,280 / 0 23:30:57 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 241261056; Write bytes: 235292160; Read time: 17655; Write time: 492 23:30:57 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 1041920; Write bytes: 299529216; Read time: 13; Write time: 1142 23:30:57 INFO - Running post-run listener: _upload_blobber_files 23:30:57 INFO - Blob upload gear active. 23:30:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:30:57 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 23:30:57 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:30:57 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:30:57 INFO - (blobuploader) - INFO - Open directory for files ... 23:30:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:30:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:30:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:30:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:30:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:30:58 INFO - (blobuploader) - INFO - Done attempting. 23:30:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:30:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:30:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:30:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:30:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:30:59 INFO - (blobuploader) - INFO - Done attempting. 23:30:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 23:30:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:30:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:31:00 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 23:31:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:31:00 INFO - (blobuploader) - INFO - Done attempting. 23:31:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:31:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:31:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:31:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:31:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:31:02 INFO - (blobuploader) - INFO - Done attempting. 23:31:02 INFO - (blobuploader) - INFO - Iteration through files over. 23:31:02 INFO - Return code: 0 23:31:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:31:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:31:02 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b7c2ff4af201d6bfc5013db51840518b34d32acef5cce6a76b443d7417232f2c24f654083d7ffef39b34a12105724de58842009acaf2c715ba2570692fcc4ddc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4f835b3ebb718753de65844b9804838d682221d1fc3fbdeb21c2b55207e92bb21163b85a874b5dbe305263378033020d25980f044ade60377f8b8accc99253af", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9268fd80abf35414dec05be5b9618893d44449dbb871153482b654502e79a610474f1117b8e27b8dfada42648095562e8c1258133d2608f674ba66d39035c370", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b859c9c75c9d53520545c921cc52ec06e0d040654791eecf84acc9e53261fe6e9aed085795dd3d557d1903d301c3ab6e390e4662665601a3863720b92e488c1a"} 23:31:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:31:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:31:02 INFO - Contents: 23:31:02 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b7c2ff4af201d6bfc5013db51840518b34d32acef5cce6a76b443d7417232f2c24f654083d7ffef39b34a12105724de58842009acaf2c715ba2570692fcc4ddc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4f835b3ebb718753de65844b9804838d682221d1fc3fbdeb21c2b55207e92bb21163b85a874b5dbe305263378033020d25980f044ade60377f8b8accc99253af", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9268fd80abf35414dec05be5b9618893d44449dbb871153482b654502e79a610474f1117b8e27b8dfada42648095562e8c1258133d2608f674ba66d39035c370", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b859c9c75c9d53520545c921cc52ec06e0d040654791eecf84acc9e53261fe6e9aed085795dd3d557d1903d301c3ab6e390e4662665601a3863720b92e488c1a"} 23:31:02 INFO - Running post-run listener: copy_logs_to_upload_dir 23:31:02 INFO - Copying logs to upload dir... 23:31:02 INFO - mkdir: /builds/slave/test/build/upload/logs 23:31:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.843884 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-01-24 23:31:02.954897) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-24 23:31:02.958105) ========= 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.KUkQfECKPO/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.X86v054kIY/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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/Graphics/sha512/b7c2ff4af201d6bfc5013db51840518b34d32acef5cce6a76b443d7417232f2c24f654083d7ffef39b34a12105724de58842009acaf2c715ba2570692fcc4ddc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4f835b3ebb718753de65844b9804838d682221d1fc3fbdeb21c2b55207e92bb21163b85a874b5dbe305263378033020d25980f044ade60377f8b8accc99253af", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9268fd80abf35414dec05be5b9618893d44449dbb871153482b654502e79a610474f1117b8e27b8dfada42648095562e8c1258133d2608f674ba66d39035c370", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b859c9c75c9d53520545c921cc52ec06e0d040654791eecf84acc9e53261fe6e9aed085795dd3d557d1903d301c3ab6e390e4662665601a3863720b92e488c1a"} build_url:https://queue.taskcluster.net/v1/task/T3fyx_EvSEiVTFm-cmPg3w/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011447 build_url: 'https://queue.taskcluster.net/v1/task/T3fyx_EvSEiVTFm-cmPg3w/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b7c2ff4af201d6bfc5013db51840518b34d32acef5cce6a76b443d7417232f2c24f654083d7ffef39b34a12105724de58842009acaf2c715ba2570692fcc4ddc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4f835b3ebb718753de65844b9804838d682221d1fc3fbdeb21c2b55207e92bb21163b85a874b5dbe305263378033020d25980f044ade60377f8b8accc99253af", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9268fd80abf35414dec05be5b9618893d44449dbb871153482b654502e79a610474f1117b8e27b8dfada42648095562e8c1258133d2608f674ba66d39035c370", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b859c9c75c9d53520545c921cc52ec06e0d040654791eecf84acc9e53261fe6e9aed085795dd3d557d1903d301c3ab6e390e4662665601a3863720b92e488c1a"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-24 23:31:02.996214) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-24 23:31:02.996548) ========= 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.KUkQfECKPO/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.X86v054kIY/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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.005271 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-24 23:31:03.045298) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-24 23:31:03.045614) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-24 23:31:03.045908) ========= ========= Total master_lag: 1.05 =========