builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0230 starttime: 1479981766.59 results: success (0) buildid: 20161123224937 builduid: a2c57c512d084ff9a4b3a0a2d792f7ee revision: 43937645f19a15c40062c248b0a6579b6ca805e3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-24 02:02:46.588013) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-24 02:02:46.588541) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-24 02:02:46.588862) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-24 02:02:46.615196) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 02:02:46.615643) ========= 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.m5oKL6NAxW/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.FBD7ZWibDY/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 --2016-11-24 02:02:46-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.34M=0.001s 2016-11-24 02:02:46 (8.34 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127708 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 02:02:46.767243) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 02:02:46.767605) ========= 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.m5oKL6NAxW/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.FBD7ZWibDY/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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.019473 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 02:02:46.820138) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-24 02:02:46.820552) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 43937645f19a15c40062c248b0a6579b6ca805e3 --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 43937645f19a15c40062c248b0a6579b6ca805e3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.m5oKL6NAxW/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.FBD7ZWibDY/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 2016-11-24 02:02:46,905 truncating revision to first 12 chars 2016-11-24 02:02:46,905 Setting DEBUG logging. 2016-11-24 02:02:46,905 attempt 1/10 2016-11-24 02:02:46,905 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/43937645f19a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-24 02:02:48,810 unpacking tar archive at: graphics-43937645f19a/testing/mozharness/ program finished with exit code 0 elapsedTime=2.165208 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-24 02:02:49.015000) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-24 02:02:49.015437) ========= script_repo_revision: 43937645f19a15c40062c248b0a6579b6ca805e3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-24 02:02:49.015913) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-24 02:02:49.016238) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-24 02:02:49.039862) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-24 02:02:49.040332) ========= /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.m5oKL6NAxW/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.FBD7ZWibDY/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 02:02:49 INFO - MultiFileLogger online at 20161124 02:02:49 in /builds/slave/test 02:02:49 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 02:02:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:02:49 INFO - {'append_to_log': False, 02:02:49 INFO - 'base_work_dir': '/builds/slave/test', 02:02:49 INFO - 'blob_upload_branch': 'Graphics', 02:02:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:02:49 INFO - 'branch': 'Graphics', 02:02:49 INFO - 'buildbot_json_path': 'buildprops.json', 02:02:49 INFO - 'config_files': ('talos/mac_config.py',), 02:02:49 INFO - 'default_actions': ('clobber', 02:02:49 INFO - 'read-buildbot-config', 02:02:49 INFO - 'download-and-extract', 02:02:49 INFO - 'populate-webroot', 02:02:49 INFO - 'create-virtualenv', 02:02:49 INFO - 'install', 02:02:49 INFO - 'run-tests'), 02:02:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:02:49 INFO - 'download_minidump_stackwalk': True, 02:02:49 INFO - 'download_symbols': 'ondemand', 02:02:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:02:49 INFO - 'tooltool.py': '/tools/tooltool.py', 02:02:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:02:49 INFO - '/tools/misc-python/virtualenv.py')}, 02:02:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:02:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:02:49 INFO - 'installer_path': 'installer.exe', 02:02:49 INFO - 'log_level': 'info', 02:02:49 INFO - 'log_name': 'talos', 02:02:49 INFO - 'log_to_console': True, 02:02:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:02:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:02:49 INFO - 'opt_config_files': (), 02:02:49 INFO - 'pip_index': False, 02:02:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:02:49 INFO - 'cmd': ('bash', 02:02:49 INFO - '-c', 02:02:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:02:49 INFO - 'enabled': True, 02:02:49 INFO - 'halt_on_failure': False, 02:02:49 INFO - 'name': 'check_screen_resolution'},), 02:02:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:02:49 INFO - 'cmd': ('bash', 02:02:49 INFO - '-c', 02:02:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:02:49 INFO - 'enabled': True, 02:02:49 INFO - 'halt_on_failure': False, 02:02:49 INFO - 'name': 'check_screen_resolution'},), 02:02:49 INFO - 'run_cmd_checks_enabled': True, 02:02:49 INFO - 'sps_profile': False, 02:02:49 INFO - 'sps_profile_interval': 0, 02:02:49 INFO - 'suite': 'dromaeojs-e10s', 02:02:49 INFO - 'system_bits': '32', 02:02:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:02:49 INFO - 'title': 't-yosemite-r7-0230', 02:02:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:02:49 INFO - 'use_talos_json': True, 02:02:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:02:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:02:49 INFO - 'work_dir': 'build'} 02:02:49 INFO - [mozharness: 2016-11-24 10:02:49.246622Z] Running clobber step. 02:02:49 INFO - Running pre-action listener: _resource_record_pre_action 02:02:49 INFO - Running main action method: clobber 02:02:49 INFO - rmtree: /builds/slave/test/build 02:02:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:02:51 INFO - Running post-action listener: _resource_record_post_action 02:02:51 INFO - [mozharness: 2016-11-24 10:02:51.292943Z] Finished clobber step (success) 02:02:51 INFO - [mozharness: 2016-11-24 10:02:51.293107Z] Running read-buildbot-config step. 02:02:51 INFO - Running pre-action listener: _resource_record_pre_action 02:02:51 INFO - Running main action method: read_buildbot_config 02:02:51 INFO - Using buildbot properties: 02:02:51 INFO - { 02:02:51 INFO - "project": "", 02:02:51 INFO - "product": "firefox", 02:02:51 INFO - "script_repo_revision": "default", 02:02:51 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 02:02:51 INFO - "repository": "", 02:02:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 02:02:51 INFO - "buildid": "20161123224937", 02:02:51 INFO - "pgo_build": "False", 02:02:51 INFO - "basedir": "/builds/slave/test", 02:02:51 INFO - "buildnumber": 1, 02:02:51 INFO - "slavename": "t-yosemite-r7-0230", 02:02:51 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 02:02:51 INFO - "platform": "yosemite_r7", 02:02:51 INFO - "scheduler": "tests-graphics-macosx64-talos-8-3600", 02:02:51 INFO - "branch": "graphics", 02:02:51 INFO - "repo_path": "projects/graphics", 02:02:51 INFO - "revision": "43937645f19a15c40062c248b0a6579b6ca805e3", 02:02:51 INFO - "stage_platform": "macosx64", 02:02:51 INFO - "builduid": "a2c57c512d084ff9a4b3a0a2d792f7ee", 02:02:51 INFO - "slavebuilddir": "test" 02:02:51 INFO - } 02:02:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/Jrjs5dx3QpKmneEb0aezOg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 02:02:51 INFO - Running post-action listener: _resource_record_post_action 02:02:51 INFO - [mozharness: 2016-11-24 10:02:51.296655Z] Finished read-buildbot-config step (success) 02:02:51 INFO - [mozharness: 2016-11-24 10:02:51.296765Z] Running download-and-extract step. 02:02:51 INFO - Running pre-action listener: _resource_record_pre_action 02:02:51 INFO - Running main action method: download_and_extract 02:02:51 INFO - mkdir: /builds/slave/test/build/tests 02:02:51 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')]} 02:02:51 INFO - trying https://queue.taskcluster.net/v1/task/Jrjs5dx3QpKmneEb0aezOg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 02:02:51 INFO - Downloading https://queue.taskcluster.net/v1/task/Jrjs5dx3QpKmneEb0aezOg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 02:02:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Jrjs5dx3QpKmneEb0aezOg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 02:02:52 INFO - Downloaded 1199 bytes. 02:02:52 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 02:02:52 INFO - Using the following test package requirements: 02:02:52 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 02:02:52 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 02:02:52 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 02:02:52 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 02:02:52 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 02:02:52 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 02:02:52 INFO - u'jsshell-mac.zip'], 02:02:52 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 02:02:52 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 02:02:52 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 02:02:52 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 02:02:52 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 02:02:52 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 02:02:52 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 02:02:52 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 02:02:52 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 02:02:52 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 02:02:52 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 02:02:52 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:02:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Jrjs5dx3QpKmneEb0aezOg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 02:02:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Jrjs5dx3QpKmneEb0aezOg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:02:52 INFO - Fetch https://queue.taskcluster.net/v1/task/Jrjs5dx3QpKmneEb0aezOg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 02:02:52 INFO - Http code: 200 02:02:52 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 02:02:52 INFO - x-amz-cf-id: NgWbdwtwTdeTigIXY68pCQZl8r0vymaMMw_i88TgtWO4ueu5id5c3A== 02:02:52 INFO - x-amz-version-id: HcmyVJCfT.siKERSC5zuvT.IKE6yHTlO 02:02:53 INFO - Expected file size: 16267152 02:02:53 INFO - Obtained file size: 16267152 02:02:59 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 02:02:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Jrjs5dx3QpKmneEb0aezOg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 02:02:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Jrjs5dx3QpKmneEb0aezOg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:02:59 INFO - Fetch https://queue.taskcluster.net/v1/task/Jrjs5dx3QpKmneEb0aezOg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 02:03:00 INFO - Http code: 200 02:03:00 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 02:03:00 INFO - x-amz-cf-id: I3O_C1IelFCedHMSlcWHHLQW_0hR_h3qmpS5JSTliMJeCWAIw6zl-A== 02:03:00 INFO - x-amz-version-id: HcmyVJCfT.siKERSC5zuvT.IKE6yHTlO 02:03:00 INFO - Expected file size: 16267152 02:03:00 INFO - Obtained file size: 16267152 02:03:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Jrjs5dx3QpKmneEb0aezOg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 02:03:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Jrjs5dx3QpKmneEb0aezOg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 02:03:07 INFO - Fetch https://queue.taskcluster.net/v1/task/Jrjs5dx3QpKmneEb0aezOg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 02:03:08 INFO - Http code: 200 02:03:08 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 02:03:08 INFO - x-amz-cf-id: 0e_rPNFlb6dqsy0uWYeCdIxVY6nIpEvQJIbWpBC0wgEEODboPnZUtg== 02:03:08 INFO - x-amz-version-id: HKV3p2qFDWtXK5tPTQdPlkeQKc0nmhwV 02:03:08 INFO - Expected file size: 14409506 02:03:08 INFO - Obtained file size: 14409506 02:03:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:03:08 INFO - trying https://queue.taskcluster.net/v1/task/Jrjs5dx3QpKmneEb0aezOg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 02:03:08 INFO - Downloading https://queue.taskcluster.net/v1/task/Jrjs5dx3QpKmneEb0aezOg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 02:03:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Jrjs5dx3QpKmneEb0aezOg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 02:03:11 INFO - Downloaded 107546333 bytes. 02:03:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Jrjs5dx3QpKmneEb0aezOg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 02:03:11 INFO - mkdir: /builds/slave/test/properties 02:03:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:03:11 INFO - Writing to file /builds/slave/test/properties/build_url 02:03:11 INFO - Contents: 02:03:11 INFO - build_url:https://queue.taskcluster.net/v1/task/Jrjs5dx3QpKmneEb0aezOg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 02:03:12 INFO - Running post-action listener: _resource_record_post_action 02:03:12 INFO - Running post-action listener: set_extra_try_arguments 02:03:12 INFO - [mozharness: 2016-11-24 10:03:12.983648Z] Finished download-and-extract step (success) 02:03:12 INFO - [mozharness: 2016-11-24 10:03:12.983747Z] Running populate-webroot step. 02:03:12 INFO - Running pre-action listener: _resource_record_pre_action 02:03:12 INFO - Running main action method: populate_webroot 02:03:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:03:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:03:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:03:12 INFO - '--tppagecycles', 02:03:12 INFO - '7'], 02:03:12 INFO - 'tests': ['tsvgm']}}, 02:03:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:03:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:03:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:03:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:03:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:03:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:03:12 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:03:12 INFO - 'talos_options': ['--disable-e10s'], 02:03:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:03:12 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:03:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:03:12 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:03:12 INFO - 'talos_options': ['--disable-e10s'], 02:03:12 INFO - 'tests': ['damp', 'tps']}, 02:03:12 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:03:12 INFO - 'tests': ['damp', 'tps']}, 02:03:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:03:12 INFO - 'tests': ['dromaeo_dom']}, 02:03:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:03:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:03:12 INFO - 'tests': ['basic_compositor_video']}, 02:03:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:03:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:03:12 INFO - 'tests': ['a11yr', 02:03:12 INFO - 'ts_paint', 02:03:12 INFO - 'tpaint', 02:03:12 INFO - 'sessionrestore', 02:03:12 INFO - 'sessionrestore_no_auto_restore', 02:03:12 INFO - 'tabpaint']}, 02:03:12 INFO - 'other-e10s': {'tests': ['a11yr', 02:03:12 INFO - 'ts_paint', 02:03:12 INFO - 'tpaint', 02:03:12 INFO - 'sessionrestore', 02:03:12 INFO - 'sessionrestore_no_auto_restore', 02:03:12 INFO - 'tabpaint']}, 02:03:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:03:12 INFO - 'ts_paint', 02:03:12 INFO - 'tpaint', 02:03:12 INFO - 'sessionrestore', 02:03:12 INFO - 'sessionrestore_no_auto_restore', 02:03:12 INFO - 'tabpaint']}, 02:03:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:03:12 INFO - 'ts_paint', 02:03:12 INFO - 'tpaint', 02:03:12 INFO - 'sessionrestore', 02:03:12 INFO - 'sessionrestore_no_auto_restore', 02:03:12 INFO - 'tabpaint']}, 02:03:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:03:12 INFO - 'tests': ['a11yr', 02:03:12 INFO - 'ts_paint', 02:03:12 INFO - 'tpaint', 02:03:12 INFO - 'sessionrestore', 02:03:12 INFO - 'sessionrestore_no_auto_restore', 02:03:12 INFO - 'tabpaint']}, 02:03:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:03:12 INFO - 'tests': ['a11yr', 02:03:12 INFO - 'ts_paint', 02:03:12 INFO - 'tpaint', 02:03:12 INFO - 'sessionrestore', 02:03:12 INFO - 'sessionrestore_no_auto_restore', 02:03:12 INFO - 'tabpaint']}, 02:03:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:03:13 INFO - 'tests': ['tsvgx', 02:03:13 INFO - 'tsvgr_opacity', 02:03:13 INFO - 'tart', 02:03:13 INFO - 'tscrollx', 02:03:13 INFO - 'cart']}, 02:03:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:03:13 INFO - 'tsvgr_opacity', 02:03:13 INFO - 'tart', 02:03:13 INFO - 'tscrollx', 02:03:13 INFO - 'cart']}, 02:03:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:03:13 INFO - 'talos_options': ['--disable-e10s'], 02:03:13 INFO - 'tests': ['tp5o']}, 02:03:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:03:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:03:13 INFO - 'talos_options': ['--disable-e10s', 02:03:13 INFO - '--xperf_path', 02:03:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:03:13 INFO - 'tests': ['tp5n']}, 02:03:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:03:13 INFO - 'talos_options': ['--xperf_path', 02:03:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:03:13 INFO - 'tests': ['tp5n']}}, 02:03:13 INFO - 'talos.zip': {'path': '', 02:03:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:03:13 INFO - Running post-action listener: _resource_record_post_action 02:03:13 INFO - [mozharness: 2016-11-24 10:03:13.002659Z] Finished populate-webroot step (success) 02:03:13 INFO - [mozharness: 2016-11-24 10:03:13.002794Z] Running create-virtualenv step. 02:03:13 INFO - Running pre-action listener: _resource_record_pre_action 02:03:13 INFO - Running main action method: create_virtualenv 02:03:13 INFO - Creating virtualenv /builds/slave/test/build/venv 02:03:13 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 02:03:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:03:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:03:13 INFO - Using real prefix '/tools/python27' 02:03:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:03:13 INFO - Installing distribute.............................................................................................................................................................................................done. 02:03:16 INFO - Installing pip.................done. 02:03:16 INFO - Return code: 0 02:03:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:03:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:03:16 INFO - Reading from file tmpfile_stdout 02:03:16 INFO - Output received: 02:03:16 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) 02:03:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:03:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:03:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:03:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:03:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:03:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:16 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 0x10264b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025c1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8704a5c80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102461d30>, '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 0x1022c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102459b20>, '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.m5oKL6NAxW/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.FBD7ZWibDY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 02:03:16 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 02:03:16 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 02:03:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m5oKL6NAxW/Render', 02:03:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:03:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:03:16 INFO - 'HOME': '/Users/cltbld', 02:03:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:03:16 INFO - 'LOGNAME': 'cltbld', 02:03:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:16 INFO - 'MOZ_NO_REMOTE': '1', 02:03:16 INFO - 'NO_EM_RESTART': '1', 02:03:16 INFO - 'PAGER': '/bin/cat', 02:03:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:03:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:03:16 INFO - 'PWD': '/builds/slave/test', 02:03:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:03:16 INFO - 'SHELL': '/bin/bash', 02:03:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FBD7ZWibDY/Listeners', 02:03:16 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 02:03:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:03:16 INFO - 'USER': 'cltbld', 02:03:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:03:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:03:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:03:16 INFO - 'XPC_FLAGS': '0x0', 02:03:16 INFO - 'XPC_SERVICE_NAME': '0', 02:03:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:03:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:03:17 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 02:03:17 INFO - Cleaning up... 02:03:17 INFO - Return code: 0 02:03:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:03:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:03:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:03:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:03:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:03:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10264b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025c1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8704a5c80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102461d30>, '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 0x1022c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102459b20>, '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.m5oKL6NAxW/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.FBD7ZWibDY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 02:03:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:03:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:03:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m5oKL6NAxW/Render', 02:03:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:03:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:03:17 INFO - 'HOME': '/Users/cltbld', 02:03:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:03:17 INFO - 'LOGNAME': 'cltbld', 02:03:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:17 INFO - 'MOZ_NO_REMOTE': '1', 02:03:17 INFO - 'NO_EM_RESTART': '1', 02:03:17 INFO - 'PAGER': '/bin/cat', 02:03:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:03:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:03:17 INFO - 'PWD': '/builds/slave/test', 02:03:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:03:17 INFO - 'SHELL': '/bin/bash', 02:03:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FBD7ZWibDY/Listeners', 02:03:17 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 02:03:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:03:17 INFO - 'USER': 'cltbld', 02:03:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:03:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:03:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:03:17 INFO - 'XPC_FLAGS': '0x0', 02:03:17 INFO - 'XPC_SERVICE_NAME': '0', 02:03:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:03:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:03:17 INFO - Downloading/unpacking psutil>=3.1.1 02:03:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:03:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:03:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:03:20 INFO - Installing collected packages: psutil 02:03:20 INFO - Running setup.py install for psutil 02:03:21 INFO - building 'psutil._psutil_osx' extension 02:03:21 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 02:03:21 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 02:03:21 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 02:03:21 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 02:03:21 INFO - building 'psutil._psutil_posix' extension 02:03:21 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 02:03:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:03:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:03:21 INFO - ^ 02:03:21 INFO - 1 warning generated. 02:03:21 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 02:03:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:03:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:03:21 INFO - Successfully installed psutil 02:03:21 INFO - Cleaning up... 02:03:21 INFO - Return code: 0 02:03:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:03:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:03:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:03:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:03:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:03:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10264b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025c1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8704a5c80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102461d30>, '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 0x1022c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102459b20>, '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.m5oKL6NAxW/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.FBD7ZWibDY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 02:03:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:03:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:03:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m5oKL6NAxW/Render', 02:03:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:03:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:03:21 INFO - 'HOME': '/Users/cltbld', 02:03:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:03:21 INFO - 'LOGNAME': 'cltbld', 02:03:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:21 INFO - 'MOZ_NO_REMOTE': '1', 02:03:21 INFO - 'NO_EM_RESTART': '1', 02:03:21 INFO - 'PAGER': '/bin/cat', 02:03:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:03:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:03:21 INFO - 'PWD': '/builds/slave/test', 02:03:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:03:21 INFO - 'SHELL': '/bin/bash', 02:03:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FBD7ZWibDY/Listeners', 02:03:21 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 02:03:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:03:21 INFO - 'USER': 'cltbld', 02:03:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:03:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:03:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:03:21 INFO - 'XPC_FLAGS': '0x0', 02:03:21 INFO - 'XPC_SERVICE_NAME': '0', 02:03:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:03:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:03:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:03:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:03:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:03:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:03:24 INFO - Installing collected packages: mozsystemmonitor 02:03:24 INFO - Running setup.py install for mozsystemmonitor 02:03:24 INFO - Successfully installed mozsystemmonitor 02:03:24 INFO - Cleaning up... 02:03:24 INFO - Return code: 0 02:03:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:03:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:03:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:03:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:03:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:03:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10264b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025c1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8704a5c80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102461d30>, '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 0x1022c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102459b20>, '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.m5oKL6NAxW/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.FBD7ZWibDY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 02:03:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:03:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:03:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m5oKL6NAxW/Render', 02:03:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:03:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:03:24 INFO - 'HOME': '/Users/cltbld', 02:03:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:03:24 INFO - 'LOGNAME': 'cltbld', 02:03:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:24 INFO - 'MOZ_NO_REMOTE': '1', 02:03:24 INFO - 'NO_EM_RESTART': '1', 02:03:24 INFO - 'PAGER': '/bin/cat', 02:03:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:03:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:03:24 INFO - 'PWD': '/builds/slave/test', 02:03:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:03:24 INFO - 'SHELL': '/bin/bash', 02:03:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FBD7ZWibDY/Listeners', 02:03:24 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 02:03:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:03:24 INFO - 'USER': 'cltbld', 02:03:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:03:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:03:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:03:24 INFO - 'XPC_FLAGS': '0x0', 02:03:24 INFO - 'XPC_SERVICE_NAME': '0', 02:03:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:03:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:03:25 INFO - Downloading/unpacking jsonschema==2.5.1 02:03:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:03:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:03: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 02:03: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 02:03: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 02:03: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 02:03: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 02:03: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 02:03:28 INFO - Downloading functools32-3.2.3-2.tar.gz 02:03:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:03:28 INFO - warning: no files found matching '*.txt' 02:03:28 INFO - no previously-included directories found matching 'build' 02:03:28 INFO - no previously-included directories found matching 'dist' 02:03:28 INFO - no previously-included directories found matching '.git*' 02:03:28 INFO - Installing collected packages: jsonschema, functools32 02:03:28 INFO - Running setup.py install for functools32 02:03:28 INFO - warning: no files found matching '*.txt' 02:03:28 INFO - no previously-included directories found matching 'build' 02:03:28 INFO - no previously-included directories found matching 'dist' 02:03:28 INFO - no previously-included directories found matching '.git*' 02:03:28 INFO - Successfully installed jsonschema functools32 02:03:28 INFO - Cleaning up... 02:03:28 INFO - Return code: 0 02:03:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:03:28 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')]} 02:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:03:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:28 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')]} 02:03:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:03:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:28 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 0x10264b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025c1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8704a5c80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102461d30>, '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 0x1022c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102459b20>, '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.m5oKL6NAxW/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.FBD7ZWibDY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 02:03:28 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 02:03:28 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 02:03:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m5oKL6NAxW/Render', 02:03:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:03:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:03:28 INFO - 'HOME': '/Users/cltbld', 02:03:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:03:28 INFO - 'LOGNAME': 'cltbld', 02:03:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:28 INFO - 'MOZ_NO_REMOTE': '1', 02:03:28 INFO - 'NO_EM_RESTART': '1', 02:03:28 INFO - 'PAGER': '/bin/cat', 02:03:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:03:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:03:28 INFO - 'PWD': '/builds/slave/test', 02:03:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:03:28 INFO - 'SHELL': '/bin/bash', 02:03:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FBD7ZWibDY/Listeners', 02:03:28 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 02:03:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:03:28 INFO - 'USER': 'cltbld', 02:03:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:03:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:03:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:03:28 INFO - 'XPC_FLAGS': '0x0', 02:03:28 INFO - 'XPC_SERVICE_NAME': '0', 02:03:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:03:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:03:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:03:28 INFO - Cleaning up... 02:03:28 INFO - Return code: 0 02:03:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:03:28 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')]} 02:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:03:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:28 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')]} 02:03:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:03:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:28 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 0x10264b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025c1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8704a5c80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102461d30>, '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 0x1022c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102459b20>, '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.m5oKL6NAxW/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.FBD7ZWibDY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 02:03:28 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 02:03:28 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 02:03:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m5oKL6NAxW/Render', 02:03:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:03:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:03:28 INFO - 'HOME': '/Users/cltbld', 02:03:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:03:28 INFO - 'LOGNAME': 'cltbld', 02:03:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:28 INFO - 'MOZ_NO_REMOTE': '1', 02:03:28 INFO - 'NO_EM_RESTART': '1', 02:03:28 INFO - 'PAGER': '/bin/cat', 02:03:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:03:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:03:28 INFO - 'PWD': '/builds/slave/test', 02:03:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:03:28 INFO - 'SHELL': '/bin/bash', 02:03:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FBD7ZWibDY/Listeners', 02:03:28 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 02:03:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:03:28 INFO - 'USER': 'cltbld', 02:03:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:03:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:03:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:03:28 INFO - 'XPC_FLAGS': '0x0', 02:03:28 INFO - 'XPC_SERVICE_NAME': '0', 02:03:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:03:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:03:28 INFO - Downloading/unpacking blobuploader==1.2.4 02:03:28 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 02:03:28 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 02:03:28 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 02:03:28 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 02:03:28 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 02:03:28 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 02:03:31 INFO - Downloading blobuploader-1.2.4.tar.gz 02:03:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:03:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:03:31 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 02:03:31 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 02:03:31 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 02:03:31 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 02:03:31 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 02:03:31 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 02:03:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:03:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:03: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 02:03: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 02:03: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 02:03: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 02:03: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 02:03: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 02:03:32 INFO - Downloading docopt-0.6.1.tar.gz 02:03:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:03:32 INFO - Installing collected packages: blobuploader, requests, docopt 02:03:32 INFO - Running setup.py install for blobuploader 02:03:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:03:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:03:32 INFO - Running setup.py install for requests 02:03:33 INFO - Running setup.py install for docopt 02:03:33 INFO - Successfully installed blobuploader requests docopt 02:03:33 INFO - Cleaning up... 02:03:33 INFO - Return code: 0 02:03:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:03:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:03:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:03:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:03:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:03:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:33 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 0x10264b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025c1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8704a5c80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102461d30>, '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 0x1022c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102459b20>, '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.m5oKL6NAxW/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.FBD7ZWibDY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 02:03:33 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 02:03:33 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 02:03:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m5oKL6NAxW/Render', 02:03:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:03:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:03:33 INFO - 'HOME': '/Users/cltbld', 02:03:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:03:33 INFO - 'LOGNAME': 'cltbld', 02:03:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:33 INFO - 'MOZ_NO_REMOTE': '1', 02:03:33 INFO - 'NO_EM_RESTART': '1', 02:03:33 INFO - 'PAGER': '/bin/cat', 02:03:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:03:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:03:33 INFO - 'PWD': '/builds/slave/test', 02:03:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:03:33 INFO - 'SHELL': '/bin/bash', 02:03:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FBD7ZWibDY/Listeners', 02:03:33 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 02:03:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:03:33 INFO - 'USER': 'cltbld', 02:03:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:03:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:03:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:03:33 INFO - 'XPC_FLAGS': '0x0', 02:03:33 INFO - 'XPC_SERVICE_NAME': '0', 02:03:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:03:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:03:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:03:33 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-kaCbgm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:03:34 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-KTnxPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:03:34 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-0P_2Vu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:03:34 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-ts3sRj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:03:34 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-9Nnpw1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:03:34 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-uTQsuH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:03:34 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-dOxqhl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:03:34 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-lgKL2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:03:34 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-u7td9P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:03:34 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-jCAWbt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:03:34 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-u9elRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:03:35 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-IvzyQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:03:35 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-9a5J6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:03:35 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-ZMl8xP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:03:35 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-mlrJgp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:03:35 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-wlE3wl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:03:35 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-jr9IdH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:03:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:03:35 INFO - Running setup.py install for manifestparser 02:03:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:03:35 INFO - Running setup.py install for mozcrash 02:03:35 INFO - Running setup.py install for mozdebug 02:03:36 INFO - Running setup.py install for mozdevice 02:03:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:03:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:03:36 INFO - Running setup.py install for mozfile 02:03:36 INFO - Running setup.py install for mozhttpd 02:03:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:03:36 INFO - Running setup.py install for mozinfo 02:03:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:03:36 INFO - Running setup.py install for mozInstall 02:03:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:03:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:03:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:03:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:03:36 INFO - Running setup.py install for mozleak 02:03:36 INFO - Running setup.py install for mozlog 02:03:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:03:37 INFO - Running setup.py install for moznetwork 02:03:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:03:37 INFO - Running setup.py install for mozprocess 02:03:37 INFO - Running setup.py install for mozprofile 02:03:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:03:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:03:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:03:37 INFO - Running setup.py install for mozrunner 02:03:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:03:37 INFO - Running setup.py install for mozscreenshot 02:03:37 INFO - Running setup.py install for moztest 02:03:38 INFO - Running setup.py install for mozversion 02:03:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:03:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:03:38 INFO - Cleaning up... 02:03:38 INFO - Return code: 0 02:03:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:03: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')]} 02:03:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:03:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03: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')]} 02:03:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:03:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:38 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 0x10264b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025c1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8704a5c80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102461d30>, '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 0x1022c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102459b20>, '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.m5oKL6NAxW/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.FBD7ZWibDY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 02:03:38 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 02:03:38 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 02:03:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m5oKL6NAxW/Render', 02:03:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:03:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:03:38 INFO - 'HOME': '/Users/cltbld', 02:03:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:03:38 INFO - 'LOGNAME': 'cltbld', 02:03:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:38 INFO - 'MOZ_NO_REMOTE': '1', 02:03:38 INFO - 'NO_EM_RESTART': '1', 02:03:38 INFO - 'PAGER': '/bin/cat', 02:03:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:03:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:03:38 INFO - 'PWD': '/builds/slave/test', 02:03:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:03:38 INFO - 'SHELL': '/bin/bash', 02:03:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FBD7ZWibDY/Listeners', 02:03:38 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 02:03:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:03:38 INFO - 'USER': 'cltbld', 02:03:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:03:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:03:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:03:38 INFO - 'XPC_FLAGS': '0x0', 02:03:38 INFO - 'XPC_SERVICE_NAME': '0', 02:03:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:03:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:03:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:03:38 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-dsgyKC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:03:38 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)) 02:03:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:03:38 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-i3Ug0l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:03:38 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)) 02:03:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:03:38 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-ggANwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:03:38 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)) 02:03:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:03:38 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-Aqdl0Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:03:38 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)) 02:03:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:03:38 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-sTPjs3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:03:39 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)) 02:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:03:39 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-FRyXB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:03:39 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)) 02:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:03:39 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-HY4RYN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:03:39 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)) 02:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:03:39 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-wOtXMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:03:39 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)) 02:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:03:39 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-z3z5hY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:03:39 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)) 02:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:03:39 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-bt_PA1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:03:39 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)) 02:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:03:39 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-bOgvSr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:03:39 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)) 02:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:03:39 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-XE43i3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:03:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 02:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:03:39 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-K3LhsY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:03:39 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)) 02:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:03:39 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-NLT0wK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:03:40 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)) 02:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:03:40 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-Czmztx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:03:40 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)) 02:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:03:40 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-qQ9snk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:03:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 02:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:03:40 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-ETEDX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:03:40 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)) 02:03:40 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)) 02:03:40 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)) 02:03:40 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)) 02:03:40 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)) 02:03:40 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)) 02:03:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:03:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:43 INFO - Downloading blessings-1.6.tar.gz 02:03:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:03:43 INFO - Installing collected packages: blessings 02:03:43 INFO - Running setup.py install for blessings 02:03:43 INFO - Successfully installed blessings 02:03:43 INFO - Cleaning up... 02:03:43 INFO - Return code: 0 02:03:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:03:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:03:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:03:43 INFO - Reading from file tmpfile_stdout 02:03:43 INFO - Current package versions: 02:03:43 INFO - blessings == 1.6 02:03:43 INFO - blobuploader == 1.2.4 02:03:43 INFO - docopt == 0.6.1 02:03:43 INFO - functools32 == 3.2.3-2 02:03:43 INFO - jsonschema == 2.5.1 02:03:43 INFO - manifestparser == 1.1 02:03:43 INFO - mozInstall == 1.12 02:03:43 INFO - mozcrash == 1.0 02:03:43 INFO - mozdebug == 0.1 02:03:43 INFO - mozdevice == 0.49 02:03:43 INFO - mozfile == 1.2 02:03:43 INFO - mozhttpd == 0.7 02:03:43 INFO - mozinfo == 0.9 02:03:43 INFO - mozleak == 0.1 02:03:43 INFO - mozlog == 3.4 02:03:43 INFO - moznetwork == 0.27 02:03:43 INFO - mozprocess == 0.23 02:03:43 INFO - mozprofile == 0.28 02:03:43 INFO - mozrunner == 6.13 02:03:43 INFO - mozscreenshot == 0.1 02:03:43 INFO - mozsystemmonitor == 0.3 02:03:43 INFO - moztest == 0.7 02:03:43 INFO - mozversion == 1.4 02:03:43 INFO - psutil == 3.1.1 02:03:43 INFO - requests == 1.2.3 02:03:43 INFO - wsgiref == 0.1.2 02:03:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:03:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:03:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:03:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03: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')]} 02:03:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:03:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:44 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 0x10264b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025c1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8704a5c80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102461d30>, '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 0x1022c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102459b20>, '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.m5oKL6NAxW/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.FBD7ZWibDY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 02:03:44 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 02:03:44 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 02:03:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m5oKL6NAxW/Render', 02:03:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:03:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:03:44 INFO - 'HOME': '/Users/cltbld', 02:03:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:03:44 INFO - 'LOGNAME': 'cltbld', 02:03:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:44 INFO - 'MOZ_NO_REMOTE': '1', 02:03:44 INFO - 'NO_EM_RESTART': '1', 02:03:44 INFO - 'PAGER': '/bin/cat', 02:03:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:03:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:03:44 INFO - 'PWD': '/builds/slave/test', 02:03:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:03:44 INFO - 'SHELL': '/bin/bash', 02:03:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FBD7ZWibDY/Listeners', 02:03:44 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 02:03:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:03:44 INFO - 'USER': 'cltbld', 02:03:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:03:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:03:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:03:44 INFO - 'XPC_FLAGS': '0x0', 02:03:44 INFO - 'XPC_SERVICE_NAME': '0', 02:03:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:03:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:03:44 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)) 02:03:44 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)) 02:03:44 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)) 02:03:44 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)) 02:03:44 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)) 02:03:44 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)) 02:03:44 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)) 02:03:44 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)) 02:03:44 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)) 02:03:44 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)) 02:03:44 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)) 02:03:44 INFO - Cleaning up... 02:03:44 INFO - Return code: 0 02:03:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:03: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')]} 02:03:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:03:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03: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')]} 02:03:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:03:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:44 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 0x10264b3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025c1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8704a5c80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102461d30>, '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 0x1022c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102459b20>, '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.m5oKL6NAxW/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.FBD7ZWibDY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 02:03:44 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 02:03:44 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 02:03:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m5oKL6NAxW/Render', 02:03:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:03:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:03:44 INFO - 'HOME': '/Users/cltbld', 02:03:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:03:44 INFO - 'LOGNAME': 'cltbld', 02:03:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:44 INFO - 'MOZ_NO_REMOTE': '1', 02:03:44 INFO - 'NO_EM_RESTART': '1', 02:03:44 INFO - 'PAGER': '/bin/cat', 02:03:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:03:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:03:44 INFO - 'PWD': '/builds/slave/test', 02:03:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:03:44 INFO - 'SHELL': '/bin/bash', 02:03:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FBD7ZWibDY/Listeners', 02:03:44 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 02:03:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:03:44 INFO - 'USER': 'cltbld', 02:03:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:03:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:03:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:03:44 INFO - 'XPC_FLAGS': '0x0', 02:03:44 INFO - 'XPC_SERVICE_NAME': '0', 02:03:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:03:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:03:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:03:44 INFO - Cleaning up... 02:03:44 INFO - Return code: 0 02:03:44 INFO - Running post-action listener: _resource_record_post_action 02:03:44 INFO - Running post-action listener: _start_resource_monitoring 02:03:44 INFO - Starting resource monitoring. 02:03:44 INFO - [mozharness: 2016-11-24 10:03:44.811025Z] Finished create-virtualenv step (success) 02:03:44 INFO - [mozharness: 2016-11-24 10:03:44.811976Z] Running install step. 02:03:44 INFO - Running pre-action listener: _resource_record_pre_action 02:03:44 INFO - Running main action method: install 02:03:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:03:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:03:45 INFO - Reading from file tmpfile_stdout 02:03:45 INFO - Detecting whether we're running mozinstall >=1.0... 02:03:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:03:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:03:45 INFO - Reading from file tmpfile_stdout 02:03:45 INFO - Output received: 02:03:45 INFO - Usage: mozinstall [options] installer 02:03:45 INFO - Options: 02:03:45 INFO - -h, --help show this help message and exit 02:03:45 INFO - -d DEST, --destination=DEST 02:03:45 INFO - Directory to install application into. [default: 02:03:45 INFO - "/builds/slave/test"] 02:03:45 INFO - --app=APP Application being installed. [default: firefox] 02:03:45 INFO - mkdir: /builds/slave/test/build/application 02:03:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:03:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 02:04:18 INFO - Reading from file tmpfile_stdout 02:04:18 INFO - Output received: 02:04:18 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:04:18 INFO - Running post-action listener: _resource_record_post_action 02:04:18 INFO - [mozharness: 2016-11-24 10:04:18.126574Z] Finished install step (success) 02:04:18 INFO - [mozharness: 2016-11-24 10:04:18.126724Z] Running run-tests step. 02:04:18 INFO - Running pre-action listener: _resource_record_pre_action 02:04:18 INFO - Running main action method: run_tests 02:04:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:04:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:04:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:04:18 INFO - 2016-11-24 02:04:18.141 screenresolution[2168:73696] starting screenresolution argv=screenresolution get 02:04:18 INFO - 2016-11-24 02:04:18.144 screenresolution[2168:73696] Display 0: 1600x1200x32@60 02:04:18 INFO - 2016-11-24 02:04:18.150 screenresolution[2169:73698] starting screenresolution argv=screenresolution list 02:04:18 INFO - Available Modes on Display 0 02:04:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:04:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:04:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:04:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:04:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:04:18 INFO - Intel Iris: 02:04:18 INFO - Chipset Model: Intel Iris 02:04:18 INFO - Type: GPU 02:04:18 INFO - Bus: Built-In 02:04:18 INFO - VRAM (Dynamic, Max): 1536 MB 02:04:18 INFO - Vendor: Intel (0x8086) 02:04:18 INFO - Device ID: 0x0a2e 02:04:18 INFO - Revision ID: 0x0009 02:04:18 INFO - Displays: 02:04:18 INFO - Display: 02:04:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:04:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:04:18 INFO - Display Serial Number: 1600x1200 60 02:04:18 INFO - Main Display: Yes 02:04:18 INFO - Mirror: Off 02:04:18 INFO - Online: Yes 02:04:18 INFO - Rotation: Supported 02:04:18 INFO - Return code: 0 02:04:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:04:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:04:18 INFO - Python 2.7.3 02:04:18 INFO - Return code: 0 02:04:18 INFO - grabbing minidump binary from tooltool 02:04: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')]} 02:04: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 0x102461d30>, '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 0x1022c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102459b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:04: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 02:04: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 02:04: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 02:04:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:04:18 INFO - Return code: 0 02:04:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:04:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:04:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:04:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:04:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:04: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/Jrjs5dx3QpKmneEb0aezOg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0230', '--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 02:04: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/Jrjs5dx3QpKmneEb0aezOg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0230 --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 02:04:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m5oKL6NAxW/Render', 02:04:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:04:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:04:18 INFO - 'HOME': '/Users/cltbld', 02:04:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:04:18 INFO - 'LOGNAME': 'cltbld', 02:04:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:04:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:04:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:04:18 INFO - 'MOZ_NO_REMOTE': '1', 02:04:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:04:18 INFO - 'NO_EM_RESTART': '1', 02:04:18 INFO - 'PAGER': '/bin/cat', 02:04:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:04:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:04:18 INFO - 'PWD': '/builds/slave/test', 02:04:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:04:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:04:18 INFO - 'SHELL': '/bin/bash', 02:04:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FBD7ZWibDY/Listeners', 02:04:18 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 02:04:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:04:18 INFO - 'USER': 'cltbld', 02:04:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:04:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:04:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:04:18 INFO - 'XPC_FLAGS': '0x0', 02:04:18 INFO - 'XPC_SERVICE_NAME': '0', 02:04:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:04: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/Jrjs5dx3QpKmneEb0aezOg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0230', '--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 02:04:18 INFO - mozversion application_buildid: 20161123224937 02:04:18 INFO - mozversion application_changeset: 43937645f19a15c40062c248b0a6579b6ca805e3 02:04:18 INFO - mozversion application_display_name: Nightly 02:04:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:04:18 INFO - mozversion application_name: Firefox 02:04:18 INFO - mozversion application_remotingname: firefox 02:04:18 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 02:04:18 INFO - mozversion application_vendor: Mozilla 02:04:18 INFO - mozversion application_version: 53.0a1 02:04:18 INFO - mozversion platform_buildid: 20161123224937 02:04:18 INFO - mozversion platform_changeset: 43937645f19a15c40062c248b0a6579b6ca805e3 02:04:18 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 02:04:18 INFO - mozversion platform_version: 53.0a1 02:04:18 INFO - using testdate: 1479981858 02:04:18 INFO - actual date: 1479981858 02:04:18 INFO - starting webserver on 'localhost:57120' 02:04:18 INFO - SUITE-START | Running 2 tests 02:04:18 INFO - TEST-START | dromaeo_css 02:04:18 INFO - operating with platform_type : mac_ 02:04:18 INFO - Initialising browser for dromaeo_css test... 02:04:18 INFO - TEST-INFO | started process 2179 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmps6B6Aw/profile http://localhost:57120/getInfo.html) 02:04:21 INFO - PROCESS | 2179 | 2016-11-24 02:04:21.888 plugin-container[2181:73851] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b3b, name = 'com.apple.tsm.portname' 02:04:21 INFO - PROCESS | 2179 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:04:21 INFO - PROCESS | 2179 | 2016-11-24 02:04:21.891 plugin-container[2181:73851] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c0b, name = 'com.apple.CFPasteboardClient' 02:04:21 INFO - PROCESS | 2179 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:04:21 INFO - PROCESS | 2179 | 2016-11-24 02:04:21.891 plugin-container[2181:73851] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:04:21 INFO - PROCESS | 2179 | __metrics Screen width/height:1600/1200 02:04:21 INFO - PROCESS | 2179 | colorDepth:24 02:04:21 INFO - PROCESS | 2179 | Browser inner width/height: 1024/663 02:04:21 INFO - PROCESS | 2179 | __metrics 02:04:22 ERROR - PROCESS | 2179 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 02:04:22 INFO - TEST-INFO | 2179: exit 0 02:04:22 INFO - Browser initialized. 02:04:22 INFO - Running cycle 1/1 for dromaeo_css test... 02:04:22 INFO - TEST-INFO | started process 2182 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmps6B6Aw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:04:25 INFO - PROCESS | 2182 | 2016-11-24 02:04:25.674 plugin-container[2183:73974] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 02:04:25 INFO - PROCESS | 2182 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:04:25 INFO - PROCESS | 2182 | 2016-11-24 02:04:25.675 plugin-container[2183:73974] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4317, name = 'com.apple.CFPasteboardClient' 02:04:25 INFO - PROCESS | 2182 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:04:25 INFO - PROCESS | 2182 | 2016-11-24 02:04:25.675 plugin-container[2183:73974] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:04:35 INFO - PROCESS | 2182 | RSS: Main: 256045056 02:04:35 INFO - PROCESS | 2182 | 02:05:24 INFO - PROCESS | 2182 | 1479981924934 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 02:07:37 INFO - PROCESS | 2182 | Cycle 1(1): loaded http://localhost:57120/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57120/tests/dromaeo/cssquery-ext.html) 02:07:40 INFO - PROCESS | 2182 | RSS: Main: 262623232 02:07:40 INFO - PROCESS | 2182 | 02:10:42 INFO - PROCESS | 2182 | Cycle 1(1): loaded http://localhost:57120/tests/dromaeo/cssquery-ext.html (next: http://localhost:57120/tests/dromaeo/cssquery-jquery.html) 02:10:43 INFO - PROCESS | 2182 | RSS: Main: 270954496 02:10:43 INFO - PROCESS | 2182 | 02:13:45 INFO - PROCESS | 2182 | Cycle 1(1): loaded http://localhost:57120/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57120/tests/dromaeo/cssquery-mootools.html) 02:13:46 INFO - PROCESS | 2182 | RSS: Main: 275304448 02:13:46 INFO - PROCESS | 2182 | 02:16:47 INFO - PROCESS | 2182 | Cycle 1(1): loaded http://localhost:57120/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57120/tests/dromaeo/cssquery-prototype.html) 02:16:48 INFO - PROCESS | 2182 | RSS: Main: 273846272 02:16:48 INFO - PROCESS | 2182 | 02:19:51 INFO - PROCESS | 2182 | Cycle 1(1): loaded http://localhost:57120/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57120/tests/dromaeo/cssquery-yui.html) 02:19:52 INFO - PROCESS | 2182 | RSS: Main: 278261760 02:19:52 INFO - PROCESS | 2182 | 02:22:54 INFO - PROCESS | 2182 | Cycle 1(1): loaded http://localhost:57120/tests/dromaeo/cssquery-yui.html (next: http://localhost:57120/tests/dromaeo/cssquery-dojo.html) 02:22:55 INFO - PROCESS | 2182 | RSS: Main: 280457216 02:22:55 INFO - PROCESS | 2182 | 02:22:55 INFO - PROCESS | 2182 | __start_tp_report 02:22:55 INFO - PROCESS | 2182 | _x_x_mozilla_page_load 02:22:55 INFO - PROCESS | 2182 | _x_x_mozilla_page_load_details 02:22:55 INFO - PROCESS | 2182 | |i|pagename|runs| 02:22:55 INFO - PROCESS | 2182 | |0;dojo.html;3003.8948636797713;3302.917427064325;3325.2185736351967;3351.178961154516;3375.713064389526;4075.9606300393393;4507.414036175301;5026.663299713311;5449.7820087196515;5667.546596272298;5515.669059856412;5591.496765291123;5615.634983726055;5676.489115979561;5695.860827834439;5140.177571588547;5181.922271165926;5184.455632158626;5186.585073194155;5245.868853278679;5163.535281824635;5182.896342073156;5233.241180028894;5248.107821670325;5268.0780863348955;5225.163973764198;5227.424983251877;5238.554722848525;5265.078611243036;5305.575553955674;5018.347614810088;5106.157484015133;5145.536901678867;5149.794008239666;5203.531682148587;4999.575036121934;5048.596112311043;5086.160783470723;5178.0679477694;5184;5533.280673512458;5618.971905140489;5621.971890140564;5634.183043458716;5676.943230567724;6141.754329826802;6200.81397558074;6212.06818977157;6225.688715564249;6241.750329986795;6078.939210607881;6083.209182806251;6144.662043587601;6160.84597885052;6221.066839973995;5673;5680.375158732536;5686.545076393879;5686.800961966312;5692.601517893749;6178.443940045418;6193.845153871099;6235.127082208494;6241.438270555672;6254;6094.6647934363145;6100.603460775035;6105.664188469588;6108.847278818065;6125.081237814364;5508.779648814003;5910.379410161957;5970.611910225822;6064.48451881595;6192.783252586138;6056.031035034373;6104.436136822545;6124.663143527148;6143.96928015357;6184.134221209034;5212.478752124833;6103.542234332443;6178.165947597106;6182.6599537025895;6221.564490485623;4752.667313288037;4868.123737727243;4882.023595280958;4927.655064145475;4944.728039957765;3692.7045836333027;3702.777833330009;4300.763458009839;4320.827166913288;4377.212101821639;5323.920141197885;5542.140968149944;5650.971745141248;5719.2279042328755;5743.913841292384;5138.794448222029;6200.968995155085;6235.220597425322;6251.843703907348;6252.5935814171935;5662.17898404729;5690.857728556736;5713.371529131792;5812.680302583314;5817.418258174233;5318.441563635763;5344.225087362311;5422.972885135549;5461.644993075437;5462.3445186577865;6269.373062693877;6269.811905642838;6286.088517164985;6292.8426789328805;6295.779647712399;5962.821115366546;6046.90929636047;6212.9068063979985;6236.750529978932;6243.750249989949;6155.2921414037955;6161.661108639068;6178.62928224308;6203.751849925952;6291.150694656163;5991.490723288569;6061.9393806063135;6148.200733904564;6157.9384206157365;6165.167702360124;6006.849828754316;6025.0661147522205;6094.3600921904335;6121.785737499345;6133.395871483965;5870.412958704095;6010.579259451796;6012.549058820518;6024.427679370453;6030.276366836008;5841.707914604338;5925.970370148121;5933.228680271537;5974.4324289192455;5976.103584462366;5753.769849206153;5766.587186139423;5825.766969321349;5859.414058594107;5875.706214689334;6001.459868611846;6003.189569408249;6008.429199226067;6026.035834266497;6034.668093254913;5804.1874137619925;5827.067669172912;5838.357780644041;5856.560757943086;5862.560307977004 02:22:55 INFO - PROCESS | 2182 | |1;ext.html;442.67021069303377;470.4260801821778;481.41027241629007;486.13468026912085;537.9408265090842;25134.87432562837;39091.0227244319;39157.41263881044;39179;39405.80297098514;39559.60440395595;40081.59918400815;40121.197576048464;40187;40228.59771402285;8334.041585217718;8572.271356934669;9038.231750301216;9283.535823208846;10899.073578745816;10878.891211087866;11168.776624467506;11186.272892262017;11189.272697274651;11195.832062519068;11195.720106997349;11206.66380008597;11216.831747523753;11217.719557011058;11236.719082022933;11190.66428007161;11192;11194.328340299608;11195.104391648649;11199.720006999809;11118.444077796077;11126.888731112665;11150.721231969184;11159.27464714791;11162.497687604076;31125.688743112507;38544.42183367252;38872;38930.61069389298;38975;3222.0978126124724;3248.59392575928;3263.951040734391;3653.451982202664;4014.498187726534;28672.99644512453;30305.393892122254;30383.088507344815;30384.54423183654;30396.240093997607;2891.479533683957;3055.022392834294;3057.0981560439723;3115.688431156866;3175.491921292582;507306;512509;513568;515744;517536.41231793974;61301;61524.38475615276;61588.69205653943;61808.38191618116;61941.380586194464;63966;64538;64566;64611.353886461475;64732.67633661802;15819.762703559456;15832.60418489547;16004.03975761458;16015.519534413987;16041.598960026093;11037.337759734442;11305;12086.395680215954;12261.325627090417;12283.631491055283;18972.715409268872;19241.615167696567;19274.80725192758;19467.31864384768;19471.90264048699;7037.401820845285;7821.178776228528;7943.563104029218;8088.78668199763;8125.309348705308;791.1969351108629;850.5832142250252;935.569637966543;950.6815216902277;1041.656253436364;28869;29820.701792981792;29987.250318741768;30490.237744056132;30534.54198187029;955.8327292723745;1049.0872940541778;1050.9001644843727;1053.378506681062;1113.7549739057395;13502.919766418663;14860.479883204252;15385.615359615875;15517.5344739658;15751;688267;692203;696537;698184.5090774513;699880;56264.59338516488;56758.71620641871;56915.715421423454;56997.14504282439;57051;1294.72810709752;1336.0447280194708;1339.9866001340056;1354.3296068445986;1356.9660758481116;28387.71612283851;28553.28616784597;29479.26301842471;29645.40709185761;29844.403111938074;50277.497225027284;50603.98792024065;50763.49236507588;50778;50798.74600627121;606715.966420165;614232;615079;616891;618089;40708;40946;41171.764847054634;41181.58818411898;41423.58576414197;45402.6379208611;45651;45686.314705280114;45749.54250457453;46576.60270191899;36244;36774.26451471009;36997.490577166864;37924.43113353247;38993.80503097466;37637.81181094077;37673.81163094167;38176.04559886025;38321;39174.62888798879;36322;36362.27275454423;36392.81803590965;36497.087572810895;36538;32262.35475290528;33612.66387336193;33941;34239;34358.31283374268;20389.89805050965;25182;25191.992320307716;25554;26339.917650506424 02:22:55 INFO - PROCESS | 2182 | |2;jquery.html;357.58520116664664;363.7635536901012;376.4785771706187;381.2013831024007;397.9692596175904;18559.35042273519;18999.905000474995;19224.327148549804;19743.5064123397;19922.103505342257;11352.545898164084;11587.942060289708;11752.236104653213;11753.823692644617;11773.23473974191;5215.556677682392;5796.420357964212;5888.676122813244;5896.115582662592;5932.317783454893;3793.7154713396467;4035.4955630546183;4463.754493502856;5711.428857114297;5833.795817146401;3999.4600728901664;6518.28298887122;6562.507811914102;6587.835304117388;6590.275069742324;6353.904691429632;6399.488040956712;6545.37818907203;6549.541532092755;6557.245916719567;5756.856078598027;5764.33710123337;5767.509761670221;5769.451902069297;5794.5943783935145;19410.22359105634;22306.884655767146;23749.762502374928;24420.511589768284;24592;2032.7459067616549;2155.902984365707;2228.743694475124;2300.240920496249;2368.230325144335;7794.9220507795335;8455.492670439731;9676.806463870684;9770.804583908353;10079.992000799935;1800.3608718904732;1806.8283513066237;1928.9456728584014;1938.835199008086;1943.067327682706;73561.63219183924;93603.59594606086;122291.3885430576;122889;122931.38534307272;8971.685990990283;9201.871363736362;11128.49921753523;11257.043151332065;11340.94329528347;11751.647450576496;14218.217998010208;14249.643758905902;14264.35810388535;14309;6586.637734924625;8285.130061343472;8325.333973282122;8468.830623387497;8490.745277641681;8226.794330141674;8236.546989915612;8293.460925039853;8301.958490207511;8366.707165249187;3479.756417050782;4307.935380969288;4417.514073451917;4419.18245124653;4503.617192538605;1846.5475958390011;1885.5191926059042;1892.1296203746156;1896.8482521398255;1900.3443811884877;818.251300060438;862.4652715316419;900.5452246615417;951.2817822543935;1000.5147503460815;19942.70085948712;20931.581368372546;20988.68516972216;21059.578808424052;21304.893475532524;881.7442941547008;910.7222297199418;911.6444586611227;938.8497840345512;1003.9157709673535;9108.271338293052;10575.312604680672;11060.446977651085;11079.224454288122;11086.667399978014;564464;571900;574049;589541;594003;70199;104743.95256047495;112585;132360;135614;884.2263019857625;913.365211178238;931.2549960032082;948.672707915768;952.9047095290554;18766.437006889268;18889.527761806065;19050.952197629264;19153.808461915203;19314.71027934554;41911.74264772062;51193.48806511887;51870.48129518657;52428.73785631047;52469;72568.46883780484;73132;74446;74745;75127.62436188002;5961.582689211714;7741.535507869546;7772.805679857823;7865.134835168242;8062.355011599176;8697.95465672807;11899.167058305835;11981.580644677395;12337.074719395901;12385.133040687066;9934.354266972623;10329.276950613685;10750.892491074988;10781.9460902695;10853;8605.65577376898;10323.225758068025;10326.793464130526;10711.410872402093;10831.133509319394;10649.840252396376;10672.786544269227;10976.286541374786;11001.119910407308;11046.354702948169;7695.88456173169;7789;7882.487638303492;7940.0471943364855;7997.400194985516;8001.239882211181;8683.609237584198;9492.477913714813;10017.69946901594;10823.783524329627 02:22:55 INFO - PROCESS | 2182 | |3;mootools.html;1225.5771758743235;1226.423580916969;1276.6616846535667;1777.1913779230451;1810.0316330763044;6607.5374723769355;7821.726239581616;8122.67509299628;8459.619317130728;8486.872696909551;7881.014873140857;8238.093809680946;8262.049864265611;8263.132371101037;8351.582420878962;2273.8294627902933;2310.4223944013997;2319.373769082347;2327;2328.080408238745;2327.197116994634;2351.7177938647387;2370.478494731165;2377.869217193054;2378.4053986503372;2118.69700134417;2155.5257843274453;2305.596520608895;2343.3321503371544;2344.6365813298967;2292.3695983604475;2297.8391512594126;2308.7806658367595;2316.247219653611;2325.767423257678;2320.025589252518;2348.894299756515;2350.929472115822;2364.8581085134947;2399.6280576510667;8175.934024740723;8219.342452603778;8328.66685332586;8335.333173346118;8335.49987000782;5000.82497112603;5157.638965272433;5161.535461808456;5166.715830629314;5173.706573356661;2835.979047542883;3152.3537674776844;3380.0948884110308;3485.6514348565192;3505.404081306159;1007.6976906927893;1038.1227862456212;1045.8535804987307;1069.4706120470416;1070.7216123807789;9543.284253680933;10591.364518128938;10807.67576972692;10810.405427701473;10872.836907446395;4039.7374170678813;4388.736675799462;4534.183846907522;4593.770311484413;4615.330777037311;9097.727068187965;9395.436273823592;9501.524923753785;9513.143817056498;9527.190188833889;5164.612654050961;5194.22086687;5206.427292997767;5212.530872221519;5216.530512253839;4284;4286.971126929577;4695.389599352063;4703.529647035269;4760.523947605212;3615.385384484644;3701.5002974598624;3787.4697542343542;3819.560750513671;3826.4069069294305;1231.1874163052341;1263.5261776833688;1269.2511418263084;1276.5787290194214;1278.5588971804711;741.0662565167927;820.6430202861774;865.1391865094146;876.8202518483696;884.35883984111;5338.172583249602;5419.810306639249;5570.3315602127195;5635.098384258499;5641.2384328116;415.0992346607868;424.23849190702765;424.8343146172995;425.4235510882771;431.1441788050717;2963.555466679972;3584.8386822592524;3601.333753255656;3668.79821609814;3677.3932301170007;7707.383409327241;9188.448693078437;9595.856062159073;9800.26498012652;9944.850827237597;8418.663253469915;9811;9836.016398360107;9856.112949834549;9878.654247101316;6740.955151951455;7283.890741638879;7714.267144621252;7806.6096695165015;7827.882581761278;4906.803727850846;5173.430922598436;5512.4211957745465;5527.806526771544;5725.2843394575675;9035.819283614423;9236.983931767634;9307.72076837696;9362.204212642002;9372.812543749224;8944.821103577762;9214.723558293263;9414.576344064397;9508.809823803347;9540.713778586654;6566.376194261537;6768.695408706521;6860.245373009065;6916.585004899722;7156.069710937545;6659.267480577229;7760.456768026184;7899.68401263966;7901.130875603794;7969.163237859942;7002.8949565757575;7143.357097861218;7183.066201393785;7214.170370407471;7252.601106939169;6959.373656370951;6964.930350696428;6996.895046574407;7042.401395881203;7076.893846592408;6597.538172327891;6618.503612229199;6710.731570737311;6711.261761206166;6725.798226053226;4751.6436267279405;4753.5008824072975;4777.641676874317;4779.4742578317055;4881.243407271879;4693.202155633482;4726.385569875894;4741.668083234141;4780.306855505863;4976.178930476575 02:22:55 INFO - PROCESS | 2182 | |4;prototype.html;295.64817866738565;300.85258223470504;301.9803712758672;308.23711314496626;311.20952779938943;5269.8155564555245;5362.35651721793;5368.62419630626;5444.809431669893;5493.489290445124;4658.529395600282;4757.548032936874;4852.514748525155;5094.1594636884965;5343.118385466393;4137.503499580054;4151.609210914332;4164.708470407073;4170.603792639709;4184.246835569596;3800.182960663457;4058.391241313797;4175.770332631704;4181.226473102486;4200.432941552882;4013.6789056875527;4043.0081739512843;4057.9391309130388;4075.470188875442;4094.406311084891;3698.594534077047;4068.0643452005907;4105.952981989603;4113.547509773923;4126.649234815044;3545.1846075402545;3545.3795585772386;3545.556805399325;3551.7691350062164;3554.626764189749;4352.542982986804;4535.006895449021;5025.84922452327;5215.06574548694;5250.474952504719;4050.432939388488;4615.46922103959;4642.558956899089;4650.6048185544205;4666.160091183586;5559.583031272671;5604.775808967636;5615.578831587647;5675.262215911946;5696.6866822324755;2838.58840468131;3309.4043072246986;3510.3154884797473;3537.832515269955;3662.08447888028;5940.495057920082;5949.583529152961;5951.613145145551;5965.821025369246;6003.669798161056;3890.90272743178;3918;3919.941200881989;3921.254961557295;3925.1953349563273;3468.5664291963503;3776.8489260429274;3847.5952529668957;3881.971277611435;3982.203559288154;4338.132373525307;4591.510452056461;4604.447466304065;4628.305754136906;4635.791389387486;4731.29030645406;5053.67151135175;5702.429757024264;5768.567357448124;5771.759071799584;1040.625374865048;1081.2485322700647;1131.2872890079198;1240.864608882862;1259.540267802264;834.820513589569;865.9004214515286;867.1718508824058;867.9609417576224;874.2481465939287;4252.702310838212;5046.1169295373165;5067.138586440314;5090.083784918676;5104.540591346797;5185.455527169593;5506.174073888948;5515.751791169327;5532.640378375393;5556;4377.737335759864;4514.480834703985;4624.930626040612;4834.347363105936;5054.7472626368535;2598.857062861523;2626.4090579619433;2645.550256456407;2920.678725340211;2923.8596947190613;4840.515948405131;5134.460881607452;5216.713080780593;5636.464535869168;5678.290213723285;4383.408239887574;5226;5279.788808447696;5517.310336207974;5584.52531534816;4683.578477937002;4761.47623761386;5006.64953453262;5381.354237491448;5713.171785028834;4717.669763116549;5463.262459567907;5501.504864562208;5503;5514.476124768141;4666.556677115668;5408.432114627907;5451.291332126798;5693.9715301424885;5813.6221145625395;4520;5795.5073818724195;6114.5847390279705;6140.938590614037;6155.753769849156;3150.6691797361864;3347.3974684557015;3899.2786334528205;3936.980315098406;3965.385365268388;5606.196901549225;5854.707264636666;5854.94145058561;5912.911306330495;5994.700264986646;5283.920741188808;5474.753636086306;5653.547716182778;5789.2763404574425;5870.823875283749;5261.4738526147075;5673.943260567342;5763.827085187451;5820.272465941757;5851.829634073288;5318.813841515528;5667.554773532723;5681;5687.74869528703;5810.215620891126;4313.97843010783;4935.481774413634;5757.913631295618;5814.854628634318;5877.147813567094;4873.805047798049;5456.809011684572;5631.339629241026;5631.436856314336;5638.689872057076 02:22:55 INFO - PROCESS | 2182 | |5;yui.html;650.830783996161;662.9502787290954;718.4755128756008;723.6345645449047;774.5701135869593;13820.170789752608;14734.484293049745;14749.852501475008;14764.187969661665;14804.92597537012;13252.867471325284;13358.66603334917;13375.464981400757;13408.59774206773;13439;651.9739210431577;676.8646270745846;796.1958421993799;810.8135128920337;824.7154731617583;809.2797410304834;824.7319621123129;827.1728271728272;828.5235989306173;828.6768160417442;875.7504111328273;880.2034159086037;884.8849649545582;888;888.3603805260235;821.2772759971247;855.0936007831688;872.9742552500786;873.4148120759044;874.0560194989396;649.0134994807913;653.5033374635263;653.5098675993005;653.6698967021671;653.7581094994835;13834.515791947233;14084.507042253472;14266;14370.928145359207;14417.495387661382;13746.518871839538;13870.098443601133;13922.930385348009;14117;14127.011109222358;9420.576074076684;11132.666020019413;11159.832602510969;11173.21787474877;11325;3669.6330366963357;3697.556293244828;3788.3938569828697;3831.1571454280015;3836.673882719972;94118;95484;100390;100616.49691751567;100753.4962325191;9445.90554094464;10057;10803.83794243087;10901.236913415985;11245.775084498264;21227.15091396358;21455;21671.45821354479;21694.674579881314;21753.23863664796;7598.35413989806;7679.769606911802;7682.923170768333;7687.846243075107;7799.220077992155;4158.9376159357635;4196.328587425997;4263.5736426357735;4306.267934451088;4357.346398040319;3373.763836531419;3472.201393679468;3475.669811367916;3479.6520347965;3484.3553942520716;576.4725276372156;612.7457055746899;631.6557476175495;635.0727937211631;645.8482256669679;496.8335607571432;573.8307199376194;587.63477005055;666.3835951744617;668.304962838652;6792.029467539099;7976.960115199387;8977.50623715689;9105.726828195166;9399.57701903416;491.50112635674816;500.73210832205325;520.5055197562331;592.733270028489;595.2232336800516;4265.722728022669;4512.984578469818;4513.165064463019;4539.432570928634;4625.814967401334;79565.60217198993;81832.77250841242;111910.88089119004;113260.43369783099;118975;71334.64332678408;77009;77513.61243193748;102456.48771756243;104858.47570762098;62701;74681;75038.62480687561;76723.23276767161;80641.59679201683;9324;9517;9552.665656701982;9628.807423851624;9630.807383852423;46389.76805116088;48019.279710805065;48682.75658621684;52524.73737631287;53023.73488132535;49872.25191622056;49946.75026624843;69677.65161174162;73679.63160184165;73824.63087684527;11596.304221746723;12042.21725587869;12060.638180854588;12080.637580872588;12486.750264994831;13652.795208071688;13875.167489950636;13965.231912244924;14019.859801401855;14243.792212207125;12482.438290276777;13194.538191163263;13393.464261429823;13401.798973015608;13421.261830599411;12418.130730848754;12940.223586584834;13073.477060917457;13244.940404767789;13289.335533223108;12368;12502.749945001231;12664.110262279031;12692.492300307884;12727.554535591209;8938.910610894069;10906.291091079054;10936.45317734126;11098.891210669946;11152.498137583665;9311.813763724824;10358.585656573652;10459.320144190604;10571.572837666816;11131 02:22:55 INFO - PROCESS | 2182 | __end_tp_report 02:22:55 INFO - PROCESS | 2182 | __start_cc_report 02:22:55 INFO - PROCESS | 2182 | _x_x_mozilla_cycle_collect,373 02:22:55 INFO - PROCESS | 2182 | __end_cc_report 02:22:55 INFO - PROCESS | 2182 | __startTimestamp1479982975157__endTimestamp 02:22:55 INFO - PROCESS | 2182 | 02:22:55 INFO - PROCESS | 2182 | ------- Summary: start ------- 02:22:55 INFO - PROCESS | 2182 | Number of tests: 6 02:22:55 INFO - PROCESS | 2182 | 02:22:55 INFO - PROCESS | 2182 | [#0] dojo.html Cycles:165 Average:5630.92 Median:5827.07 stddev:669.80 (11.5%) stddev-sans-first:639.37 02:22:55 INFO - PROCESS | 2182 | Values: 3003.9 3302.9 3325.2 3351.2 3375.7 4076.0 4507.4 5026.7 5449.8 5667.5 5515.7 5591.5 5615.6 5676.5 5695.9 5140.2 5181.9 5184.5 5186.6 5245.9 5163.5 5182.9 5233.2 5248.1 5268.1 5225.2 5227.4 5238.6 5265.1 5305.6 5018.3 5106.2 5145.5 5149.8 5203.5 4999.6 5048.6 5086.2 5178.1 5184.0 5533.3 5619.0 5622.0 5634.2 5676.9 6141.8 6200.8 6212.1 6225.7 6241.8 6078.9 6083.2 6144.7 6160.8 6221.1 5673.0 5680.4 5686.5 5686.8 5692.6 6178.4 6193.8 6235.1 6241.4 6254.0 6094.7 6100.6 6105.7 6108.8 6125.1 5508.8 5910.4 5970.6 6064.5 6192.8 6056.0 6104.4 6124.7 6144.0 6184.1 5212.5 6103.5 6178.2 6182.7 6221.6 4752.7 4868.1 4882.0 4927.7 4944.7 3692.7 3702.8 4300.8 4320.8 4377.2 5323.9 5542.1 5651.0 5719.2 5743.9 5138.8 6201.0 6235.2 6251.8 6252.6 5662.2 5690.9 5713.4 5812.7 5817.4 5318.4 5344.2 5423.0 5461.6 5462.3 6269.4 6269.8 6286.1 6292.8 6295.8 5962.8 6046.9 6212.9 6236.8 6243.8 6155.3 6161.7 6178.6 6203.8 6291.2 5991.5 6061.9 6148.2 6157.9 6165.2 6006.8 6025.1 6094.4 6121.8 6133.4 5870.4 6010.6 6012.5 6024.4 6030.3 5841.7 5926.0 5933.2 5974.4 5976.1 5753.8 5766.6 5825.8 5859.4 5875.7 6001.5 6003.2 6008.4 6026.0 6034.7 5804.2 5827.1 5838.4 5856.6 5862.6 02:22:55 INFO - PROCESS | 2182 | 02:22:55 INFO - PROCESS | 2182 | [#1] ext.html Cycles:180 Average:73615.13 Median:20075.27 stddev:163830.49 (816.1%) stddev-sans-first:164197.96 02:22:55 INFO - PROCESS | 2182 | Values: 442.7 470.4 481.4 486.1 537.9 25134.9 39091.0 39157.4 39179.0 39405.8 39559.6 40081.6 40121.2 40187.0 40228.6 8334.0 8572.3 9038.2 9283.5 10899.1 10878.9 11168.8 11186.3 11189.3 11195.8 11195.7 11206.7 11216.8 11217.7 11236.7 11190.7 11192.0 11194.3 11195.1 11199.7 11118.4 11126.9 11150.7 11159.3 11162.5 31125.7 38544.4 38872.0 38930.6 38975.0 3222.1 3248.6 3264.0 3653.5 4014.5 28673.0 30305.4 30383.1 30384.5 30396.2 2891.5 3055.0 3057.1 3115.7 3175.5 507306.0 512509.0 513568.0 515744.0 517536.4 61301.0 61524.4 61588.7 61808.4 61941.4 63966.0 64538.0 64566.0 64611.4 64732.7 15819.8 15832.6 16004.0 16015.5 16041.6 11037.3 11305.0 12086.4 12261.3 12283.6 18972.7 19241.6 19274.8 19467.3 19471.9 7037.4 7821.2 7943.6 8088.8 8125.3 791.2 850.6 935.6 950.7 1041.7 28869.0 29820.7 29987.3 30490.2 30534.5 955.8 1049.1 1050.9 1053.4 1113.8 13502.9 14860.5 15385.6 15517.5 15751.0 688267.0 692203.0 696537.0 698184.5 699880.0 56264.6 56758.7 56915.7 56997.1 57051.0 1294.7 1336.0 1340.0 1354.3 1357.0 28387.7 28553.3 29479.3 29645.4 29844.4 50277.5 50604.0 50763.5 50778.0 50798.7 606716.0 614232.0 615079.0 616891.0 618089.0 40708.0 40946.0 41171.8 41181.6 41423.6 45402.6 45651.0 45686.3 45749.5 46576.6 36244.0 36774.3 36997.5 37924.4 38993.8 37637.8 37673.8 38176.0 38321.0 39174.6 36322.0 36362.3 36392.8 36497.1 36538.0 32262.4 33612.7 33941.0 34239.0 34358.3 20389.9 25182.0 25192.0 25554.0 26339.9 02:22:55 INFO - PROCESS | 2182 | 02:22:55 INFO - PROCESS | 2182 | [#2] jquery.html Cycles:180 Average:32909.94 Median:23109.84 stddev:96333.59 (416.9%) stddev-sans-first:96572.82 02:22:55 INFO - PROCESS | 2182 | Values: 357.6 363.8 376.5 381.2 398.0 18559.4 18999.9 19224.3 19743.5 19922.1 11352.5 11587.9 11752.2 11753.8 11773.2 5215.6 5796.4 5888.7 5896.1 5932.3 3793.7 4035.5 4463.8 5711.4 5833.8 3999.5 6518.3 6562.5 6587.8 6590.3 6353.9 6399.5 6545.4 6549.5 6557.2 5756.9 5764.3 5767.5 5769.5 5794.6 19410.2 22306.9 23749.8 24420.5 24592.0 2032.7 2155.9 2228.7 2300.2 2368.2 7794.9 8455.5 9676.8 9770.8 10080.0 1800.4 1806.8 1928.9 1938.8 1943.1 73561.6 93603.6 122291.4 122889.0 122931.4 8971.7 9201.9 11128.5 11257.0 11340.9 11751.6 14218.2 14249.6 14264.4 14309.0 6586.6 8285.1 8325.3 8468.8 8490.7 8226.8 8236.5 8293.5 8302.0 8366.7 3479.8 4307.9 4417.5 4419.2 4503.6 1846.5 1885.5 1892.1 1896.8 1900.3 818.3 862.5 900.5 951.3 1000.5 19942.7 20931.6 20988.7 21059.6 21304.9 881.7 910.7 911.6 938.8 1003.9 9108.3 10575.3 11060.4 11079.2 11086.7 564464.0 571900.0 574049.0 589541.0 594003.0 70199.0 104744.0 112585.0 132360.0 135614.0 884.2 913.4 931.3 948.7 952.9 18766.4 18889.5 19051.0 19153.8 19314.7 41911.7 51193.5 51870.5 52428.7 52469.0 72568.5 73132.0 74446.0 74745.0 75127.6 5961.6 7741.5 7772.8 7865.1 8062.4 8698.0 11899.2 11981.6 12337.1 12385.1 9934.4 10329.3 10750.9 10781.9 10853.0 8605.7 10323.2 10326.8 10711.4 10831.1 10649.8 10672.8 10976.3 11001.1 11046.4 7695.9 7789.0 7882.5 7940.0 7997.4 8001.2 8683.6 9492.5 10017.7 10823.8 02:22:55 INFO - PROCESS | 2182 | 02:22:55 INFO - PROCESS | 2182 | [#3] mootools.html Cycles:180 Average:5317.58 Median:5173.57 stddev:2925.45 (56.5%) stddev-sans-first:2917.49 02:22:55 INFO - PROCESS | 2182 | Values: 1225.6 1226.4 1276.7 1777.2 1810.0 6607.5 7821.7 8122.7 8459.6 8486.9 7881.0 8238.1 8262.0 8263.1 8351.6 2273.8 2310.4 2319.4 2327.0 2328.1 2327.2 2351.7 2370.5 2377.9 2378.4 2118.7 2155.5 2305.6 2343.3 2344.6 2292.4 2297.8 2308.8 2316.2 2325.8 2320.0 2348.9 2350.9 2364.9 2399.6 8175.9 8219.3 8328.7 8335.3 8335.5 5000.8 5157.6 5161.5 5166.7 5173.7 2836.0 3152.4 3380.1 3485.7 3505.4 1007.7 1038.1 1045.9 1069.5 1070.7 9543.3 10591.4 10807.7 10810.4 10872.8 4039.7 4388.7 4534.2 4593.8 4615.3 9097.7 9395.4 9501.5 9513.1 9527.2 5164.6 5194.2 5206.4 5212.5 5216.5 4284.0 4287.0 4695.4 4703.5 4760.5 3615.4 3701.5 3787.5 3819.6 3826.4 1231.2 1263.5 1269.3 1276.6 1278.6 741.1 820.6 865.1 876.8 884.4 5338.2 5419.8 5570.3 5635.1 5641.2 415.1 424.2 424.8 425.4 431.1 2963.6 3584.8 3601.3 3668.8 3677.4 7707.4 9188.4 9595.9 9800.3 9944.9 8418.7 9811.0 9836.0 9856.1 9878.7 6741.0 7283.9 7714.3 7806.6 7827.9 4906.8 5173.4 5512.4 5527.8 5725.3 9035.8 9237.0 9307.7 9362.2 9372.8 8944.8 9214.7 9414.6 9508.8 9540.7 6566.4 6768.7 6860.2 6916.6 7156.1 6659.3 7760.5 7899.7 7901.1 7969.2 7002.9 7143.4 7183.1 7214.2 7252.6 6959.4 6964.9 6996.9 7042.4 7076.9 6597.5 6618.5 6710.7 6711.3 6725.8 4751.6 4753.5 4777.6 4779.5 4881.2 4693.2 4726.4 4741.7 4780.3 4976.2 02:22:55 INFO - PROCESS | 2182 | 02:22:55 INFO - PROCESS | 2182 | [#4] prototype.html Cycles:180 Average:4490.67 Median:4971.07 stddev:1404.41 (28.3%) stddev-sans-first:1372.60 02:22:55 INFO - PROCESS | 2182 | Values: 295.6 300.9 302.0 308.2 311.2 5269.8 5362.4 5368.6 5444.8 5493.5 4658.5 4757.5 4852.5 5094.2 5343.1 4137.5 4151.6 4164.7 4170.6 4184.2 3800.2 4058.4 4175.8 4181.2 4200.4 4013.7 4043.0 4057.9 4075.5 4094.4 3698.6 4068.1 4106.0 4113.5 4126.6 3545.2 3545.4 3545.6 3551.8 3554.6 4352.5 4535.0 5025.8 5215.1 5250.5 4050.4 4615.5 4642.6 4650.6 4666.2 5559.6 5604.8 5615.6 5675.3 5696.7 2838.6 3309.4 3510.3 3537.8 3662.1 5940.5 5949.6 5951.6 5965.8 6003.7 3890.9 3918.0 3919.9 3921.3 3925.2 3468.6 3776.8 3847.6 3882.0 3982.2 4338.1 4591.5 4604.4 4628.3 4635.8 4731.3 5053.7 5702.4 5768.6 5771.8 1040.6 1081.2 1131.3 1240.9 1259.5 834.8 865.9 867.2 868.0 874.2 4252.7 5046.1 5067.1 5090.1 5104.5 5185.5 5506.2 5515.8 5532.6 5556.0 4377.7 4514.5 4624.9 4834.3 5054.7 2598.9 2626.4 2645.6 2920.7 2923.9 4840.5 5134.5 5216.7 5636.5 5678.3 4383.4 5226.0 5279.8 5517.3 5584.5 4683.6 4761.5 5006.6 5381.4 5713.2 4717.7 5463.3 5501.5 5503.0 5514.5 4666.6 5408.4 5451.3 5694.0 5813.6 4520.0 5795.5 6114.6 6140.9 6155.8 3150.7 3347.4 3899.3 3937.0 3965.4 5606.2 5854.7 5854.9 5912.9 5994.7 5283.9 5474.8 5653.5 5789.3 5870.8 5261.5 5673.9 5763.8 5820.3 5851.8 5318.8 5667.6 5681.0 5687.7 5810.2 4314.0 4935.5 5757.9 5814.9 5877.1 4873.8 5456.8 5631.3 5631.4 5638.7 02:22:55 INFO - PROCESS | 2182 | 02:22:55 INFO - PROCESS | 2182 | [#5] yui.html Cycles:180 Average:19608.47 Median:4513.07 stddev:28460.63 (630.6%) stddev-sans-first:28504.87 02:22:55 INFO - PROCESS | 2182 | Values: 650.8 663.0 718.5 723.6 774.6 13820.2 14734.5 14749.9 14764.2 14804.9 13252.9 13358.7 13375.5 13408.6 13439.0 652.0 676.9 796.2 810.8 824.7 809.3 824.7 827.2 828.5 828.7 875.8 880.2 884.9 888.0 888.4 821.3 855.1 873.0 873.4 874.1 649.0 653.5 653.5 653.7 653.8 13834.5 14084.5 14266.0 14370.9 14417.5 13746.5 13870.1 13922.9 14117.0 14127.0 9420.6 11132.7 11159.8 11173.2 11325.0 3669.6 3697.6 3788.4 3831.2 3836.7 94118.0 95484.0 100390.0 100616.5 100753.5 9445.9 10057.0 10803.8 10901.2 11245.8 21227.2 21455.0 21671.5 21694.7 21753.2 7598.4 7679.8 7682.9 7687.8 7799.2 4158.9 4196.3 4263.6 4306.3 4357.3 3373.8 3472.2 3475.7 3479.7 3484.4 576.5 612.7 631.7 635.1 645.8 496.8 573.8 587.6 666.4 668.3 6792.0 7977.0 8977.5 9105.7 9399.6 491.5 500.7 520.5 592.7 595.2 4265.7 4513.0 4513.2 4539.4 4625.8 79565.6 81832.8 111910.9 113260.4 118975.0 71334.6 77009.0 77513.6 102456.5 104858.5 62701.0 74681.0 75038.6 76723.2 80641.6 9324.0 9517.0 9552.7 9628.8 9630.8 46389.8 48019.3 48682.8 52524.7 53023.7 49872.3 49946.8 69677.7 73679.6 73824.6 11596.3 12042.2 12060.6 12080.6 12486.8 13652.8 13875.2 13965.2 14019.9 14243.8 12482.4 13194.5 13393.5 13401.8 13421.3 12418.1 12940.2 13073.5 13244.9 13289.3 12368.0 12502.7 12664.1 12692.5 12727.6 8938.9 10906.3 10936.5 11098.9 11152.5 9311.8 10358.6 10459.3 10571.6 11131.0 02:22:55 INFO - PROCESS | 2182 | -------- Summary: end -------- 02:22:55 INFO - PROCESS | 2182 | 02:22:55 INFO - TEST-INFO | 2182: exit 0 02:22:55 INFO - TEST-OK | dromaeo_css | took 1117075ms 02:22:55 INFO - TEST-START | kraken 02:22:55 INFO - operating with platform_type : mac_ 02:22:55 INFO - Initialising browser for kraken test... 02:22:55 INFO - TEST-INFO | started process 2186 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmpmxbgps/profile http://localhost:57120/getInfo.html) 02:22:58 INFO - PROCESS | 2186 | 2016-11-24 02:22:58.924 plugin-container[2187:76003] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 02:22:58 INFO - PROCESS | 2186 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:22:58 INFO - PROCESS | 2186 | 2016-11-24 02:22:58.929 plugin-container[2187:76003] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x470b, name = 'com.apple.CFPasteboardClient' 02:22:58 INFO - PROCESS | 2186 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:22:58 INFO - PROCESS | 2186 | 2016-11-24 02:22:58.929 plugin-container[2187:76003] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:22:59 INFO - PROCESS | 2186 | __metrics Screen width/height:1600/1200 02:22:59 INFO - PROCESS | 2186 | colorDepth:24 02:22:59 INFO - PROCESS | 2186 | Browser inner width/height: 1024/663 02:22:59 INFO - PROCESS | 2186 | __metrics 02:22:59 ERROR - PROCESS | 2186 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 02:23:00 INFO - TEST-INFO | 2186: exit 0 02:23:00 INFO - Browser initialized. 02:23:00 INFO - Running cycle 1/1 for kraken test... 02:23:00 INFO - TEST-INFO | started process 2188 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmpmxbgps/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:23:02 INFO - PROCESS | 2188 | 2016-11-24 02:23:02.821 plugin-container[2189:76120] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4543, name = 'com.apple.tsm.portname' 02:23:02 INFO - PROCESS | 2188 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:23:02 INFO - PROCESS | 2188 | 2016-11-24 02:23:02.822 plugin-container[2189:76120] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b27, name = 'com.apple.CFPasteboardClient' 02:23:02 INFO - PROCESS | 2188 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:23:02 INFO - PROCESS | 2188 | 2016-11-24 02:23:02.822 plugin-container[2189:76120] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:23:12 INFO - PROCESS | 2188 | RSS: Main: 253698048 02:23:12 INFO - PROCESS | 2188 | 02:23:15 INFO - PROCESS | 2188 | Cycle 1(1): loaded http://localhost:57120/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57120/tests/kraken/driver.html?testName=audio-beat-detection) 02:23:16 INFO - PROCESS | 2188 | RSS: Main: 262983680 02:23:16 INFO - PROCESS | 2188 | 02:23:18 INFO - PROCESS | 2188 | Cycle 1(1): loaded http://localhost:57120/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57120/tests/kraken/driver.html?testName=audio-dft) 02:23:19 INFO - PROCESS | 2188 | RSS: Main: 270610432 02:23:19 INFO - PROCESS | 2188 | 02:23:22 INFO - PROCESS | 2188 | Cycle 1(1): loaded http://localhost:57120/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57120/tests/kraken/driver.html?testName=audio-fft) 02:23:23 INFO - PROCESS | 2188 | RSS: Main: 270540800 02:23:23 INFO - PROCESS | 2188 | 02:23:25 INFO - PROCESS | 2188 | Cycle 1(1): loaded http://localhost:57120/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57120/tests/kraken/driver.html?testName=audio-oscillator) 02:23:26 INFO - PROCESS | 2188 | RSS: Main: 271450112 02:23:26 INFO - PROCESS | 2188 | 02:23:28 INFO - PROCESS | 2188 | Cycle 1(1): loaded http://localhost:57120/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57120/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:23:28 INFO - PROCESS | 2188 | RSS: Main: 271552512 02:23:28 INFO - PROCESS | 2188 | 02:23:31 INFO - PROCESS | 2188 | Cycle 1(1): loaded http://localhost:57120/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57120/tests/kraken/driver.html?testName=imaging-darkroom) 02:23:32 INFO - PROCESS | 2188 | RSS: Main: 276946944 02:23:32 INFO - PROCESS | 2188 | 02:23:35 INFO - PROCESS | 2188 | Cycle 1(1): loaded http://localhost:57120/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57120/tests/kraken/driver.html?testName=imaging-desaturate) 02:23:36 INFO - PROCESS | 2188 | RSS: Main: 276033536 02:23:36 INFO - PROCESS | 2188 | 02:23:39 INFO - PROCESS | 2188 | Cycle 1(1): loaded http://localhost:57120/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57120/tests/kraken/driver.html?testName=json-parse-financial) 02:23:39 INFO - PROCESS | 2188 | RSS: Main: 276201472 02:23:39 INFO - PROCESS | 2188 | 02:23:41 INFO - PROCESS | 2188 | Cycle 1(1): loaded http://localhost:57120/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57120/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:23:42 INFO - PROCESS | 2188 | RSS: Main: 275476480 02:23:42 INFO - PROCESS | 2188 | 02:23:44 INFO - PROCESS | 2188 | Cycle 1(1): loaded http://localhost:57120/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57120/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:23:44 INFO - PROCESS | 2188 | RSS: Main: 275636224 02:23:44 INFO - PROCESS | 2188 | 02:23:46 INFO - PROCESS | 2188 | Cycle 1(1): loaded http://localhost:57120/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57120/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:23:47 INFO - PROCESS | 2188 | RSS: Main: 275472384 02:23:47 INFO - PROCESS | 2188 | 02:23:50 INFO - PROCESS | 2188 | Cycle 1(1): loaded http://localhost:57120/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57120/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:23:51 INFO - PROCESS | 2188 | RSS: Main: 275804160 02:23:51 INFO - PROCESS | 2188 | 02:23:54 INFO - PROCESS | 2188 | Cycle 1(1): loaded http://localhost:57120/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57120/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:23:55 INFO - PROCESS | 2188 | RSS: Main: 275869696 02:23:55 INFO - PROCESS | 2188 | 02:23:56 INFO - PROCESS | 2188 | Cycle 1(1): loaded http://localhost:57120/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57120/tests/kraken/driver.html?testName=ai-astar) 02:23:57 INFO - PROCESS | 2188 | RSS: Main: 277831680 02:23:57 INFO - PROCESS | 2188 | 02:23:57 INFO - PROCESS | 2188 | __start_tp_report 02:23:57 INFO - PROCESS | 2188 | _x_x_mozilla_page_load 02:23:57 INFO - PROCESS | 2188 | _x_x_mozilla_page_load_details 02:23:57 INFO - PROCESS | 2188 | |i|pagename|runs| 02:23:57 INFO - PROCESS | 2188 | |0;ai-astar;111;120;118;119;119;119;117;118;99;175 02:23:57 INFO - PROCESS | 2188 | |1;audio-beat-detection;153;149;151;153;119;146;145;118;138;116 02:23:57 INFO - PROCESS | 2188 | |2;audio-dft;196;214;206;191;245;277;226;201;200;199 02:23:57 INFO - PROCESS | 2188 | |3;audio-fft;85;57;60;87;60;58;60;61;74;58 02:23:57 INFO - PROCESS | 2188 | |4;audio-oscillator;89;70;83;70;70;76;117;85;72;82 02:23:57 INFO - PROCESS | 2188 | |5;imaging-gaussian-blur;92;90;92;90;92;80;78;82;125;103 02:23:57 INFO - PROCESS | 2188 | |6;imaging-darkroom;107;135;155;152;97;99;103;147;154;140 02:23:57 INFO - PROCESS | 2188 | |7;imaging-desaturate;108;120;91;93;93;94;92;92;78;146 02:23:57 INFO - PROCESS | 2188 | |8;json-parse-financial;56;55;58;58;54;50;82;90;89;63 02:23:57 INFO - PROCESS | 2188 | |9;json-stringify-tinderbox;69;82;87;78;72;70;71;74;75;76 02:23:57 INFO - PROCESS | 2188 | |10;stanford-crypto-aes;90;89;89;90;90;88;91;88;90;92 02:23:57 INFO - PROCESS | 2188 | |11;stanford-crypto-ccm;149;151;170;155;168;177;164;194;222;202 02:23:57 INFO - PROCESS | 2188 | |12;stanford-crypto-pbkdf2;199;245;182;239;236;177;177;181;174;177 02:23:57 INFO - PROCESS | 2188 | |13;stanford-crypto-sha256-iterative;71;69;70;69;68;72;67;75;79;95 02:23:57 INFO - PROCESS | 2188 | __end_tp_report 02:23:57 INFO - PROCESS | 2188 | __start_cc_report 02:23:57 INFO - PROCESS | 2188 | _x_x_mozilla_cycle_collect,931 02:23:57 INFO - PROCESS | 2188 | __end_cc_report 02:23:57 INFO - PROCESS | 2188 | __startTimestamp1479983037204__endTimestamp 02:23:57 INFO - PROCESS | 2188 | 02:23:57 INFO - PROCESS | 2188 | ------- Summary: start ------- 02:23:57 INFO - PROCESS | 2188 | Number of tests: 14 02:23:57 INFO - PROCESS | 2188 | 02:23:57 INFO - PROCESS | 2188 | [#0] ai-astar Cycles:10 Average:121.50 Median:119.00 stddev:19.85 (16.7%) stddev-sans-first:20.68 02:23:57 INFO - PROCESS | 2188 | Values: 111.0 120.0 118.0 119.0 119.0 119.0 117.0 118.0 99.0 175.0 02:23:57 INFO - PROCESS | 2188 | 02:23:57 INFO - PROCESS | 2188 | [#1] audio-beat-detection Cycles:10 Average:138.80 Median:147.50 stddev:15.24 (10.3%) stddev-sans-first:15.28 02:23:57 INFO - PROCESS | 2188 | Values: 153.0 149.0 151.0 153.0 119.0 146.0 145.0 118.0 138.0 116.0 02:23:57 INFO - PROCESS | 2188 | 02:23:57 INFO - PROCESS | 2188 | [#2] audio-dft Cycles:10 Average:215.50 Median:210.00 stddev:26.99 (12.9%) stddev-sans-first:27.69 02:23:57 INFO - PROCESS | 2188 | Values: 196.0 214.0 206.0 191.0 245.0 277.0 226.0 201.0 200.0 199.0 02:23:57 INFO - PROCESS | 2188 | 02:23:57 INFO - PROCESS | 2188 | [#3] audio-fft Cycles:10 Average:66.00 Median:60.50 stddev:11.59 (19.1%) stddev-sans-first:10.04 02:23:57 INFO - PROCESS | 2188 | Values: 85.0 57.0 60.0 87.0 60.0 58.0 60.0 61.0 74.0 58.0 02:23:57 INFO - PROCESS | 2188 | 02:23:57 INFO - PROCESS | 2188 | [#4] audio-oscillator Cycles:10 Average:81.40 Median:79.00 stddev:14.33 (18.1%) stddev-sans-first:14.93 02:23:57 INFO - PROCESS | 2188 | Values: 89.0 70.0 83.0 70.0 70.0 76.0 117.0 85.0 72.0 82.0 02:23:57 INFO - PROCESS | 2188 | 02:23:57 INFO - PROCESS | 2188 | [#5] imaging-gaussian-blur Cycles:10 Average:92.40 Median:90.00 stddev:13.57 (15.1%) stddev-sans-first:14.39 02:23:57 INFO - PROCESS | 2188 | Values: 92.0 90.0 92.0 90.0 92.0 80.0 78.0 82.0 125.0 103.0 02:23:57 INFO - PROCESS | 2188 | 02:23:57 INFO - PROCESS | 2188 | [#6] imaging-darkroom Cycles:10 Average:128.90 Median:153.00 stddev:24.48 (16.0%) stddev-sans-first:24.65 02:23:57 INFO - PROCESS | 2188 | Values: 107.0 135.0 155.0 152.0 97.0 99.0 103.0 147.0 154.0 140.0 02:23:57 INFO - PROCESS | 2188 | 02:23:57 INFO - PROCESS | 2188 | [#7] imaging-desaturate Cycles:10 Average:100.70 Median:92.00 stddev:19.44 (21.1%) stddev-sans-first:20.44 02:23:57 INFO - PROCESS | 2188 | Values: 108.0 120.0 91.0 93.0 93.0 94.0 92.0 92.0 78.0 146.0 02:23:57 INFO - PROCESS | 2188 | 02:23:57 INFO - PROCESS | 2188 | [#8] json-parse-financial Cycles:10 Average:65.50 Median:60.50 stddev:15.34 (25.3%) stddev-sans-first:15.88 02:23:57 INFO - PROCESS | 2188 | Values: 56.0 55.0 58.0 58.0 54.0 50.0 82.0 90.0 89.0 63.0 02:23:57 INFO - PROCESS | 2188 | 02:23:57 INFO - PROCESS | 2188 | [#9] json-stringify-tinderbox Cycles:10 Average:75.40 Median:75.50 stddev:5.66 (7.5%) stddev-sans-first:5.51 02:23:57 INFO - PROCESS | 2188 | Values: 69.0 82.0 87.0 78.0 72.0 70.0 71.0 74.0 75.0 76.0 02:23:57 INFO - PROCESS | 2188 | 02:23:57 INFO - PROCESS | 2188 | [#10] stanford-crypto-aes Cycles:10 Average:89.70 Median:90.00 stddev:1.25 (1.4%) stddev-sans-first:1.32 02:23:57 INFO - PROCESS | 2188 | Values: 90.0 89.0 89.0 90.0 90.0 88.0 91.0 88.0 90.0 92.0 02:23:57 INFO - PROCESS | 2188 | 02:23:57 INFO - PROCESS | 2188 | [#11] stanford-crypto-ccm Cycles:10 Average:175.20 Median:173.50 stddev:23.92 (13.8%) stddev-sans-first:23.42 02:23:57 INFO - PROCESS | 2188 | Values: 149.0 151.0 170.0 155.0 168.0 177.0 164.0 194.0 222.0 202.0 02:23:57 INFO - PROCESS | 2188 | 02:23:57 INFO - PROCESS | 2188 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:198.70 Median:190.50 stddev:29.39 (15.4%) stddev-sans-first:31.17 02:23:57 INFO - PROCESS | 2188 | Values: 199.0 245.0 182.0 239.0 236.0 177.0 177.0 181.0 174.0 177.0 02:23:57 INFO - PROCESS | 2188 | 02:23:57 INFO - PROCESS | 2188 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.50 Median:71.50 stddev:8.36 (11.7%) stddev-sans-first:8.81 02:23:57 INFO - PROCESS | 2188 | Values: 71.0 69.0 70.0 69.0 68.0 72.0 67.0 75.0 79.0 95.0 02:23:57 INFO - PROCESS | 2188 | -------- Summary: end -------- 02:23:57 INFO - PROCESS | 2188 | 02:23:58 INFO - TEST-INFO | 2188: exit 0 02:23:58 INFO - TEST-OK | kraken | took 62449ms 02:23:58 INFO - SUITE-END | took 1179s 02:23:58 INFO - Completed test suite (00:19:39) 02:23:58 INFO - javascript benchmark 02:23:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7064.565913145391, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3003.8948636797713, 3302.917427064325, 3325.2185736351967, 3351.178961154516, 3375.713064389526, 4075.9606300393393, 4507.414036175301, 5026.663299713311, 5449.7820087196515, 5667.546596272298, 5515.669059856412, 5591.496765291123, 5615.634983726055, 5676.489115979561, 5695.860827834439, 5140.177571588547, 5181.922271165926, 5184.455632158626, 5186.585073194155, 5245.868853278679, 5163.535281824635, 5182.896342073156, 5233.241180028894, 5248.107821670325, 5268.0780863348955, 5225.163973764198, 5227.424983251877, 5238.554722848525, 5265.078611243036, 5305.575553955674, 5018.347614810088, 5106.157484015133, 5145.536901678867, 5149.794008239666, 5203.531682148587, 4999.575036121934, 5048.596112311043, 5086.160783470723, 5178.0679477694, 5184.0, 5533.280673512458, 5618.971905140489, 5621.971890140564, 5634.183043458716, 5676.943230567724, 6141.754329826802, 6200.81397558074, 6212.06818977157, 6225.688715564249, 6241.750329986795, 6078.939210607881, 6083.209182806251, 6144.662043587601, 6160.84597885052, 6221.066839973995, 5673.0, 5680.375158732536, 5686.545076393879, 5686.800961966312, 5692.601517893749, 6178.443940045418, 6193.845153871099, 6235.127082208494, 6241.438270555672, 6254.0, 6094.6647934363145, 6100.603460775035, 6105.664188469588, 6108.847278818065, 6125.081237814364, 5508.779648814003, 5910.379410161957, 5970.611910225822, 6064.48451881595, 6192.783252586138, 6056.031035034373, 6104.436136822545, 6124.663143527148, 6143.96928015357, 6184.134221209034, 5212.478752124833, 6103.542234332443, 6178.165947597106, 6182.6599537025895, 6221.564490485623, 4752.667313288037, 4868.123737727243, 4882.023595280958, 4927.655064145475, 4944.728039957765, 3692.7045836333027, 3702.777833330009, 4300.763458009839, 4320.827166913288, 4377.212101821639, 5323.920141197885, 5542.140968149944, 5650.971745141248, 5719.2279042328755, 5743.913841292384, 5138.794448222029, 6200.968995155085, 6235.220597425322, 6251.843703907348, 6252.5935814171935, 5662.17898404729, 5690.857728556736, 5713.371529131792, 5812.680302583314, 5817.418258174233, 5318.441563635763, 5344.225087362311, 5422.972885135549, 5461.644993075437, 5462.3445186577865, 6269.373062693877, 6269.811905642838, 6286.088517164985, 6292.8426789328805, 6295.779647712399, 5962.821115366546, 6046.90929636047, 6212.9068063979985, 6236.750529978932, 6243.750249989949, 6155.2921414037955, 6161.661108639068, 6178.62928224308, 6203.751849925952, 6291.150694656163, 5991.490723288569, 6061.9393806063135, 6148.200733904564, 6157.9384206157365, 6165.167702360124, 6006.849828754316, 6025.0661147522205, 6094.3600921904335, 6121.785737499345, 6133.395871483965, 5870.412958704095, 6010.579259451796, 6012.549058820518, 6024.427679370453, 6030.276366836008, 5841.707914604338, 5925.970370148121, 5933.228680271537, 5974.4324289192455, 5976.103584462366, 5753.769849206153, 5766.587186139423, 5825.766969321349, 5859.414058594107, 5875.706214689334, 6001.459868611846, 6003.189569408249, 6008.429199226067, 6026.035834266497, 6034.668093254913, 5804.1874137619925, 5827.067669172912, 5838.357780644041, 5856.560757943086, 5862.560307977004], "value": 5586.48408390585, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [442.67021069303377, 470.4260801821778, 481.41027241629007, 486.13468026912085, 537.9408265090842, 25134.87432562837, 39091.0227244319, 39157.41263881044, 39179.0, 39405.80297098514, 39559.60440395595, 40081.59918400815, 40121.197576048464, 40187.0, 40228.59771402285, 8334.041585217718, 8572.271356934669, 9038.231750301216, 9283.535823208846, 10899.073578745816, 10878.891211087866, 11168.776624467506, 11186.272892262017, 11189.272697274651, 11195.832062519068, 11195.720106997349, 11206.66380008597, 11216.831747523753, 11217.719557011058, 11236.719082022933, 11190.66428007161, 11192.0, 11194.328340299608, 11195.104391648649, 11199.720006999809, 11118.444077796077, 11126.888731112665, 11150.721231969184, 11159.27464714791, 11162.497687604076, 31125.688743112507, 38544.42183367252, 38872.0, 38930.61069389298, 38975.0, 3222.0978126124724, 3248.59392575928, 3263.951040734391, 3653.451982202664, 4014.498187726534, 28672.99644512453, 30305.393892122254, 30383.088507344815, 30384.54423183654, 30396.240093997607, 2891.479533683957, 3055.022392834294, 3057.0981560439723, 3115.688431156866, 3175.491921292582, 507306.0, 512509.0, 513568.0, 515744.0, 517536.41231793974, 61301.0, 61524.38475615276, 61588.69205653943, 61808.38191618116, 61941.380586194464, 63966.0, 64538.0, 64566.0, 64611.353886461475, 64732.67633661802, 15819.762703559456, 15832.60418489547, 16004.03975761458, 16015.519534413987, 16041.598960026093, 11037.337759734442, 11305.0, 12086.395680215954, 12261.325627090417, 12283.631491055283, 18972.715409268872, 19241.615167696567, 19274.80725192758, 19467.31864384768, 19471.90264048699, 7037.401820845285, 7821.178776228528, 7943.563104029218, 8088.78668199763, 8125.309348705308, 791.1969351108629, 850.5832142250252, 935.569637966543, 950.6815216902277, 1041.656253436364, 28869.0, 29820.701792981792, 29987.250318741768, 30490.237744056132, 30534.54198187029, 955.8327292723745, 1049.0872940541778, 1050.9001644843727, 1053.378506681062, 1113.7549739057395, 13502.919766418663, 14860.479883204252, 15385.615359615875, 15517.5344739658, 15751.0, 688267.0, 692203.0, 696537.0, 698184.5090774513, 699880.0, 56264.59338516488, 56758.71620641871, 56915.715421423454, 56997.14504282439, 57051.0, 1294.72810709752, 1336.0447280194708, 1339.9866001340056, 1354.3296068445986, 1356.9660758481116, 28387.71612283851, 28553.28616784597, 29479.26301842471, 29645.40709185761, 29844.403111938074, 50277.497225027284, 50603.98792024065, 50763.49236507588, 50778.0, 50798.74600627121, 606715.966420165, 614232.0, 615079.0, 616891.0, 618089.0, 40708.0, 40946.0, 41171.764847054634, 41181.58818411898, 41423.58576414197, 45402.6379208611, 45651.0, 45686.314705280114, 45749.54250457453, 46576.60270191899, 36244.0, 36774.26451471009, 36997.490577166864, 37924.43113353247, 38993.80503097466, 37637.81181094077, 37673.81163094167, 38176.04559886025, 38321.0, 39174.62888798879, 36322.0, 36362.27275454423, 36392.81803590965, 36497.087572810895, 36538.0, 32262.35475290528, 33612.66387336193, 33941.0, 34239.0, 34358.31283374268, 20389.89805050965, 25182.0, 25191.992320307716, 25554.0, 26339.917650506424], "value": 20294.354233078793, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.58520116664664, 363.7635536901012, 376.4785771706187, 381.2013831024007, 397.9692596175904, 18559.35042273519, 18999.905000474995, 19224.327148549804, 19743.5064123397, 19922.103505342257, 11352.545898164084, 11587.942060289708, 11752.236104653213, 11753.823692644617, 11773.23473974191, 5215.556677682392, 5796.420357964212, 5888.676122813244, 5896.115582662592, 5932.317783454893, 3793.7154713396467, 4035.4955630546183, 4463.754493502856, 5711.428857114297, 5833.795817146401, 3999.4600728901664, 6518.28298887122, 6562.507811914102, 6587.835304117388, 6590.275069742324, 6353.904691429632, 6399.488040956712, 6545.37818907203, 6549.541532092755, 6557.245916719567, 5756.856078598027, 5764.33710123337, 5767.509761670221, 5769.451902069297, 5794.5943783935145, 19410.22359105634, 22306.884655767146, 23749.762502374928, 24420.511589768284, 24592.0, 2032.7459067616549, 2155.902984365707, 2228.743694475124, 2300.240920496249, 2368.230325144335, 7794.9220507795335, 8455.492670439731, 9676.806463870684, 9770.804583908353, 10079.992000799935, 1800.3608718904732, 1806.8283513066237, 1928.9456728584014, 1938.835199008086, 1943.067327682706, 73561.63219183924, 93603.59594606086, 122291.3885430576, 122889.0, 122931.38534307272, 8971.685990990283, 9201.871363736362, 11128.49921753523, 11257.043151332065, 11340.94329528347, 11751.647450576496, 14218.217998010208, 14249.643758905902, 14264.35810388535, 14309.0, 6586.637734924625, 8285.130061343472, 8325.333973282122, 8468.830623387497, 8490.745277641681, 8226.794330141674, 8236.546989915612, 8293.460925039853, 8301.958490207511, 8366.707165249187, 3479.756417050782, 4307.935380969288, 4417.514073451917, 4419.18245124653, 4503.617192538605, 1846.5475958390011, 1885.5191926059042, 1892.1296203746156, 1896.8482521398255, 1900.3443811884877, 818.251300060438, 862.4652715316419, 900.5452246615417, 951.2817822543935, 1000.5147503460815, 19942.70085948712, 20931.581368372546, 20988.68516972216, 21059.578808424052, 21304.893475532524, 881.7442941547008, 910.7222297199418, 911.6444586611227, 938.8497840345512, 1003.9157709673535, 9108.271338293052, 10575.312604680672, 11060.446977651085, 11079.224454288122, 11086.667399978014, 564464.0, 571900.0, 574049.0, 589541.0, 594003.0, 70199.0, 104743.95256047495, 112585.0, 132360.0, 135614.0, 884.2263019857625, 913.365211178238, 931.2549960032082, 948.672707915768, 952.9047095290554, 18766.437006889268, 18889.527761806065, 19050.952197629264, 19153.808461915203, 19314.71027934554, 41911.74264772062, 51193.48806511887, 51870.48129518657, 52428.73785631047, 52469.0, 72568.46883780484, 73132.0, 74446.0, 74745.0, 75127.62436188002, 5961.582689211714, 7741.535507869546, 7772.805679857823, 7865.134835168242, 8062.355011599176, 8697.95465672807, 11899.167058305835, 11981.580644677395, 12337.074719395901, 12385.133040687066, 9934.354266972623, 10329.276950613685, 10750.892491074988, 10781.9460902695, 10853.0, 8605.65577376898, 10323.225758068025, 10326.793464130526, 10711.410872402093, 10831.133509319394, 10649.840252396376, 10672.786544269227, 10976.286541374786, 11001.119910407308, 11046.354702948169, 7695.88456173169, 7789.0, 7882.487638303492, 7940.0471943364855, 7997.400194985516, 8001.239882211181, 8683.609237584198, 9492.477913714813, 10017.69946901594, 10823.783524329627], "value": 8987.39109536799, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1225.5771758743235, 1226.423580916969, 1276.6616846535667, 1777.1913779230451, 1810.0316330763044, 6607.5374723769355, 7821.726239581616, 8122.67509299628, 8459.619317130728, 8486.872696909551, 7881.014873140857, 8238.093809680946, 8262.049864265611, 8263.132371101037, 8351.582420878962, 2273.8294627902933, 2310.4223944013997, 2319.373769082347, 2327.0, 2328.080408238745, 2327.197116994634, 2351.7177938647387, 2370.478494731165, 2377.869217193054, 2378.4053986503372, 2118.69700134417, 2155.5257843274453, 2305.596520608895, 2343.3321503371544, 2344.6365813298967, 2292.3695983604475, 2297.8391512594126, 2308.7806658367595, 2316.247219653611, 2325.767423257678, 2320.025589252518, 2348.894299756515, 2350.929472115822, 2364.8581085134947, 2399.6280576510667, 8175.934024740723, 8219.342452603778, 8328.66685332586, 8335.333173346118, 8335.49987000782, 5000.82497112603, 5157.638965272433, 5161.535461808456, 5166.715830629314, 5173.706573356661, 2835.979047542883, 3152.3537674776844, 3380.0948884110308, 3485.6514348565192, 3505.404081306159, 1007.6976906927893, 1038.1227862456212, 1045.8535804987307, 1069.4706120470416, 1070.7216123807789, 9543.284253680933, 10591.364518128938, 10807.67576972692, 10810.405427701473, 10872.836907446395, 4039.7374170678813, 4388.736675799462, 4534.183846907522, 4593.770311484413, 4615.330777037311, 9097.727068187965, 9395.436273823592, 9501.524923753785, 9513.143817056498, 9527.190188833889, 5164.612654050961, 5194.22086687, 5206.427292997767, 5212.530872221519, 5216.530512253839, 4284.0, 4286.971126929577, 4695.389599352063, 4703.529647035269, 4760.523947605212, 3615.385384484644, 3701.5002974598624, 3787.4697542343542, 3819.560750513671, 3826.4069069294305, 1231.1874163052341, 1263.5261776833688, 1269.2511418263084, 1276.5787290194214, 1278.5588971804711, 741.0662565167927, 820.6430202861774, 865.1391865094146, 876.8202518483696, 884.35883984111, 5338.172583249602, 5419.810306639249, 5570.3315602127195, 5635.098384258499, 5641.2384328116, 415.0992346607868, 424.23849190702765, 424.8343146172995, 425.4235510882771, 431.1441788050717, 2963.555466679972, 3584.8386822592524, 3601.333753255656, 3668.79821609814, 3677.3932301170007, 7707.383409327241, 9188.448693078437, 9595.856062159073, 9800.26498012652, 9944.850827237597, 8418.663253469915, 9811.0, 9836.016398360107, 9856.112949834549, 9878.654247101316, 6740.955151951455, 7283.890741638879, 7714.267144621252, 7806.6096695165015, 7827.882581761278, 4906.803727850846, 5173.430922598436, 5512.4211957745465, 5527.806526771544, 5725.2843394575675, 9035.819283614423, 9236.983931767634, 9307.72076837696, 9362.204212642002, 9372.812543749224, 8944.821103577762, 9214.723558293263, 9414.576344064397, 9508.809823803347, 9540.713778586654, 6566.376194261537, 6768.695408706521, 6860.245373009065, 6916.585004899722, 7156.069710937545, 6659.267480577229, 7760.456768026184, 7899.68401263966, 7901.130875603794, 7969.163237859942, 7002.8949565757575, 7143.357097861218, 7183.066201393785, 7214.170370407471, 7252.601106939169, 6959.373656370951, 6964.930350696428, 6996.895046574407, 7042.401395881203, 7076.893846592408, 6597.538172327891, 6618.503612229199, 6710.731570737311, 6711.261761206166, 6725.798226053226, 4751.6436267279405, 4753.5008824072975, 4777.641676874317, 4779.4742578317055, 4881.243407271879, 4693.202155633482, 4726.385569875894, 4741.668083234141, 4780.306855505863, 4976.178930476575], "value": 4239.95684965372, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.64817866738565, 300.85258223470504, 301.9803712758672, 308.23711314496626, 311.20952779938943, 5269.8155564555245, 5362.35651721793, 5368.62419630626, 5444.809431669893, 5493.489290445124, 4658.529395600282, 4757.548032936874, 4852.514748525155, 5094.1594636884965, 5343.118385466393, 4137.503499580054, 4151.609210914332, 4164.708470407073, 4170.603792639709, 4184.246835569596, 3800.182960663457, 4058.391241313797, 4175.770332631704, 4181.226473102486, 4200.432941552882, 4013.6789056875527, 4043.0081739512843, 4057.9391309130388, 4075.470188875442, 4094.406311084891, 3698.594534077047, 4068.0643452005907, 4105.952981989603, 4113.547509773923, 4126.649234815044, 3545.1846075402545, 3545.3795585772386, 3545.556805399325, 3551.7691350062164, 3554.626764189749, 4352.542982986804, 4535.006895449021, 5025.84922452327, 5215.06574548694, 5250.474952504719, 4050.432939388488, 4615.46922103959, 4642.558956899089, 4650.6048185544205, 4666.160091183586, 5559.583031272671, 5604.775808967636, 5615.578831587647, 5675.262215911946, 5696.6866822324755, 2838.58840468131, 3309.4043072246986, 3510.3154884797473, 3537.832515269955, 3662.08447888028, 5940.495057920082, 5949.583529152961, 5951.613145145551, 5965.821025369246, 6003.669798161056, 3890.90272743178, 3918.0, 3919.941200881989, 3921.254961557295, 3925.1953349563273, 3468.5664291963503, 3776.8489260429274, 3847.5952529668957, 3881.971277611435, 3982.203559288154, 4338.132373525307, 4591.510452056461, 4604.447466304065, 4628.305754136906, 4635.791389387486, 4731.29030645406, 5053.67151135175, 5702.429757024264, 5768.567357448124, 5771.759071799584, 1040.625374865048, 1081.2485322700647, 1131.2872890079198, 1240.864608882862, 1259.540267802264, 834.820513589569, 865.9004214515286, 867.1718508824058, 867.9609417576224, 874.2481465939287, 4252.702310838212, 5046.1169295373165, 5067.138586440314, 5090.083784918676, 5104.540591346797, 5185.455527169593, 5506.174073888948, 5515.751791169327, 5532.640378375393, 5556.0, 4377.737335759864, 4514.480834703985, 4624.930626040612, 4834.347363105936, 5054.7472626368535, 2598.857062861523, 2626.4090579619433, 2645.550256456407, 2920.678725340211, 2923.8596947190613, 4840.515948405131, 5134.460881607452, 5216.713080780593, 5636.464535869168, 5678.290213723285, 4383.408239887574, 5226.0, 5279.788808447696, 5517.310336207974, 5584.52531534816, 4683.578477937002, 4761.47623761386, 5006.64953453262, 5381.354237491448, 5713.171785028834, 4717.669763116549, 5463.262459567907, 5501.504864562208, 5503.0, 5514.476124768141, 4666.556677115668, 5408.432114627907, 5451.291332126798, 5693.9715301424885, 5813.6221145625395, 4520.0, 5795.5073818724195, 6114.5847390279705, 6140.938590614037, 6155.753769849156, 3150.6691797361864, 3347.3974684557015, 3899.2786334528205, 3936.980315098406, 3965.385365268388, 5606.196901549225, 5854.707264636666, 5854.94145058561, 5912.911306330495, 5994.700264986646, 5283.920741188808, 5474.753636086306, 5653.547716182778, 5789.2763404574425, 5870.823875283749, 5261.4738526147075, 5673.943260567342, 5763.827085187451, 5820.272465941757, 5851.829634073288, 5318.813841515528, 5667.554773532723, 5681.0, 5687.74869528703, 5810.215620891126, 4313.97843010783, 4935.481774413634, 5757.913631295618, 5814.854628634318, 5877.147813567094, 4873.805047798049, 5456.809011684572, 5631.339629241026, 5631.436856314336, 5638.689872057076], "value": 4035.557447037217, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [650.830783996161, 662.9502787290954, 718.4755128756008, 723.6345645449047, 774.5701135869593, 13820.170789752608, 14734.484293049745, 14749.852501475008, 14764.187969661665, 14804.92597537012, 13252.867471325284, 13358.66603334917, 13375.464981400757, 13408.59774206773, 13439.0, 651.9739210431577, 676.8646270745846, 796.1958421993799, 810.8135128920337, 824.7154731617583, 809.2797410304834, 824.7319621123129, 827.1728271728272, 828.5235989306173, 828.6768160417442, 875.7504111328273, 880.2034159086037, 884.8849649545582, 888.0, 888.3603805260235, 821.2772759971247, 855.0936007831688, 872.9742552500786, 873.4148120759044, 874.0560194989396, 649.0134994807913, 653.5033374635263, 653.5098675993005, 653.6698967021671, 653.7581094994835, 13834.515791947233, 14084.507042253472, 14266.0, 14370.928145359207, 14417.495387661382, 13746.518871839538, 13870.098443601133, 13922.930385348009, 14117.0, 14127.011109222358, 9420.576074076684, 11132.666020019413, 11159.832602510969, 11173.21787474877, 11325.0, 3669.6330366963357, 3697.556293244828, 3788.3938569828697, 3831.1571454280015, 3836.673882719972, 94118.0, 95484.0, 100390.0, 100616.49691751567, 100753.4962325191, 9445.90554094464, 10057.0, 10803.83794243087, 10901.236913415985, 11245.775084498264, 21227.15091396358, 21455.0, 21671.45821354479, 21694.674579881314, 21753.23863664796, 7598.35413989806, 7679.769606911802, 7682.923170768333, 7687.846243075107, 7799.220077992155, 4158.9376159357635, 4196.328587425997, 4263.5736426357735, 4306.267934451088, 4357.346398040319, 3373.763836531419, 3472.201393679468, 3475.669811367916, 3479.6520347965, 3484.3553942520716, 576.4725276372156, 612.7457055746899, 631.6557476175495, 635.0727937211631, 645.8482256669679, 496.8335607571432, 573.8307199376194, 587.63477005055, 666.3835951744617, 668.304962838652, 6792.029467539099, 7976.960115199387, 8977.50623715689, 9105.726828195166, 9399.57701903416, 491.50112635674816, 500.73210832205325, 520.5055197562331, 592.733270028489, 595.2232336800516, 4265.722728022669, 4512.984578469818, 4513.165064463019, 4539.432570928634, 4625.814967401334, 79565.60217198993, 81832.77250841242, 111910.88089119004, 113260.43369783099, 118975.0, 71334.64332678408, 77009.0, 77513.61243193748, 102456.48771756243, 104858.47570762098, 62701.0, 74681.0, 75038.62480687561, 76723.23276767161, 80641.59679201683, 9324.0, 9517.0, 9552.665656701982, 9628.807423851624, 9630.807383852423, 46389.76805116088, 48019.279710805065, 48682.75658621684, 52524.73737631287, 53023.73488132535, 49872.25191622056, 49946.75026624843, 69677.65161174162, 73679.63160184165, 73824.63087684527, 11596.304221746723, 12042.21725587869, 12060.638180854588, 12080.637580872588, 12486.750264994831, 13652.795208071688, 13875.167489950636, 13965.231912244924, 14019.859801401855, 14243.792212207125, 12482.438290276777, 13194.538191163263, 13393.464261429823, 13401.798973015608, 13421.261830599411, 12418.130730848754, 12940.223586584834, 13073.477060917457, 13244.940404767789, 13289.335533223108, 12368.0, 12502.749945001231, 12664.110262279031, 12692.492300307884, 12727.554535591209, 8938.910610894069, 10906.291091079054, 10936.45317734126, 11098.891210669946, 11152.498137583665, 9311.813763724824, 10358.585656573652, 10459.320144190604, 10571.572837666816, 11131.0], "value": 7129.366057013713, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1623.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 120.0, 118.0, 119.0, 119.0, 119.0, 117.0, 118.0, 99.0, 175.0], "value": 121.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 149.0, 151.0, 153.0, 119.0, 146.0, 145.0, 118.0, 138.0, 116.0], "value": 138.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 214.0, 206.0, 191.0, 245.0, 277.0, 226.0, 201.0, 200.0, 199.0], "value": 215.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 57.0, 60.0, 87.0, 60.0, 58.0, 60.0, 61.0, 74.0, 58.0], "value": 66.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 70.0, 83.0, 70.0, 70.0, 76.0, 117.0, 85.0, 72.0, 82.0], "value": 81.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 92.0, 90.0, 92.0, 80.0, 78.0, 82.0, 125.0, 103.0], "value": 92.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 135.0, 155.0, 152.0, 97.0, 99.0, 103.0, 147.0, 154.0, 140.0], "value": 128.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 120.0, 91.0, 93.0, 93.0, 94.0, 92.0, 92.0, 78.0, 146.0], "value": 100.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 58.0, 58.0, 54.0, 50.0, 82.0, 90.0, 89.0, 63.0], "value": 65.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 82.0, 87.0, 78.0, 72.0, 70.0, 71.0, 74.0, 75.0, 76.0], "value": 75.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 89.0, 90.0, 90.0, 88.0, 91.0, 88.0, 90.0, 92.0], "value": 89.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 151.0, 170.0, 155.0, 168.0, 177.0, 164.0, 194.0, 222.0, 202.0], "value": 175.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 245.0, 182.0, 239.0, 236.0, 177.0, 177.0, 181.0, 174.0, 177.0], "value": 198.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 70.0, 69.0, 68.0, 72.0, 67.0, 75.0, 79.0, 95.0], "value": 73.5, "unit": "score"}]}]} 02:23:58 INFO - Return code: 0 02:23:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:23:58 ERROR - # TBPL SUCCESS # 02:23:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:23:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:23:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:23:58 INFO - 2016-11-24 02:23:58.535 screenresolution[2193:76497] starting screenresolution argv=screenresolution get 02:23:58 INFO - 2016-11-24 02:23:58.538 screenresolution[2193:76497] Display 0: 1600x1200x32@60 02:23:58 INFO - 2016-11-24 02:23:58.544 screenresolution[2194:76500] starting screenresolution argv=screenresolution list 02:23:58 INFO - Available Modes on Display 0 02:23:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:23:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:23:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:23:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:23:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:23:58 INFO - Intel Iris: 02:23:58 INFO - Chipset Model: Intel Iris 02:23:58 INFO - Type: GPU 02:23:58 INFO - Bus: Built-In 02:23:58 INFO - VRAM (Dynamic, Max): 1536 MB 02:23:58 INFO - Vendor: Intel (0x8086) 02:23:58 INFO - Device ID: 0x0a2e 02:23:58 INFO - Revision ID: 0x0009 02:23:58 INFO - Displays: 02:23:58 INFO - Display: 02:23:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:23:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:23:58 INFO - Display Serial Number: 1600x1200 60 02:23:58 INFO - Main Display: Yes 02:23:58 INFO - Mirror: Off 02:23:58 INFO - Online: Yes 02:23:58 INFO - Rotation: Supported 02:23:58 INFO - Return code: 0 02:23:58 INFO - Running post-action listener: _resource_record_post_action 02:23:58 INFO - [mozharness: 2016-11-24 10:23:58.788046Z] Finished run-tests step (success) 02:23:58 INFO - Running post-run listener: _resource_record_post_run 02:23:58 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 951808; Write bytes: 696231936; Read time: 7; Write time: 2124 02:23:58 INFO - TinderboxPrint: CPU usage
54.7% 02:23:58 INFO - TinderboxPrint: I/O read bytes / time
951,808 / 7 02:23:58 INFO - TinderboxPrint: I/O write bytes / time
696,231,936 / 2,124 02:23:58 INFO - TinderboxPrint: CPU idle
2,200.1 (45.3%) 02:23:58 INFO - TinderboxPrint: CPU system
982.2 (20.2%) 02:23:58 INFO - TinderboxPrint: CPU user
1,671.4 (34.4%) 02:23:58 INFO - TinderboxPrint: Swap in / out
383,692,800 / 0 02:23:58 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 378403328; Write bytes: 372794880; Read time: 28726; Write time: 793 02:23:58 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 951808; Write bytes: 309006848; Read time: 7; Write time: 1284 02:23:59 INFO - Running post-run listener: _upload_blobber_files 02:23:59 INFO - Blob upload gear active. 02:23:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:23:59 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 02:23:59 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'] 02:23:59 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 02:23:59 INFO - (blobuploader) - INFO - Open directory for files ... 02:23:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:23:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:23:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:24:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:24:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:24:00 INFO - (blobuploader) - INFO - Done attempting. 02:24:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:24:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:24:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:24:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:24:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:24:01 INFO - (blobuploader) - INFO - Done attempting. 02:24:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:24:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:24:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:24:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:24:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:24:03 INFO - (blobuploader) - INFO - Done attempting. 02:24:03 INFO - (blobuploader) - INFO - Iteration through files over. 02:24:03 INFO - Return code: 0 02:24:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:24:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:24:03 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/0d4058a03f8c81658822f167f5a8abc0227f1b239534bb29a57b70a336f7d5da5a59a9799c0ee8e32adb9b349b72b3896350d8b2964bf21ca117f37cca8e140d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/35dfdb798d6cb59cd8fbfb1f8d08224c623ddd898d3707d57038162b60245683c48a49fb32bc830110cb724e69a7b5cd96f98c4158a1fde17c7e0e566909151b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/5f6096f4c314630e8bb0d393fef07e2b07c0a1a1b0d680046da163ce7d76fa1f85456da991d72fb30e0b36972663afa7d5740aed3dc5c78cfcb5db2d74b3a903"} 02:24:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:24:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:24:03 INFO - Contents: 02:24:03 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/0d4058a03f8c81658822f167f5a8abc0227f1b239534bb29a57b70a336f7d5da5a59a9799c0ee8e32adb9b349b72b3896350d8b2964bf21ca117f37cca8e140d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/35dfdb798d6cb59cd8fbfb1f8d08224c623ddd898d3707d57038162b60245683c48a49fb32bc830110cb724e69a7b5cd96f98c4158a1fde17c7e0e566909151b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/5f6096f4c314630e8bb0d393fef07e2b07c0a1a1b0d680046da163ce7d76fa1f85456da991d72fb30e0b36972663afa7d5740aed3dc5c78cfcb5db2d74b3a903"} 02:24:03 INFO - Running post-run listener: copy_logs_to_upload_dir 02:24:03 INFO - Copying logs to upload dir... 02:24:03 INFO - mkdir: /builds/slave/test/build/upload/logs 02:24:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.510401 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-24 02:24:03.593627) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-24 02:24:03.599429) ========= 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.m5oKL6NAxW/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.FBD7ZWibDY/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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/0d4058a03f8c81658822f167f5a8abc0227f1b239534bb29a57b70a336f7d5da5a59a9799c0ee8e32adb9b349b72b3896350d8b2964bf21ca117f37cca8e140d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/35dfdb798d6cb59cd8fbfb1f8d08224c623ddd898d3707d57038162b60245683c48a49fb32bc830110cb724e69a7b5cd96f98c4158a1fde17c7e0e566909151b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/5f6096f4c314630e8bb0d393fef07e2b07c0a1a1b0d680046da163ce7d76fa1f85456da991d72fb30e0b36972663afa7d5740aed3dc5c78cfcb5db2d74b3a903"} build_url:https://queue.taskcluster.net/v1/task/Jrjs5dx3QpKmneEb0aezOg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011535 build_url: 'https://queue.taskcluster.net/v1/task/Jrjs5dx3QpKmneEb0aezOg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/0d4058a03f8c81658822f167f5a8abc0227f1b239534bb29a57b70a336f7d5da5a59a9799c0ee8e32adb9b349b72b3896350d8b2964bf21ca117f37cca8e140d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/35dfdb798d6cb59cd8fbfb1f8d08224c623ddd898d3707d57038162b60245683c48a49fb32bc830110cb724e69a7b5cd96f98c4158a1fde17c7e0e566909151b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/5f6096f4c314630e8bb0d393fef07e2b07c0a1a1b0d680046da163ce7d76fa1f85456da991d72fb30e0b36972663afa7d5740aed3dc5c78cfcb5db2d74b3a903"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-24 02:24:03.632418) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 02:24:03.632739) ========= 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.m5oKL6NAxW/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.FBD7ZWibDY/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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.005599 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-24 02:24:03.681783) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-24 02:24:03.682104) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-24 02:24:03.682428) ========= ========= Total master_lag: 0.19 =========