builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0052 starttime: 1475207636.8 results: success (0) buildid: 20160929185419 builduid: e424fb6a233f40439c09a2eec8fa7f1f revision: f6a964152400fa1e5340e9909615ac938610f931 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-29 20:53:56.800081) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-29 20:53:56.800523) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-29 20:53:56.800794) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-29 20:53:56.825904) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 20:53:56.826200) ========= 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.6GPdEwv5b6/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.pNJ6w9EFas/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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-09-29 20:53:56-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.63M=0.001s 2016-09-29 20:53:56 (9.63 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.088427 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 20:53:56.937560) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 20:53:56.938012) ========= 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.6GPdEwv5b6/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.pNJ6w9EFas/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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.028996 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 20:53:56.987121) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-29 20:53:56.987473) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f6a964152400fa1e5340e9909615ac938610f931 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev f6a964152400fa1e5340e9909615ac938610f931 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6GPdEwv5b6/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.pNJ6w9EFas/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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-09-29 20:53:57,053 truncating revision to first 12 chars 2016-09-29 20:53:57,053 Setting DEBUG logging. 2016-09-29 20:53:57,053 attempt 1/10 2016-09-29 20:53:57,053 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f6a964152400?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-29 20:53:57,777 unpacking tar archive at: fx-team-f6a964152400/testing/mozharness/ program finished with exit code 0 elapsedTime=0.988296 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-29 20:53:57.997164) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-29 20:53:57.997561) ========= script_repo_revision: f6a964152400fa1e5340e9909615ac938610f931 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-29 20:53:57.997977) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-29 20:53:57.998252) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-29 20:53:58.016452) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-09-29 20:53:58.016840) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6GPdEwv5b6/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.pNJ6w9EFas/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 20:53:58 INFO - MultiFileLogger online at 20160929 20:53:58 in /builds/slave/test 20:53:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 20:53:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:53:58 INFO - {'append_to_log': False, 20:53:58 INFO - 'base_work_dir': '/builds/slave/test', 20:53:58 INFO - 'blob_upload_branch': 'Fx-Team', 20:53:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:53:58 INFO - 'branch': 'Fx-Team', 20:53:58 INFO - 'buildbot_json_path': 'buildprops.json', 20:53:58 INFO - 'config_files': ('talos/mac_config.py',), 20:53:58 INFO - 'default_actions': ('clobber', 20:53:58 INFO - 'read-buildbot-config', 20:53:58 INFO - 'download-and-extract', 20:53:58 INFO - 'populate-webroot', 20:53:58 INFO - 'create-virtualenv', 20:53:58 INFO - 'install', 20:53:58 INFO - 'run-tests'), 20:53:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:53:58 INFO - 'download_minidump_stackwalk': True, 20:53:58 INFO - 'download_symbols': 'ondemand', 20:53:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:53:58 INFO - 'tooltool.py': '/tools/tooltool.py', 20:53:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:53:58 INFO - '/tools/misc-python/virtualenv.py')}, 20:53:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:53:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:53:58 INFO - 'installer_path': 'installer.exe', 20:53:58 INFO - 'log_level': 'info', 20:53:58 INFO - 'log_name': 'talos', 20:53:58 INFO - 'log_to_console': True, 20:53:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:53:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:53:58 INFO - 'opt_config_files': (), 20:53:58 INFO - 'pip_index': False, 20:53:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:53:58 INFO - 'cmd': ('bash', 20:53:58 INFO - '-c', 20:53:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:53:58 INFO - 'enabled': True, 20:53:58 INFO - 'halt_on_failure': False, 20:53:58 INFO - 'name': 'check_screen_resolution'},), 20:53:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:53:58 INFO - 'cmd': ('bash', 20:53:58 INFO - '-c', 20:53:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:53:58 INFO - 'enabled': True, 20:53:58 INFO - 'halt_on_failure': False, 20:53:58 INFO - 'name': 'check_screen_resolution'},), 20:53:58 INFO - 'run_cmd_checks_enabled': True, 20:53:58 INFO - 'sps_profile': False, 20:53:58 INFO - 'sps_profile_interval': 0, 20:53:58 INFO - 'suite': 'dromaeojs-e10s', 20:53:58 INFO - 'system_bits': '32', 20:53:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:53:58 INFO - 'title': 't-yosemite-r7-0052', 20:53:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:53:58 INFO - 'use_talos_json': True, 20:53:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:53:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:53:58 INFO - 'work_dir': 'build'} 20:53:58 INFO - [mozharness: 2016-09-30 03:53:58.216263Z] Running clobber step. 20:53:58 INFO - Running pre-action listener: _resource_record_pre_action 20:53:58 INFO - Running main action method: clobber 20:53:58 INFO - rmtree: /builds/slave/test/build 20:53:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:54:00 INFO - Running post-action listener: _resource_record_post_action 20:54:00 INFO - [mozharness: 2016-09-30 03:54:00.538764Z] Finished clobber step (success) 20:54:00 INFO - [mozharness: 2016-09-30 03:54:00.538880Z] Running read-buildbot-config step. 20:54:00 INFO - Running pre-action listener: _resource_record_pre_action 20:54:00 INFO - Running main action method: read_buildbot_config 20:54:00 INFO - Using buildbot properties: 20:54:00 INFO - { 20:54:00 INFO - "project": "", 20:54:00 INFO - "product": "firefox", 20:54:00 INFO - "script_repo_revision": "production", 20:54:00 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 20:54:00 INFO - "repository": "", 20:54:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 20:54:00 INFO - "buildid": "20160929185419", 20:54:00 INFO - "pgo_build": "False", 20:54:00 INFO - "basedir": "/builds/slave/test", 20:54:00 INFO - "buildnumber": 3, 20:54:00 INFO - "slavename": "t-yosemite-r7-0052", 20:54:00 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 20:54:00 INFO - "platform": "yosemite_r7", 20:54:00 INFO - "scheduler": "tests-fx-team-macosx64-talos-8-3600", 20:54:00 INFO - "branch": "fx-team", 20:54:00 INFO - "repo_path": "integration/fx-team", 20:54:00 INFO - "revision": "f6a964152400fa1e5340e9909615ac938610f931", 20:54:00 INFO - "stage_platform": "macosx64", 20:54:00 INFO - "builduid": "e424fb6a233f40439c09a2eec8fa7f1f", 20:54:00 INFO - "slavebuilddir": "test" 20:54:00 INFO - } 20:54:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 20:54:00 INFO - Running post-action listener: _resource_record_post_action 20:54:00 INFO - [mozharness: 2016-09-30 03:54:00.542243Z] Finished read-buildbot-config step (success) 20:54:00 INFO - [mozharness: 2016-09-30 03:54:00.542401Z] Running download-and-extract step. 20:54:00 INFO - Running pre-action listener: _resource_record_pre_action 20:54:00 INFO - Running main action method: download_and_extract 20:54:00 INFO - mkdir: /builds/slave/test/build/tests 20:54:00 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')]} 20:54:00 INFO - trying https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 20:54:00 INFO - Downloading https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 20:54:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 20:54:01 INFO - Downloaded 1199 bytes. 20:54:01 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 20:54:01 INFO - Using the following test package requirements: 20:54:01 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 20:54:01 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:54:01 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 20:54:01 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:54:01 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 20:54:01 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:54:01 INFO - u'jsshell-mac.zip'], 20:54:01 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:54:01 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 20:54:01 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 20:54:01 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:54:01 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 20:54:01 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:54:01 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 20:54:01 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:54:01 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 20:54:01 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 20:54:01 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 20:54:01 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:54:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 20:54:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:54:01 INFO - Fetch https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 20:54:02 INFO - Http code: 200 20:54:02 INFO - Content-Encoding: None 20:54:02 INFO - Content-Type: application/zip 20:54:02 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 20:54:02 INFO - x-amz-cf-id: I2IjIa0yxir4ai0y9XYJcobNypQdTSzvW8vsqMut9n3ZcvsJEX51IQ== 20:54:02 INFO - x-amz-version-id: 2pt3gLa52ukbgiXAWgHSf17O0HjAMuAG 20:54:02 INFO - x-cache: Hit from cloudfront 20:54:02 INFO - Expected file size: 16473331 20:54:02 INFO - Obtained file size: 16473331 20:54:09 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 20:54:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 20:54:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:54:09 INFO - Fetch https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 20:54:10 INFO - Http code: 200 20:54:10 INFO - Content-Encoding: None 20:54:10 INFO - Content-Type: application/zip 20:54:10 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 20:54:10 INFO - x-amz-cf-id: 3eZ25RvMOd86F4UE0LSOmvH91NMpDu7lH9X8pB7C7MvluZhoP9Y48A== 20:54:10 INFO - x-amz-version-id: 2pt3gLa52ukbgiXAWgHSf17O0HjAMuAG 20:54:10 INFO - x-cache: Hit from cloudfront 20:54:10 INFO - Expected file size: 16473331 20:54:10 INFO - Obtained file size: 16473331 20:54:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 20:54:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 20:54:16 INFO - Fetch https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 20:54:17 INFO - Http code: 200 20:54:17 INFO - Content-Encoding: None 20:54:17 INFO - Content-Type: application/zip 20:54:17 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 20:54:17 INFO - x-amz-cf-id: NCn2QI5a4msWUjAIuZv2Hh4Yhw71pFwxPkv6bVmwx5U78y5CGU9ePQ== 20:54:17 INFO - x-amz-version-id: YNtHCXJ3yTQmNvAo2nWv37sGcwbEdRa5 20:54:17 INFO - x-cache: Hit from cloudfront 20:54:18 INFO - Expected file size: 14414067 20:54:18 INFO - Obtained file size: 14414067 20:54: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')]} 20:54:18 INFO - trying https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 20:54:18 INFO - Downloading https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 20:54:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 20:54:21 INFO - Downloaded 111805084 bytes. 20:54:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 20:54:21 INFO - mkdir: /builds/slave/test/properties 20:54:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:54:21 INFO - Writing to file /builds/slave/test/properties/build_url 20:54:21 INFO - Contents: 20:54:21 INFO - build_url:https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 20:54:21 INFO - Running post-action listener: _resource_record_post_action 20:54:21 INFO - Running post-action listener: set_extra_try_arguments 20:54:21 INFO - [mozharness: 2016-09-30 03:54:21.990292Z] Finished download-and-extract step (success) 20:54:21 INFO - [mozharness: 2016-09-30 03:54:21.990410Z] Running populate-webroot step. 20:54:21 INFO - Running pre-action listener: _resource_record_pre_action 20:54:21 INFO - Running main action method: populate_webroot 20:54:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:54:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:54:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:54:21 INFO - '--tppagecycles', 20:54:21 INFO - '7'], 20:54:21 INFO - 'tests': ['tsvgm']}}, 20:54:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:54:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:54:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:54:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:54:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:54:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:54:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:54:22 INFO - 'talos_options': ['--disable-e10s'], 20:54:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:54:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:54:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:54:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:54:22 INFO - 'talos_options': ['--disable-e10s'], 20:54:22 INFO - 'tests': ['damp', 'tps']}, 20:54:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:54:22 INFO - 'tests': ['damp', 'tps']}, 20:54:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:54:22 INFO - 'tests': ['dromaeo_dom']}, 20:54:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:54:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:54:22 INFO - 'tests': ['basic_compositor_video']}, 20:54:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:54:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:54:22 INFO - 'tests': ['a11yr', 20:54:22 INFO - 'ts_paint', 20:54:22 INFO - 'tpaint', 20:54:22 INFO - 'sessionrestore', 20:54:22 INFO - 'sessionrestore_no_auto_restore', 20:54:22 INFO - 'tabpaint']}, 20:54:22 INFO - 'other-e10s': {'tests': ['a11yr', 20:54:22 INFO - 'ts_paint', 20:54:22 INFO - 'tpaint', 20:54:22 INFO - 'sessionrestore', 20:54:22 INFO - 'sessionrestore_no_auto_restore', 20:54:22 INFO - 'tabpaint']}, 20:54:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:54:22 INFO - 'ts_paint', 20:54:22 INFO - 'tpaint', 20:54:22 INFO - 'sessionrestore', 20:54:22 INFO - 'sessionrestore_no_auto_restore', 20:54:22 INFO - 'tabpaint']}, 20:54:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:54:22 INFO - 'ts_paint', 20:54:22 INFO - 'tpaint', 20:54:22 INFO - 'sessionrestore', 20:54:22 INFO - 'sessionrestore_no_auto_restore', 20:54:22 INFO - 'tabpaint']}, 20:54:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:54:22 INFO - 'tests': ['a11yr', 20:54:22 INFO - 'ts_paint', 20:54:22 INFO - 'tpaint', 20:54:22 INFO - 'sessionrestore', 20:54:22 INFO - 'sessionrestore_no_auto_restore', 20:54:22 INFO - 'tabpaint']}, 20:54:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:54:22 INFO - 'tests': ['a11yr', 20:54:22 INFO - 'ts_paint', 20:54:22 INFO - 'tpaint', 20:54:22 INFO - 'sessionrestore', 20:54:22 INFO - 'sessionrestore_no_auto_restore', 20:54:22 INFO - 'tabpaint']}, 20:54:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:54:22 INFO - 'tests': ['tsvgx', 20:54:22 INFO - 'tsvgr_opacity', 20:54:22 INFO - 'tart', 20:54:22 INFO - 'tscrollx', 20:54:22 INFO - 'cart']}, 20:54:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:54:22 INFO - 'tsvgr_opacity', 20:54:22 INFO - 'tart', 20:54:22 INFO - 'tscrollx', 20:54:22 INFO - 'cart']}, 20:54:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:54:22 INFO - 'talos_options': ['--disable-e10s'], 20:54:22 INFO - 'tests': ['tp5o']}, 20:54:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:54:22 INFO - 'tests': ['tp5o']}, 20:54:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:54:22 INFO - 'talos_options': ['--disable-e10s', 20:54:22 INFO - '--xperf_path', 20:54:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:54:22 INFO - 'tests': ['tp5n']}, 20:54:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:54:22 INFO - 'talos_options': ['--xperf_path', 20:54:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:54:22 INFO - 'tests': ['tp5n']}}, 20:54:22 INFO - 'talos.zip': {'path': '', 20:54:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:54:22 INFO - Running post-action listener: _resource_record_post_action 20:54:22 INFO - [mozharness: 2016-09-30 03:54:22.008903Z] Finished populate-webroot step (success) 20:54:22 INFO - [mozharness: 2016-09-30 03:54:22.009033Z] Running create-virtualenv step. 20:54:22 INFO - Running pre-action listener: _resource_record_pre_action 20:54:22 INFO - Running main action method: create_virtualenv 20:54:22 INFO - Creating virtualenv /builds/slave/test/build/venv 20:54:22 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 20:54:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:54:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:54:22 INFO - Using real prefix '/tools/python27' 20:54:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:54:22 INFO - Installing distribute.............................................................................................................................................................................................done. 20:54:25 INFO - Installing pip.................done. 20:54:25 INFO - Return code: 0 20:54:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:54:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:54:26 INFO - Reading from file tmpfile_stdout 20:54:26 INFO - Output received: 20:54:26 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 20:54:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:54:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:54:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:54:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:26 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 0x107e04490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d7a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6cb5a6200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c18d30>, '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 0x107a3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c10b20>, '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.6GPdEwv5b6/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.pNJ6w9EFas/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 20:54:26 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 20:54:26 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 20:54:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6GPdEwv5b6/Render', 20:54:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:54:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:54:26 INFO - 'HOME': '/Users/cltbld', 20:54:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:54:26 INFO - 'LOGNAME': 'cltbld', 20:54:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:54:26 INFO - 'MOZ_NO_REMOTE': '1', 20:54:26 INFO - 'NO_EM_RESTART': '1', 20:54:26 INFO - 'PAGER': '/bin/cat', 20:54:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:54:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:54:26 INFO - 'PWD': '/builds/slave/test', 20:54:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:54:26 INFO - 'SHELL': '/bin/bash', 20:54:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pNJ6w9EFas/Listeners', 20:54:26 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 20:54:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:54:26 INFO - 'USER': 'cltbld', 20:54:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:54:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:54:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:54:26 INFO - 'XPC_FLAGS': '0x0', 20:54:26 INFO - 'XPC_SERVICE_NAME': '0', 20:54:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:54:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:54:26 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 20:54:26 INFO - Cleaning up... 20:54:26 INFO - Return code: 0 20:54:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:54:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:54:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:54:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:26 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 0x107e04490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d7a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6cb5a6200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c18d30>, '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 0x107a3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c10b20>, '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.6GPdEwv5b6/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.pNJ6w9EFas/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 20:54:26 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 20:54:26 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 20:54:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6GPdEwv5b6/Render', 20:54:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:54:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:54:26 INFO - 'HOME': '/Users/cltbld', 20:54:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:54:26 INFO - 'LOGNAME': 'cltbld', 20:54:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:54:26 INFO - 'MOZ_NO_REMOTE': '1', 20:54:26 INFO - 'NO_EM_RESTART': '1', 20:54:26 INFO - 'PAGER': '/bin/cat', 20:54:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:54:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:54:26 INFO - 'PWD': '/builds/slave/test', 20:54:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:54:26 INFO - 'SHELL': '/bin/bash', 20:54:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pNJ6w9EFas/Listeners', 20:54:26 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 20:54:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:54:26 INFO - 'USER': 'cltbld', 20:54:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:54:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:54:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:54:26 INFO - 'XPC_FLAGS': '0x0', 20:54:26 INFO - 'XPC_SERVICE_NAME': '0', 20:54:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:54:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:54:26 INFO - Downloading/unpacking psutil>=3.1.1 20:54:26 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 20:54:26 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 20:54:26 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 20:54:26 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 20:54:26 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 20:54:26 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 20:54:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:54:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:54:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:54:30 INFO - Installing collected packages: psutil 20:54:30 INFO - Running setup.py install for psutil 20:54:30 INFO - building 'psutil._psutil_osx' extension 20:54:30 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 20:54:30 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 20:54:30 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 20:54:30 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 20:54:31 INFO - building 'psutil._psutil_posix' extension 20:54:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 20:54:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:54:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:54:31 INFO - ^ 20:54:31 INFO - 1 warning generated. 20:54:31 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 20:54:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:54:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:54:31 INFO - Successfully installed psutil 20:54:31 INFO - Cleaning up... 20:54:31 INFO - Return code: 0 20:54:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:54:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:54:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:54:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e04490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d7a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6cb5a6200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c18d30>, '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 0x107a3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c10b20>, '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.6GPdEwv5b6/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.pNJ6w9EFas/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 20:54:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:54:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:54:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6GPdEwv5b6/Render', 20:54:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:54:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:54:31 INFO - 'HOME': '/Users/cltbld', 20:54:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:54:31 INFO - 'LOGNAME': 'cltbld', 20:54:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:54:31 INFO - 'MOZ_NO_REMOTE': '1', 20:54:31 INFO - 'NO_EM_RESTART': '1', 20:54:31 INFO - 'PAGER': '/bin/cat', 20:54:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:54:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:54:31 INFO - 'PWD': '/builds/slave/test', 20:54:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:54:31 INFO - 'SHELL': '/bin/bash', 20:54:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pNJ6w9EFas/Listeners', 20:54:31 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 20:54:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:54:31 INFO - 'USER': 'cltbld', 20:54:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:54:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:54:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:54:31 INFO - 'XPC_FLAGS': '0x0', 20:54:31 INFO - 'XPC_SERVICE_NAME': '0', 20:54:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:54:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:54:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:54: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 20:54: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 20:54: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 20:54: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 20:54: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 20:54: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 20:54:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:54:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:54:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:54:34 INFO - Installing collected packages: mozsystemmonitor 20:54:34 INFO - Running setup.py install for mozsystemmonitor 20:54:34 INFO - Successfully installed mozsystemmonitor 20:54:34 INFO - Cleaning up... 20:54:34 INFO - Return code: 0 20:54:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:54:34 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')]} 20:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:54:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:34 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')]} 20:54:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:54:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:34 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 0x107e04490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d7a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6cb5a6200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c18d30>, '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 0x107a3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c10b20>, '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.6GPdEwv5b6/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.pNJ6w9EFas/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 20:54:34 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 20:54:34 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 20:54:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6GPdEwv5b6/Render', 20:54:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:54:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:54:34 INFO - 'HOME': '/Users/cltbld', 20:54:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:54:34 INFO - 'LOGNAME': 'cltbld', 20:54:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:54:34 INFO - 'MOZ_NO_REMOTE': '1', 20:54:34 INFO - 'NO_EM_RESTART': '1', 20:54:34 INFO - 'PAGER': '/bin/cat', 20:54:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:54:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:54:34 INFO - 'PWD': '/builds/slave/test', 20:54:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:54:34 INFO - 'SHELL': '/bin/bash', 20:54:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pNJ6w9EFas/Listeners', 20:54:34 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 20:54:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:54:34 INFO - 'USER': 'cltbld', 20:54:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:54:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:54:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:54:34 INFO - 'XPC_FLAGS': '0x0', 20:54:34 INFO - 'XPC_SERVICE_NAME': '0', 20:54:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:54:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:54:34 INFO - Downloading/unpacking jsonschema==2.5.1 20:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:54:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:54:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:54:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:54:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:54:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:54:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:54:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:54:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:54:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:54:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:54:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:54:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:54:37 INFO - Downloading functools32-3.2.3-2.tar.gz 20:54:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:54:37 INFO - warning: no files found matching '*.txt' 20:54:37 INFO - no previously-included directories found matching 'build' 20:54:37 INFO - no previously-included directories found matching 'dist' 20:54:37 INFO - no previously-included directories found matching '.git*' 20:54:37 INFO - Installing collected packages: jsonschema, functools32 20:54:37 INFO - Running setup.py install for functools32 20:54:37 INFO - warning: no files found matching '*.txt' 20:54:37 INFO - no previously-included directories found matching 'build' 20:54:37 INFO - no previously-included directories found matching 'dist' 20:54:37 INFO - no previously-included directories found matching '.git*' 20:54:37 INFO - Successfully installed jsonschema functools32 20:54:37 INFO - Cleaning up... 20:54:37 INFO - Return code: 0 20:54:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:54:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:54:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:54:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e04490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d7a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6cb5a6200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c18d30>, '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 0x107a3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c10b20>, '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.6GPdEwv5b6/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.pNJ6w9EFas/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 20:54:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:54:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:54:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6GPdEwv5b6/Render', 20:54:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:54:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:54:37 INFO - 'HOME': '/Users/cltbld', 20:54:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:54:37 INFO - 'LOGNAME': 'cltbld', 20:54:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:54:37 INFO - 'MOZ_NO_REMOTE': '1', 20:54:37 INFO - 'NO_EM_RESTART': '1', 20:54:37 INFO - 'PAGER': '/bin/cat', 20:54:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:54:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:54:37 INFO - 'PWD': '/builds/slave/test', 20:54:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:54:37 INFO - 'SHELL': '/bin/bash', 20:54:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pNJ6w9EFas/Listeners', 20:54:37 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 20:54:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:54:37 INFO - 'USER': 'cltbld', 20:54:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:54:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:54:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:54:37 INFO - 'XPC_FLAGS': '0x0', 20:54:37 INFO - 'XPC_SERVICE_NAME': '0', 20:54:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:54:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:54:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:54:38 INFO - Cleaning up... 20:54:38 INFO - Return code: 0 20:54:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:54: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')]} 20:54:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:54:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54: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')]} 20:54:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:54:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e04490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d7a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6cb5a6200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c18d30>, '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 0x107a3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c10b20>, '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.6GPdEwv5b6/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.pNJ6w9EFas/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 20:54:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:54:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:54:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6GPdEwv5b6/Render', 20:54:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:54:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:54:38 INFO - 'HOME': '/Users/cltbld', 20:54:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:54:38 INFO - 'LOGNAME': 'cltbld', 20:54:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:54:38 INFO - 'MOZ_NO_REMOTE': '1', 20:54:38 INFO - 'NO_EM_RESTART': '1', 20:54:38 INFO - 'PAGER': '/bin/cat', 20:54:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:54:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:54:38 INFO - 'PWD': '/builds/slave/test', 20:54:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:54:38 INFO - 'SHELL': '/bin/bash', 20:54:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pNJ6w9EFas/Listeners', 20:54:38 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 20:54:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:54:38 INFO - 'USER': 'cltbld', 20:54:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:54:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:54:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:54:38 INFO - 'XPC_FLAGS': '0x0', 20:54:38 INFO - 'XPC_SERVICE_NAME': '0', 20:54:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:54:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:54:38 INFO - Downloading/unpacking blobuploader==1.2.4 20:54:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:54:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:54:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:54:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:54:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:54:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:54:40 INFO - Downloading blobuploader-1.2.4.tar.gz 20:54:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:54:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:54:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:54:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:54:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:54:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:54:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:54:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:54:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:54:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:54:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:54:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:54:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:54:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:54:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:54:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:54:42 INFO - Downloading docopt-0.6.1.tar.gz 20:54:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:54:42 INFO - Installing collected packages: blobuploader, requests, docopt 20:54:42 INFO - Running setup.py install for blobuploader 20:54:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:54:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:54:42 INFO - Running setup.py install for requests 20:54:42 INFO - Running setup.py install for docopt 20:54:43 INFO - Successfully installed blobuploader requests docopt 20:54:43 INFO - Cleaning up... 20:54:43 INFO - Return code: 0 20:54:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:54: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')]} 20:54:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:54:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54: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')]} 20:54:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:54:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e04490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d7a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6cb5a6200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c18d30>, '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 0x107a3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c10b20>, '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.6GPdEwv5b6/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.pNJ6w9EFas/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 20:54:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:54:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:54:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6GPdEwv5b6/Render', 20:54:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:54:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:54:43 INFO - 'HOME': '/Users/cltbld', 20:54:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:54:43 INFO - 'LOGNAME': 'cltbld', 20:54:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:54:43 INFO - 'MOZ_NO_REMOTE': '1', 20:54:43 INFO - 'NO_EM_RESTART': '1', 20:54:43 INFO - 'PAGER': '/bin/cat', 20:54:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:54:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:54:43 INFO - 'PWD': '/builds/slave/test', 20:54:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:54:43 INFO - 'SHELL': '/bin/bash', 20:54:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pNJ6w9EFas/Listeners', 20:54:43 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 20:54:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:54:43 INFO - 'USER': 'cltbld', 20:54:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:54:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:54:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:54:43 INFO - 'XPC_FLAGS': '0x0', 20:54:43 INFO - 'XPC_SERVICE_NAME': '0', 20:54:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:54:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:54:43 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-9_1PYl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:54:43 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-i220oB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:54:43 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-hsfyBa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:54:43 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-yJZgim-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:54:43 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-Y_OMhR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:54:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:54:43 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-1VWKdu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:54:44 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-_o5QI_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:54:44 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-Ytrkg5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:54:44 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-OHWZ0V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:54:44 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-v55ZXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:54:44 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-o4L2cU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:54:44 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-9bJeRN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:54:44 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-IArLxF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:54:44 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-ThwcKl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:54:44 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-IShWmr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:54:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:54:44 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-qqT1Tu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:54:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:54:45 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-dzHomC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:54:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:54:45 INFO - Running setup.py install for manifestparser 20:54:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:54:45 INFO - Running setup.py install for mozcrash 20:54:45 INFO - Running setup.py install for mozdebug 20:54:45 INFO - Running setup.py install for mozdevice 20:54:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:54:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:54:45 INFO - Running setup.py install for mozfile 20:54:45 INFO - Running setup.py install for mozhttpd 20:54:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:54:46 INFO - Running setup.py install for mozinfo 20:54:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:54:46 INFO - Running setup.py install for mozInstall 20:54:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:54:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:54:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:54:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:54:46 INFO - Running setup.py install for mozleak 20:54:46 INFO - Running setup.py install for mozlog 20:54:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:54:46 INFO - Running setup.py install for moznetwork 20:54:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:54:46 INFO - Running setup.py install for mozprocess 20:54:46 INFO - Running setup.py install for mozprofile 20:54:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:54:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:54:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:54:47 INFO - Running setup.py install for mozrunner 20:54:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:54:47 INFO - Running setup.py install for mozscreenshot 20:54:47 INFO - Running setup.py install for moztest 20:54:47 INFO - Running setup.py install for mozversion 20:54:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:54:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:54:47 INFO - Cleaning up... 20:54:47 INFO - Return code: 0 20:54:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:54:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:54:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:54:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e04490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d7a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6cb5a6200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c18d30>, '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 0x107a3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c10b20>, '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.6GPdEwv5b6/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.pNJ6w9EFas/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 20:54:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:54:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:54:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6GPdEwv5b6/Render', 20:54:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:54:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:54:47 INFO - 'HOME': '/Users/cltbld', 20:54:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:54:47 INFO - 'LOGNAME': 'cltbld', 20:54:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:54:47 INFO - 'MOZ_NO_REMOTE': '1', 20:54:47 INFO - 'NO_EM_RESTART': '1', 20:54:47 INFO - 'PAGER': '/bin/cat', 20:54:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:54:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:54:47 INFO - 'PWD': '/builds/slave/test', 20:54:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:54:47 INFO - 'SHELL': '/bin/bash', 20:54:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pNJ6w9EFas/Listeners', 20:54:47 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 20:54:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:54:47 INFO - 'USER': 'cltbld', 20:54:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:54:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:54:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:54:47 INFO - 'XPC_FLAGS': '0x0', 20:54:47 INFO - 'XPC_SERVICE_NAME': '0', 20:54:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:54:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:54:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:54:48 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-CjpkAU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 20:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:54:48 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-VeblE7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 20:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:54:48 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-dCGlj1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:54:48 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-TufWsN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 20:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:54:48 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-Mwf38g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 20:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:54:48 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-B5gDwu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 20:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:54:48 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-8oaB4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 20:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:54:48 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-AGT_DL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 20:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:54:48 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-p87a93-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:54:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 20:54:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:54:48 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-0bGCQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:54:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:54:49 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-S7aUyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:54:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 20:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:54:49 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-Z3d5Eq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:54:49 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)) 20:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:54:49 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-mU9J1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:54:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 20:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:54:49 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-ca1H_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:54:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 20:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:54:49 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-Juj3eV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:54:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 20:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:54:49 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-DGEPrt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:54:49 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)) 20:54:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:54:49 INFO - Running setup.py (path:/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/pip-DEBxc6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:54:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 20:54:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:54:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:54:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:54:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:54:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:54:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:54:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:54:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:54:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:54:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:54:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:54:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:54:52 INFO - Downloading blessings-1.6.tar.gz 20:54:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:54:52 INFO - Installing collected packages: blessings 20:54:52 INFO - Running setup.py install for blessings 20:54:52 INFO - Successfully installed blessings 20:54:52 INFO - Cleaning up... 20:54:52 INFO - Return code: 0 20:54:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:54:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:54:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:54:52 INFO - Reading from file tmpfile_stdout 20:54:52 INFO - Current package versions: 20:54:52 INFO - blessings == 1.6 20:54:52 INFO - blobuploader == 1.2.4 20:54:52 INFO - docopt == 0.6.1 20:54:52 INFO - functools32 == 3.2.3-2 20:54:52 INFO - jsonschema == 2.5.1 20:54:52 INFO - manifestparser == 1.1 20:54:52 INFO - mozInstall == 1.12 20:54:52 INFO - mozcrash == 0.17 20:54:52 INFO - mozdebug == 0.1 20:54:52 INFO - mozdevice == 0.48 20:54:52 INFO - mozfile == 1.2 20:54:52 INFO - mozhttpd == 0.7 20:54:52 INFO - mozinfo == 0.9 20:54:52 INFO - mozleak == 0.1 20:54:52 INFO - mozlog == 3.4 20:54:52 INFO - moznetwork == 0.27 20:54:52 INFO - mozprocess == 0.23 20:54:52 INFO - mozprofile == 0.28 20:54:52 INFO - mozrunner == 6.12 20:54:52 INFO - mozscreenshot == 0.1 20:54:52 INFO - mozsystemmonitor == 0.3 20:54:52 INFO - moztest == 0.7 20:54:52 INFO - mozversion == 1.4 20:54:52 INFO - psutil == 3.1.1 20:54:52 INFO - requests == 1.2.3 20:54:52 INFO - wsgiref == 0.1.2 20:54:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:54:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:54:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:54:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e04490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d7a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6cb5a6200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c18d30>, '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 0x107a3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c10b20>, '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.6GPdEwv5b6/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.pNJ6w9EFas/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 20:54:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 20:54:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:54:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6GPdEwv5b6/Render', 20:54:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:54:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:54:53 INFO - 'HOME': '/Users/cltbld', 20:54:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:54:53 INFO - 'LOGNAME': 'cltbld', 20:54:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:54:53 INFO - 'MOZ_NO_REMOTE': '1', 20:54:53 INFO - 'NO_EM_RESTART': '1', 20:54:53 INFO - 'PAGER': '/bin/cat', 20:54:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:54:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:54:53 INFO - 'PWD': '/builds/slave/test', 20:54:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:54:53 INFO - 'SHELL': '/bin/bash', 20:54:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pNJ6w9EFas/Listeners', 20:54:53 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 20:54:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:54:53 INFO - 'USER': 'cltbld', 20:54:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:54:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:54:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:54:53 INFO - 'XPC_FLAGS': '0x0', 20:54:53 INFO - 'XPC_SERVICE_NAME': '0', 20:54:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:54:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 20:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 20:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 20:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 20:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 20:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 20:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 20:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:54:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:54:53 INFO - Cleaning up... 20:54:53 INFO - Return code: 0 20:54:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:54:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:54:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:54:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:54:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:54:54 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 0x107e04490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d7a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6cb5a6200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c18d30>, '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 0x107a3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c10b20>, '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.6GPdEwv5b6/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.pNJ6w9EFas/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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 20:54:54 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 20:54:54 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 20:54:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6GPdEwv5b6/Render', 20:54:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:54:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:54:54 INFO - 'HOME': '/Users/cltbld', 20:54:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:54:54 INFO - 'LOGNAME': 'cltbld', 20:54:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:54:54 INFO - 'MOZ_NO_REMOTE': '1', 20:54:54 INFO - 'NO_EM_RESTART': '1', 20:54:54 INFO - 'PAGER': '/bin/cat', 20:54:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:54:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:54:54 INFO - 'PWD': '/builds/slave/test', 20:54:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:54:54 INFO - 'SHELL': '/bin/bash', 20:54:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pNJ6w9EFas/Listeners', 20:54:54 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 20:54:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:54:54 INFO - 'USER': 'cltbld', 20:54:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:54:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:54:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:54:54 INFO - 'XPC_FLAGS': '0x0', 20:54:54 INFO - 'XPC_SERVICE_NAME': '0', 20:54:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:54:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:54:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:54:54 INFO - Cleaning up... 20:54:54 INFO - Return code: 0 20:54:54 INFO - Running post-action listener: _resource_record_post_action 20:54:54 INFO - Running post-action listener: _start_resource_monitoring 20:54:54 INFO - Starting resource monitoring. 20:54:54 INFO - [mozharness: 2016-09-30 03:54:54.684687Z] Finished create-virtualenv step (success) 20:54:54 INFO - [mozharness: 2016-09-30 03:54:54.685653Z] Running install step. 20:54:54 INFO - Running pre-action listener: _resource_record_pre_action 20:54:54 INFO - Running main action method: install 20:54:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:54:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:54:54 INFO - Reading from file tmpfile_stdout 20:54:54 INFO - Detecting whether we're running mozinstall >=1.0... 20:54:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:54:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:54:55 INFO - Reading from file tmpfile_stdout 20:54:55 INFO - Output received: 20:54:55 INFO - Usage: mozinstall [options] installer 20:54:55 INFO - Options: 20:54:55 INFO - -h, --help show this help message and exit 20:54:55 INFO - -d DEST, --destination=DEST 20:54:55 INFO - Directory to install application into. [default: 20:54:55 INFO - "/builds/slave/test"] 20:54:55 INFO - --app=APP Application being installed. [default: firefox] 20:54:55 INFO - mkdir: /builds/slave/test/build/application 20:54:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:54:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 20:55:29 INFO - Reading from file tmpfile_stdout 20:55:29 INFO - Output received: 20:55:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:55:29 INFO - Running post-action listener: _resource_record_post_action 20:55:29 INFO - [mozharness: 2016-09-30 03:55:29.194151Z] Finished install step (success) 20:55:29 INFO - [mozharness: 2016-09-30 03:55:29.194305Z] Running run-tests step. 20:55:29 INFO - Running pre-action listener: _resource_record_pre_action 20:55:29 INFO - Running main action method: run_tests 20:55:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:55:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:55:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:55:29 INFO - 2016-09-29 20:55:29.206 screenresolution[1647:7461] starting screenresolution argv=screenresolution get 20:55:29 INFO - 2016-09-29 20:55:29.209 screenresolution[1647:7461] Display 0: 1600x1200x32@60 20:55:29 INFO - 2016-09-29 20:55:29.214 screenresolution[1648:7463] starting screenresolution argv=screenresolution list 20:55:29 INFO - Available Modes on Display 0 20:55:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:55:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:55:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:55:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:55:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:55:29 INFO - Intel Iris: 20:55:29 INFO - Chipset Model: Intel Iris 20:55:29 INFO - Type: GPU 20:55:29 INFO - Bus: Built-In 20:55:29 INFO - VRAM (Dynamic, Max): 1536 MB 20:55:29 INFO - Vendor: Intel (0x8086) 20:55:29 INFO - Device ID: 0x0a2e 20:55:29 INFO - Revision ID: 0x0009 20:55:29 INFO - Displays: 20:55:29 INFO - Display: 20:55:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:55:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:55:29 INFO - Display Serial Number: 1600x1200 60 20:55:29 INFO - Main Display: Yes 20:55:29 INFO - Mirror: Off 20:55:29 INFO - Online: Yes 20:55:29 INFO - Rotation: Supported 20:55:29 INFO - Return code: 0 20:55:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:55:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:55:29 INFO - Python 2.7.3 20:55:29 INFO - Return code: 0 20:55:29 INFO - grabbing minidump binary from tooltool 20:55:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:55:29 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 0x107c18d30>, '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 0x107a3edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107c10b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:55:29 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 20:55:29 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 20:55:29 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 20:55:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:55:29 INFO - Return code: 0 20:55:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:55:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:55:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:55:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:55:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:55:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0052', '--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 20:55:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0052 --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 20:55:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6GPdEwv5b6/Render', 20:55:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:55:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:55:29 INFO - 'HOME': '/Users/cltbld', 20:55:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:55:29 INFO - 'LOGNAME': 'cltbld', 20:55:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:55:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:55:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:55:29 INFO - 'MOZ_NO_REMOTE': '1', 20:55:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:55:29 INFO - 'NO_EM_RESTART': '1', 20:55:29 INFO - 'PAGER': '/bin/cat', 20:55:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:55:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:55:29 INFO - 'PWD': '/builds/slave/test', 20:55:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:55:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:55:29 INFO - 'SHELL': '/bin/bash', 20:55:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pNJ6w9EFas/Listeners', 20:55:29 INFO - 'TMPDIR': '/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/', 20:55:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:55:29 INFO - 'USER': 'cltbld', 20:55:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:55:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:55:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:55:29 INFO - 'XPC_FLAGS': '0x0', 20:55:29 INFO - 'XPC_SERVICE_NAME': '0', 20:55:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:55:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0052', '--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 20:55:29 INFO - mozversion application_buildid: 20160929185419 20:55:29 INFO - mozversion application_changeset: f6a964152400fa1e5340e9909615ac938610f931 20:55:29 INFO - mozversion application_display_name: Nightly 20:55:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:55:29 INFO - mozversion application_name: Firefox 20:55:29 INFO - mozversion application_remotingname: firefox 20:55:29 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 20:55:29 INFO - mozversion application_vendor: Mozilla 20:55:29 INFO - mozversion application_version: 52.0a1 20:55:29 INFO - mozversion platform_buildid: 20160929185419 20:55:29 INFO - mozversion platform_changeset: f6a964152400fa1e5340e9909615ac938610f931 20:55:29 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 20:55:29 INFO - mozversion platform_version: 52.0a1 20:55:29 INFO - using testdate: 1475207729 20:55:29 INFO - actual date: 1475207729 20:55:29 INFO - starting webserver on 'localhost:49273' 20:55:29 INFO - SUITE-START | Running 2 tests 20:55:29 INFO - TEST-START | dromaeo_css 20:55:29 INFO - operating with platform_type : mac_ 20:55:29 INFO - Initialising browser for dromaeo_css test... 20:55:29 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmpdu8f9C/profile http://localhost:49273/getInfo.html) 20:55:32 INFO - PROCESS | 1658 | 2016-09-29 20:55:32.587 plugin-container[1660:7637] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 20:55:32 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:55:32 INFO - PROCESS | 1658 | 2016-09-29 20:55:32.591 plugin-container[1660:7637] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 20:55:32 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:55:32 INFO - PROCESS | 1658 | 2016-09-29 20:55:32.591 plugin-container[1660:7637] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:55:32 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 20:55:32 INFO - PROCESS | 1658 | colorDepth:24 20:55:32 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 20:55:32 INFO - PROCESS | 1658 | __metrics 20:55:32 ERROR - PROCESS | 1658 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3076: TypeError: this.tabs is undefined 20:55:33 INFO - TEST-INFO | 1658: exit 0 20:55:33 INFO - Browser initialized. 20:55:33 INFO - Running cycle 1/1 for dromaeo_css test... 20:55:33 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmpdu8f9C/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:55:35 INFO - PROCESS | 1661 | 2016-09-29 20:55:35.919 plugin-container[1662:7754] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x851b, name = 'com.apple.tsm.portname' 20:55:35 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:55:35 INFO - PROCESS | 1661 | 2016-09-29 20:55:35.921 plugin-container[1662:7754] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e03, name = 'com.apple.CFPasteboardClient' 20:55:35 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:55:35 INFO - PROCESS | 1661 | 2016-09-29 20:55:35.921 plugin-container[1662:7754] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:55:45 INFO - PROCESS | 1661 | RSS: Main: 240050176 20:55:45 INFO - PROCESS | 1661 | 20:56:35 INFO - PROCESS | 1661 | 1475207795272 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] 20:58:46 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 20:58:48 INFO - PROCESS | 1661 | RSS: Main: 256073728 20:58:48 INFO - PROCESS | 1661 | 21:01:50 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 21:01:51 INFO - PROCESS | 1661 | RSS: Main: 265863168 21:01:51 INFO - PROCESS | 1661 | 21:04:53 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 21:04:54 INFO - PROCESS | 1661 | RSS: Main: 270454784 21:04:54 INFO - PROCESS | 1661 | 21:07:56 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 21:07:56 INFO - PROCESS | 1661 | RSS: Main: 270057472 21:07:56 INFO - PROCESS | 1661 | 21:10:59 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 21:11:00 INFO - PROCESS | 1661 | RSS: Main: 275378176 21:11:00 INFO - PROCESS | 1661 | 21:14:03 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 21:14:03 INFO - PROCESS | 1661 | RSS: Main: 278556672 21:14:03 INFO - PROCESS | 1661 | 21:14:03 INFO - PROCESS | 1661 | __start_tp_report 21:14:03 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 21:14:03 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 21:14:03 INFO - PROCESS | 1661 | |i|pagename|runs| 21:14:03 INFO - PROCESS | 1661 | |0;dojo.html;3393.9660603393945;3409.3522230776152;3440.2947395783867;3451.9137021574475;3452.378571857066;4979.228219625955;5564.610477266588;5650.0959846424585;5734.340550836655;5780.971095144519;5703.372629010816;5721.713914304289;5755.856103597413;5829.8251052468495;5845.240118784552;5219.112750832368;5219.739013049351;5234.476552344764;5353.491418315273;5356.330458692663;5334.546563542103;5348.197770334462;5385.111456609655;5397.298351214356;5473.67157970521;4491.169133710274;5198.194279886624;5207.739613019372;5280.419153893069;5351.625386222966;5268.130758424855;5274.472552744694;5304.973475132639;5338.519533241988;5348.411674715778;3990.3415936370466;5243.449437809013;5256.553192978601;5268.683878967274;5320;5647.774089036434;5652.943470565283;5703.458171473745;5712.11462223356;5852.209951656557;6143.911070236141;6184.288806787232;6185.226846644169;6208;6224.906626400608;6273.843153921142;6282.937170628281;6293.653849038301;6308.684565771693;6328.905066424008;4830.468648448667;5891.646501209855;5950.553708471882;5954.255718035246;5965.642061476326;6339.049142628643;6360.9045864312075;6374.904376434357;6379.457746091542;6387.489000880011;6224.537233750065;6243.968780156161;6250.4374606285655;6262.3111457739615;6263.091851681572;6140.995089002448;6192.535559833031;6194.194754681863;6199.876002480015;6323.588966717149;5428.728563571805;6226;6241.906371404341;6266.060090986297;6270.717817698214;6228.128062071313;6258.968705156445;6271.090691849657;6286.339934306832;6287.591306565058;4892.996935628188;4954.603631709455;4988.052270068675;4993.650444468925;5034.219695947061;4441.555844415532;4470.173017991617;4500.369948207253;4512.616427603625;4530.456345238593;5513.476219759116;5844.795432159854;5877.23595932526;5946.405359464019;5954.702264886739;6208.534359923024;6230.096635987757;6240.31356550779;6291.5281353898645;6306.400891915261;5106.259592359087;5752.28096487939;5840.123981402823;5873.853153671193;6045.093236014545;5449.673019618756;5496.80761173357;5497.532709719639;5504.366997795225;5516.531094856982;6370.044493325945;6427.453666438218;6442.871142577029;6446.774362897276;6451.064595633607;6158.291796443466;6190.350013248544;6210.223722034746;6229.408206220402;6249.281332646622;6418.807435776934;6439.388258115471;6443.194600674916;6452.064450654722;6471.546991710535;5060.898782024413;6111.895622426936;6139.754409823736;6142.468080853438;6200.968995154995;6126.24286675468;6212.9068063978175;6259.06114082873;6261.655608941552;6268.4671802897265;4944.431390390151;6165.383461653799;6203.937960620517;6204.193454850841;6211.565190436626;5037.899242015066;5562.499375056264;5660.377358490645;5827.980103481958;5920.73356698941;4330.631896288851;4778.904421911473;5795.884082318415;5854.795082172103;5940.702964851826;5050.343455350928;6146.661933593696;6153.800008998202;6167.352427994996;6211.844703882439;4509.2559727644575;5951.434613711691;5993.460588547052;6002.789902353396;6033.607815491979 21:14:03 INFO - PROCESS | 1661 | |1;ext.html;464.92328765753655;492.9088118698043;499.45060433523105;523.3928642774382;533.2987121934656;37242.068948276305;40048.599514004854;40207.58235549982;40288.1942361153;40326;33653.66346336536;40708.592914070854;41228;41378.58621413785;41471;9584.233261339075;9774.853377199348;9784.36401633895;9791.11879930806;9800.774903137108;9506.952465237664;9591.520423978807;9609.183219426322;9625.133737963582;9650.951745241264;9625.566849491755;9652.75868103296;9654.806903861918;9661.56522956465;9672.22622190223;9587.568559414844;9594.856077158849;9622.230221582291;9654.71035868925;9674.274429417752;9622.807543849083;9675.225981921429;9690.75773105671;9700.854487182627;9713.514324283757;38756;39245.41131882995;39383.606163938566;39485.21029579421;39632.00919976995;3226.5805445291962;3363.428217203057;3462.8614855405754;3508;3529.5588051493564;29139.192033314477;29913.401731965456;31088.8445557773;31162;31193.908213212657;3114.9532757008665;3261.6412194658806;3291.1607540077125;3360.7983520988573;3364.814935178564;373026.1348693239;373967;445824;446411.53588464024;452245;33759;34715;34833.30333393318;63282.68358658177;64054;27928.860355698092;36027.4595881062;36306.91079267626;36319;36334.81832590873;14703;14911.403543858341;14918.254087295809;14925.92537037308;14929.850701493064;12542.1847579907;12636.87363126357;12652.557160499338;12661;12666.36668166588;7098.183708873443;7650.8087297818;8006.599670016476;8079.111297757242;8081.919180808234;3316.8507417166284;3357.731381489475;3358.6641335866216;3363.260082781833;3385.322935412927;843.3169133002287;843.8776377425239;857.6012154348258;940.2431043010442;949.126803340928;26406.943722250893;29408.558871616944;29447.668751218163;29494.705052949626;30087.548686769715;852.5992783391796;980.367662857453;1030.8247597908373;1059.210887888528;1115.4478533126155;14628.268586570628;15363.539093827203;15460.669219975696;15490.30293636789;15718.528444146694;654160;663456;664317;664383;665451;46178.53821461742;48013.5198648016;56278.71860640671;56685;57476.71261643665;1286.1575667937516;1328.3291937571464;1335.3857225676297;1339.2500199888093;1339.484298545067;28515.857420712764;29129.56305655459;29198.496180458482;29916.70083299226;29978.85010574933;44165.337519936584;49893.75053124711;49996;50044.49955500398;50608.49391506185;550713.203641762;551475;554845;555807;555882;26812.731872681805;26948.865255673598;26995;27338.72661273362;27411.84763752194;45698;45885;46633.83415414642;46652.300215496114;47309;37633.2473350537;37860.62139378681;38245.61754382532;38324;39054.2189156221;37116;37608;37976.81011595035;38334.233315334095;38589.6141038586;29664;32676.673233267364;37770.62229377671;37933.81033094817;38695.80652096721;32376.676233237366;33866.322673545896;33876.98369048932;34045;34056.65943340535;22894.74078925675;24193.879030604734;24645.01419943254;25323.746762532875;25946.35134121662 21:14:03 INFO - PROCESS | 1661 | |2;jquery.html;345.78561291998966;362.70077186321294;365.90486473516887;377.47907887115775;390.01910195857425;18930.05349732515;19200.135993880274;19799.604007919832;20434.284800032;20675;16559.66880662389;20165.59668806623;20273.695894561555;20622.896885515587;20741.17035318589;5799.333076696171;5825.796097136601;5993.430624090726;6080.847978800522;6100.145979562864;5860.296764388258;5984.760609575612;6015.608985415934;6021;6029.306629737593;5722.284714410699;5926.466618004379;5962.135490353896;5977.641341519523;5997.250343707036;5939.524838012949;5972.223610930595;5984.73068711909;5988.221531200916;6014.3985601439945;4903.117438861003;5085.872853178663;5129.307543481619;5138.563222126124;5177.637565370425;24543.509129817303;24629.50740985188;24821.87589062043;24894.253172404857;25074.874625626937;2172.424307558498;2174.0760176924746;2428.659987601737;2583.3799888026924;3078.7536997040183;11592.188546801728;14566.927165364212;14742.484013059599;14954.62613434662;15683.137427441487;1631.7552367144904;1873.681474149398;1907.4563749331512;1923.2018712234405;1928.9131989060527;114642;117097;118062.409687951;118241;118652.81347186504;10348.37909725404;10452.529636166391;10486.37081775096;10550.419726915094;10860.239783215098;12150.845669661368;13048.934755326163;13110.541131060367;13113.08208425401;13126.146800458131;7057.788266352018;7786.637338465745;7804.531728096333;7877.606119694107;8589.613467393981;6920.5847649141215;7905.142232486161;8495.447795893371;8520.318374530023;8747.475151490931;5507.284053073155;6389.329120442287;6472.579282346633;6529.249136349381;6534.869302613921;1497.445945000357;1548.8296287408375;1848.1221419825745;1849.398945342769;1884.7738271407245;781.2734157233828;855.2858363266741;883.7009595894026;887.671561522241;985.7683444390562;16792.748108778378;19989.60020799605;21134.154633814782;21142.682859757115;21245.57508849845;896.8162026125452;909.4406939732017;967.4243824924159;968.5351031504918;995.6558645828075;9165.404248723946;10235.692929211986;11284.32294062359;11292.37891915936;11323.150763692756;586750;592387.0761292418;600514;601009;601125;75297.62351188318;80853.38293234102;84478;106286;115070.4246478779;842.8735689646602;885.9202671759573;947.7156852944089;1000.9199264058858;1002.9749256268651;15334.846651533342;18705.158267877705;19090.427287181406;19116.852988820716;19139.712904306725;54239.45760542502;54586.727066364416;56404;56754;57346;67614.64770704656;77055.2294477048;78454;83270.16729832624;83836.06573769888;6459.354064593503;7653.234676532302;7931.087924888711;8054.23484768946;8059.637316320663;11459.484323205643;12353.876461235273;12397.008239341012;12604.180728252633;12661.113722039738;9288.674896378594;10860.456977151269;10898.836517452086;11062.612808551663;11063.889361106287;9250.537473126182;9647.276454265817;9889.208863290776;10589.099926506025;10886.727831804268;10914.612361557547;10953.726156846142;10998;11042.558297668325;11189.552417903518;6260.843478913063;6608.900866487103;7720.8455830884195;7854.882176767467;7879.133295337394;9734;10529.262951593315;10718.678439646823;10923.453827308762;10936.56253749841 21:14:03 INFO - PROCESS | 1661 | |3;mootools.html;1477.7192333456642;1520.7034628247488;1698.8980661160324;1807.4035568262475;1828.3692126216451;6571.572847764898;6801.285864984167;8356.206160414766;9177.08229177082;9213;8530.786730331729;8802.647894084244;8832.20510154086;8884.733457996253;8974.237189838872;2384.237044145874;2411.9397015074674;2422.7213870404953;2432.525657496789;2436.0864675746593;2374.263978166774;2414.915477958272;2421.515696860626;2423.660687503751;2426.1751004658413;2381.5475059738683;2401.111588712179;2401.979158857487;2424.49085692004;2430.3559556717482;1924.7786504552016;2254.819614430858;2386.6658667786437;2389.761023897605;2395.700537432821;2005.5086503806515;2386.7851893329685;2396.2451827674226;2398.448356877928;2401.171595799464;8552.444091134119;9024.23294020009;9082.18260356571;9084.364094513387;9111;4884.975575122137;4931.926021109686;5436.483534064258;5440.292761940962;5458.699771512565;3252.317013427183;3572.214112895159;3629.256002519477;3673.9081522961874;3680.6687398134295;978.3005151316771;1042.275618445181;1047.8061558611682;1066.306900514664;1072.4744875011265;10230.744231394201;11962;11977.22148060382;11998.52005919762;12005.699857503632;4717.127331443694;4821.112909249347;4825.420949486014;4919.3358896549225;4962.8511144665;10135.290529663;10246.026627470379;10247.948760256151;10259.435731034715;10267.486625668836;5459.3994660587305;5469.781208751685;5470.480304371078;5476.561875049986;5496.0931446310915;4445.2887537993765;4606.147862645421;4791.257355109963;4940.950590494121;5001.8499445016705;4008.939865902014;4029.234445455354;4122.711410201317;4148.709590328708;4153.397757325231;1284.2487145020268;1288.6134159752037;1289.9097063205486;1304.7455746129604;1307.8888294494884;764.8393837294119;880.823835232956;909.7225346269319;922.9769255768525;943.8018016216671;5786.971065144648;5893.764249429974;5957.582969192202;6092.72582733778;6291.96854015727;886.2378354615026;914.721010091915;916.5554705967601;925.8564922437033;989.8812142542942;3849.7103470337192;3924.156306394139;3983.940240896389;4078.632923036941;4084.9795751021056;10472.73818154537;10925.399103049409;10930.01629853317;10939.179561532917;10997.945010274898;10875.83686244707;10877.510512026978;10886.510107045202;10902.72743181411;10962.725931851608;8260.05009423924;8376.49741015541;8387.706430274911;8394;8395.454295470774;4348.47818261798;5796.739146738322;5886.852828679146;5891.646501209942;6041.667708276087;7926.722564710438;8525.914740852511;9864.901350986398;10196.082352588302;10348.793024139626;7459.328660420588;10112.191024717858;10130.696079117639;10397.220208484243;10430.061294483532;5532.474414930575;7056.223815380406;7265.818354541178;7446;7758.185390534139;8277;8422.157784221528;8498.150184981452;8550.1449855014;8586.957065214634;7433.8141546461775;7564.357029652321;7570.280823321776;7573.4893174044955;7658;7283.781486555412;7362.852742945218;7402.407807375289;7537.434692397982;7586.203448637813;6971.163460384786;7089.82275443118;7089.964550177217;7140.321669441395;7150.141982962078;4932.8520144395725;4950.034743225036;5055.747212639427;5114.360704911886;5122.564582010571;5026.949730502648;5072.923906141337;5136.434992150935;5187.792488300426;5208.322918020633 21:14:03 INFO - PROCESS | 1661 | |4;prototype.html;266;277.3081162499564;296.09100062807175;299.8455795265439;313.41704429760654;4662.766861656921;4900.338454308668;4933.802647894085;5335.493128152819;5531.806386776464;5243.191099070824;5337.386200586933;5370.409254981959;5474.439784661362;5501.394846566885;3584.677379035886;4147.543770185277;4196.181744559812;4214.178235244129;4261.190373828978;3890.396988466778;3892.6691231245377;3978.7175580146495;4197.349410841324;4232.449781528397;4127.339625659895;4141.295979683461;4150.958490415087;4171.270027745132;4181.435506206653;3304;3422.845971931269;4156.397322388267;4156.958430415687;4187.1625674865145;2964.4219377221452;3577.5170352002406;3589.8025608591515;3595.712343012579;3607.2244467439627;5103.9489605103845;5230.660007099565;5449.291592093042;5483.8080667176855;5514.3658479275;3970.3647416413237;4129.876103716893;4400.273954797487;4617.822455273884;4653.302004699322;5847.064469684872;5873.826684126858;5878.794242201502;5883.676397798119;5903.704814759245;3284.786488878215;3665.193657395369;3900.4929359183407;3912.8239229234755;3940.4732764176188;6145.3547377525165;6177.876442471171;6223.782167624111;6232.781852635181;6296.779612713533;3334.6498617644797;3981.144054028398;4004.5594984551676;4014.8996275092773;4020.3969404589548;3306.8015919044933;3668.3763760160837;4021.1756589899;4048.291548978917;4080.8775736727944;4743.5967942724565;4776.904461910743;4786.497417771154;4790.712557246577;4799.736014519165;4969.925451118236;5859.20900678403;5892.381299963527;5924.407559244128;5992.880142397127;1142.1548054439654;1279.8080287956695;1305.922613843583;1354.6410201296665;1376.9672745440919;810.7838242636046;825.5170725125748;839.5508403004363;845.2730651639584;849.6049337058298;3874.8643797466952;4137.565555616678;4140.834366625361;4152.501699796044;4301.773994411577;4829.806807727651;4996.602310428871;5029.547340739351;5042.9243561345875;5082.33929589151;4108.582539024032;4551.522090180483;4623.930641040387;4628.421447319085;4681.391419115494;2441.7192022917593;2493.314338556875;2497.300755788382;2609.569421045544;2642.167717169086;4247.830086796555;4479.910401792011;4597.678162528658;5162.483751624807;5290.550303224269;4526.804499817586;4606.539346065367;5046.495350464924;5055.800726683904;5141.897162056738;4299.828006879689;4542.386777785023;4566.744145360052;5204.193350030751;5289;4517.774111294357;4960.975195124001;5028.597712182944;5030.2957585937265;5031.496850315086;3927.9770917977835;4122.91754164909;4351.976331006097;4927.433345165352;4943.036107958914;3736.7417528193155;4302;4465.553444655508;4559.385825671071;4995.925061124013;2880.9135725928254;3023.546468029725;3056.617922759655;3416.4192087344713;3445.4315038018444;4334.349847522897;4547.931781023221;4665.930011049769;4983.807124865193;5733.426657334233;5267.471613625405;5695.772169113189;5722.856928576819;5745.988903883607;5767.250257466502;4399.73601583906;5140.324667862496;5363.222332761806;5900.940990590211;5935.792247271324;5442.9455705444025;5728.942710573007;5759.107338362561;5764.694122351093;5965.850853728692;5184.444666599929;5307.548858347083;5589.524890384363;5692.829215123553;5791.333996590278;5079.184186741047;5265.60507961912;5563.8887222256135;5867.332916926921;5926.466618004228 21:14:03 INFO - PROCESS | 1661 | |5;yui.html;587.6036508978107;687.858988907274;715.1739740599606;717.2576383443137;717.6902153569737;15191.46829860955;15657.530274091767;16001.439949601767;16054.678906421865;16154.030758154518;13782.173069615805;14011.859881401182;14029.92985035076;14081.507147249848;14093.083949543297;671.4460570029745;688.9724411023553;843.182113350051;859.4284800607588;860.4708104515709;833.5207255827893;848.5502683577714;853.3429259470204;868.5700578213794;870.0864092702668;879.4283715584857;880.8150288439435;911.6080085563206;912.8767616371771;915.6108653822099;897.8069715011271;902.3548163063375;902.5036230073433;902.8916530016375;903.8463461211544;641.1568787045068;651.5829868883918;664.5979182594509;670.9463242940553;673.6766352150946;14419.855801442061;14771.482998095122;14847.406103755837;14912.254387280593;14923.552293431214;11882.762344753142;14385.99298049137;14427;14610.977231593793;14620.415183392652;9681.042451470508;11831.822522662167;11894.464749086312;11904.940475297655;11945.653686365722;3767.6797472214894;3801.3347664158937;3832.42513622956;3843.577206507282;3870.9032274193087;110983;113352;116392.41803791012;117858;119646.80353196444;10895.673129806119;10976.23166378346;11035.337879727242;11052.336859788438;11190.94404527972;22575;22596.209132680277;22643.43391415228;22644.773552264596;22664.093436262367;7966.044074710956;8025.959870200729;8055.275025247756;8214.219649133323;8324.209200125979;4405.493368262627;4438.101606279453;4458.754768487699;4477.328400739915;4485.585245020985;2804.4391121775725;3168.8415579220946;3721.6092310307567;3726.571444283888;3738.065483629093;638.396715104222;669.5514005616249;674.7368526274756;676.6447615002065;681.1179522518252;549.5274064304695;590.7755053079802;605.0923614578132;615.4891440104702;677.4275737002225;9178.449293042439;9380.486831604068;9394.342396032212;9484;9647.951760241154;517.2990597740084;552.7264004317889;580.407983856464;603.806781829819;643.3437893348759;3917.490726205575;4147.714208595346;4539.0694907543875;4665.626749860003;4754.572088512051;159755.20122399315;166048.16975915042;166363;166577;169689;105301.47349263205;117889.8211017896;139750.3012484931;144969;147163;117797.41101294439;122041;122445.99999999822;123306;124507;8930.240929520804;8955.238804701412;9029.097090290918;9035.02189178828;9077.273818094669;53809.192862107884;62216;63627.6818615904;63694;64726.89180426149;94883.52558237164;95864.52067739617;97544;100959.49520252351;101979.49010254901;11276.210665253353;11506;11562.710932226426;11596;11616.535338586362;12943.546975856176;13014.089013769324;13099.148555343872;13492.5277615283;13493.122947008413;11973.880261197277;12014.279143251433;12071;12102;12459.314737689152;11936;11995.220310679779;12284.508619655471;12515.37423128858;12582.30797306157;11698.883011169779;12026.338551379758;12142.757144856876;12217.93891030539;12322.445489952794;10134.037266459674;10240.897591023995;10410.635627752992;10465.953404659778;10520.105791007543;8835.425697329652;9236.815263694823;10261.74345641365;10607.151427885596;10683.412412317397 21:14:03 INFO - PROCESS | 1661 | __end_tp_report 21:14:03 INFO - PROCESS | 1661 | __start_cc_report 21:14:03 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,363 21:14:03 INFO - PROCESS | 1661 | __end_cc_report 21:14:03 INFO - PROCESS | 1661 | __startTimestamp1475208843319__endTimestamp 21:14:03 INFO - PROCESS | 1661 | 21:14:03 INFO - PROCESS | 1661 | ------- Summary: start ------- 21:14:03 INFO - PROCESS | 1661 | Number of tests: 6 21:14:03 INFO - PROCESS | 1661 | 21:14:03 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5711.13 Median:5920.73 stddev:681.64 (11.5%) stddev-sans-first:659.04 21:14:03 INFO - PROCESS | 1661 | Values: 3394.0 3409.4 3440.3 3451.9 3452.4 4979.2 5564.6 5650.1 5734.3 5781.0 5703.4 5721.7 5755.9 5829.8 5845.2 5219.1 5219.7 5234.5 5353.5 5356.3 5334.5 5348.2 5385.1 5397.3 5473.7 4491.2 5198.2 5207.7 5280.4 5351.6 5268.1 5274.5 5305.0 5338.5 5348.4 3990.3 5243.4 5256.6 5268.7 5320.0 5647.8 5652.9 5703.5 5712.1 5852.2 6143.9 6184.3 6185.2 6208.0 6224.9 6273.8 6282.9 6293.7 6308.7 6328.9 4830.5 5891.6 5950.6 5954.3 5965.6 6339.0 6360.9 6374.9 6379.5 6387.5 6224.5 6244.0 6250.4 6262.3 6263.1 6141.0 6192.5 6194.2 6199.9 6323.6 5428.7 6226.0 6241.9 6266.1 6270.7 6228.1 6259.0 6271.1 6286.3 6287.6 4893.0 4954.6 4988.1 4993.7 5034.2 4441.6 4470.2 4500.4 4512.6 4530.5 5513.5 5844.8 5877.2 5946.4 5954.7 6208.5 6230.1 6240.3 6291.5 6306.4 5106.3 5752.3 5840.1 5873.9 6045.1 5449.7 5496.8 5497.5 5504.4 5516.5 6370.0 6427.5 6442.9 6446.8 6451.1 6158.3 6190.4 6210.2 6229.4 6249.3 6418.8 6439.4 6443.2 6452.1 6471.5 5060.9 6111.9 6139.8 6142.5 6201.0 6126.2 6212.9 6259.1 6261.7 6268.5 4944.4 6165.4 6203.9 6204.2 6211.6 5037.9 5562.5 5660.4 5828.0 5920.7 4330.6 4778.9 5795.9 5854.8 5940.7 5050.3 6146.7 6153.8 6167.4 6211.8 4509.3 5951.4 5993.5 6002.8 6033.6 21:14:03 INFO - PROCESS | 1661 | 21:14:03 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:66121.80 Median:38329.12 stddev:148583.39 (387.7%) stddev-sans-first:148918.42 21:14:03 INFO - PROCESS | 1661 | Values: 464.9 492.9 499.5 523.4 533.3 37242.1 40048.6 40207.6 40288.2 40326.0 33653.7 40708.6 41228.0 41378.6 41471.0 9584.2 9774.9 9784.4 9791.1 9800.8 9507.0 9591.5 9609.2 9625.1 9651.0 9625.6 9652.8 9654.8 9661.6 9672.2 9587.6 9594.9 9622.2 9654.7 9674.3 9622.8 9675.2 9690.8 9700.9 9713.5 38756.0 39245.4 39383.6 39485.2 39632.0 3226.6 3363.4 3462.9 3508.0 3529.6 29139.2 29913.4 31088.8 31162.0 31193.9 3115.0 3261.6 3291.2 3360.8 3364.8 373026.1 373967.0 445824.0 446411.5 452245.0 33759.0 34715.0 34833.3 63282.7 64054.0 27928.9 36027.5 36306.9 36319.0 36334.8 14703.0 14911.4 14918.3 14925.9 14929.9 12542.2 12636.9 12652.6 12661.0 12666.4 7098.2 7650.8 8006.6 8079.1 8081.9 3316.9 3357.7 3358.7 3363.3 3385.3 843.3 843.9 857.6 940.2 949.1 26406.9 29408.6 29447.7 29494.7 30087.5 852.6 980.4 1030.8 1059.2 1115.4 14628.3 15363.5 15460.7 15490.3 15718.5 654160.0 663456.0 664317.0 664383.0 665451.0 46178.5 48013.5 56278.7 56685.0 57476.7 1286.2 1328.3 1335.4 1339.3 1339.5 28515.9 29129.6 29198.5 29916.7 29978.9 44165.3 49893.8 49996.0 50044.5 50608.5 550713.2 551475.0 554845.0 555807.0 555882.0 26812.7 26948.9 26995.0 27338.7 27411.8 45698.0 45885.0 46633.8 46652.3 47309.0 37633.2 37860.6 38245.6 38324.0 39054.2 37116.0 37608.0 37976.8 38334.2 38589.6 29664.0 32676.7 37770.6 37933.8 38695.8 32376.7 33866.3 33877.0 34045.0 34056.7 22894.7 24193.9 24645.0 25323.7 25946.4 21:14:03 INFO - PROCESS | 1661 | 21:14:03 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:33912.56 Median:371.69 stddev:98857.73 (26596.7%) stddev-sans-first:99102.93 21:14:03 INFO - PROCESS | 1661 | Values: 345.8 362.7 365.9 377.5 390.0 18930.1 19200.1 19799.6 20434.3 20675.0 16559.7 20165.6 20273.7 20622.9 20741.2 5799.3 5825.8 5993.4 6080.8 6100.1 5860.3 5984.8 6015.6 6021.0 6029.3 5722.3 5926.5 5962.1 5977.6 5997.3 5939.5 5972.2 5984.7 5988.2 6014.4 4903.1 5085.9 5129.3 5138.6 5177.6 24543.5 24629.5 24821.9 24894.3 25074.9 2172.4 2174.1 2428.7 2583.4 3078.8 11592.2 14566.9 14742.5 14954.6 15683.1 1631.8 1873.7 1907.5 1923.2 1928.9 114642.0 117097.0 118062.4 118241.0 118652.8 10348.4 10452.5 10486.4 10550.4 10860.2 12150.8 13048.9 13110.5 13113.1 13126.1 7057.8 7786.6 7804.5 7877.6 8589.6 6920.6 7905.1 8495.4 8520.3 8747.5 5507.3 6389.3 6472.6 6529.2 6534.9 1497.4 1548.8 1848.1 1849.4 1884.8 781.3 855.3 883.7 887.7 985.8 16792.7 19989.6 21134.2 21142.7 21245.6 896.8 909.4 967.4 968.5 995.7 9165.4 10235.7 11284.3 11292.4 11323.2 586750.0 592387.1 600514.0 601009.0 601125.0 75297.6 80853.4 84478.0 106286.0 115070.4 842.9 885.9 947.7 1000.9 1003.0 15334.8 18705.2 19090.4 19116.9 19139.7 54239.5 54586.7 56404.0 56754.0 57346.0 67614.6 77055.2 78454.0 83270.2 83836.1 6459.4 7653.2 7931.1 8054.2 8059.6 11459.5 12353.9 12397.0 12604.2 12661.1 9288.7 10860.5 10898.8 11062.6 11063.9 9250.5 9647.3 9889.2 10589.1 10886.7 10914.6 10953.7 10998.0 11042.6 11189.6 6260.8 6608.9 7720.8 7854.9 7879.1 9734.0 10529.3 10718.7 10923.5 10936.6 21:14:03 INFO - PROCESS | 1661 | 21:14:03 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:5715.86 Median:4936.90 stddev:3200.65 (64.8%) stddev-sans-first:3193.78 21:14:03 INFO - PROCESS | 1661 | Values: 1477.7 1520.7 1698.9 1807.4 1828.4 6571.6 6801.3 8356.2 9177.1 9213.0 8530.8 8802.6 8832.2 8884.7 8974.2 2384.2 2411.9 2422.7 2432.5 2436.1 2374.3 2414.9 2421.5 2423.7 2426.2 2381.5 2401.1 2402.0 2424.5 2430.4 1924.8 2254.8 2386.7 2389.8 2395.7 2005.5 2386.8 2396.2 2398.4 2401.2 8552.4 9024.2 9082.2 9084.4 9111.0 4885.0 4931.9 5436.5 5440.3 5458.7 3252.3 3572.2 3629.3 3673.9 3680.7 978.3 1042.3 1047.8 1066.3 1072.5 10230.7 11962.0 11977.2 11998.5 12005.7 4717.1 4821.1 4825.4 4919.3 4962.9 10135.3 10246.0 10247.9 10259.4 10267.5 5459.4 5469.8 5470.5 5476.6 5496.1 4445.3 4606.1 4791.3 4941.0 5001.8 4008.9 4029.2 4122.7 4148.7 4153.4 1284.2 1288.6 1289.9 1304.7 1307.9 764.8 880.8 909.7 923.0 943.8 5787.0 5893.8 5957.6 6092.7 6292.0 886.2 914.7 916.6 925.9 989.9 3849.7 3924.2 3983.9 4078.6 4085.0 10472.7 10925.4 10930.0 10939.2 10997.9 10875.8 10877.5 10886.5 10902.7 10962.7 8260.1 8376.5 8387.7 8394.0 8395.5 4348.5 5796.7 5886.9 5891.6 6041.7 7926.7 8525.9 9864.9 10196.1 10348.8 7459.3 10112.2 10130.7 10397.2 10430.1 5532.5 7056.2 7265.8 7446.0 7758.2 8277.0 8422.2 8498.2 8550.1 8587.0 7433.8 7564.4 7570.3 7573.5 7658.0 7283.8 7362.9 7402.4 7537.4 7586.2 6971.2 7089.8 7090.0 7140.3 7150.1 4932.9 4950.0 5055.7 5114.4 5122.6 5026.9 5072.9 5136.4 5187.8 5208.3 21:14:03 INFO - PROCESS | 1661 | 21:14:03 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:4343.48 Median:4626.18 stddev:1369.20 (29.6%) stddev-sans-first:1338.40 21:14:03 INFO - PROCESS | 1661 | Values: 266.0 277.3 296.1 299.8 313.4 4662.8 4900.3 4933.8 5335.5 5531.8 5243.2 5337.4 5370.4 5474.4 5501.4 3584.7 4147.5 4196.2 4214.2 4261.2 3890.4 3892.7 3978.7 4197.3 4232.4 4127.3 4141.3 4151.0 4171.3 4181.4 3304.0 3422.8 4156.4 4157.0 4187.2 2964.4 3577.5 3589.8 3595.7 3607.2 5103.9 5230.7 5449.3 5483.8 5514.4 3970.4 4129.9 4400.3 4617.8 4653.3 5847.1 5873.8 5878.8 5883.7 5903.7 3284.8 3665.2 3900.5 3912.8 3940.5 6145.4 6177.9 6223.8 6232.8 6296.8 3334.6 3981.1 4004.6 4014.9 4020.4 3306.8 3668.4 4021.2 4048.3 4080.9 4743.6 4776.9 4786.5 4790.7 4799.7 4969.9 5859.2 5892.4 5924.4 5992.9 1142.2 1279.8 1305.9 1354.6 1377.0 810.8 825.5 839.6 845.3 849.6 3874.9 4137.6 4140.8 4152.5 4301.8 4829.8 4996.6 5029.5 5042.9 5082.3 4108.6 4551.5 4623.9 4628.4 4681.4 2441.7 2493.3 2497.3 2609.6 2642.2 4247.8 4479.9 4597.7 5162.5 5290.6 4526.8 4606.5 5046.5 5055.8 5141.9 4299.8 4542.4 4566.7 5204.2 5289.0 4517.8 4961.0 5028.6 5030.3 5031.5 3928.0 4122.9 4352.0 4927.4 4943.0 3736.7 4302.0 4465.6 4559.4 4995.9 2880.9 3023.5 3056.6 3416.4 3445.4 4334.3 4547.9 4665.9 4983.8 5733.4 5267.5 5695.8 5722.9 5746.0 5767.3 4399.7 5140.3 5363.2 5900.9 5935.8 5442.9 5728.9 5759.1 5764.7 5965.9 5184.4 5307.5 5589.5 5692.8 5791.3 5079.2 5265.6 5563.9 5867.3 5926.5 21:14:03 INFO - PROCESS | 1661 | 21:14:03 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:25796.36 Median:3445.23 stddev:42801.45 (1242.3%) stddev-sans-first:42879.66 21:14:03 INFO - PROCESS | 1661 | Values: 587.6 687.9 715.2 717.3 717.7 15191.5 15657.5 16001.4 16054.7 16154.0 13782.2 14011.9 14029.9 14081.5 14093.1 671.4 689.0 843.2 859.4 860.5 833.5 848.6 853.3 868.6 870.1 879.4 880.8 911.6 912.9 915.6 897.8 902.4 902.5 902.9 903.8 641.2 651.6 664.6 670.9 673.7 14419.9 14771.5 14847.4 14912.3 14923.6 11882.8 14386.0 14427.0 14611.0 14620.4 9681.0 11831.8 11894.5 11904.9 11945.7 3767.7 3801.3 3832.4 3843.6 3870.9 110983.0 113352.0 116392.4 117858.0 119646.8 10895.7 10976.2 11035.3 11052.3 11190.9 22575.0 22596.2 22643.4 22644.8 22664.1 7966.0 8026.0 8055.3 8214.2 8324.2 4405.5 4438.1 4458.8 4477.3 4485.6 2804.4 3168.8 3721.6 3726.6 3738.1 638.4 669.6 674.7 676.6 681.1 549.5 590.8 605.1 615.5 677.4 9178.4 9380.5 9394.3 9484.0 9648.0 517.3 552.7 580.4 603.8 643.3 3917.5 4147.7 4539.1 4665.6 4754.6 159755.2 166048.2 166363.0 166577.0 169689.0 105301.5 117889.8 139750.3 144969.0 147163.0 117797.4 122041.0 122446.0 123306.0 124507.0 8930.2 8955.2 9029.1 9035.0 9077.3 53809.2 62216.0 63627.7 63694.0 64726.9 94883.5 95864.5 97544.0 100959.5 101979.5 11276.2 11506.0 11562.7 11596.0 11616.5 12943.5 13014.1 13099.1 13492.5 13493.1 11973.9 12014.3 12071.0 12102.0 12459.3 11936.0 11995.2 12284.5 12515.4 12582.3 11698.9 12026.3 12142.8 12217.9 12322.4 10134.0 10240.9 10410.6 10466.0 10520.1 8835.4 9236.8 10261.7 10607.2 10683.4 21:14:03 INFO - PROCESS | 1661 | -------- Summary: end -------- 21:14:03 INFO - PROCESS | 1661 | 21:14:04 INFO - TEST-INFO | 1661: exit 0 21:14:04 INFO - TEST-OK | dromaeo_css | took 1114252ms 21:14:04 INFO - TEST-START | kraken 21:14:04 INFO - operating with platform_type : mac_ 21:14:04 INFO - Initialising browser for kraken test... 21:14:04 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmpSHUl38/profile http://localhost:49273/getInfo.html) 21:14:06 INFO - PROCESS | 1673 | 2016-09-29 21:14:06.627 plugin-container[1674:9968] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8927, name = 'com.apple.tsm.portname' 21:14:06 INFO - PROCESS | 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:14:06 INFO - PROCESS | 1673 | 2016-09-29 21:14:06.631 plugin-container[1674:9968] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c1b, name = 'com.apple.CFPasteboardClient' 21:14:06 INFO - PROCESS | 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:14:06 INFO - PROCESS | 1673 | 2016-09-29 21:14:06.631 plugin-container[1674:9968] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:14:06 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 21:14:06 INFO - PROCESS | 1673 | colorDepth:24 21:14:06 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 21:14:06 INFO - PROCESS | 1673 | __metrics 21:14:06 ERROR - PROCESS | 1673 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3076: TypeError: this.tabs is undefined 21:14:07 INFO - TEST-INFO | 1673: exit 0 21:14:07 INFO - Browser initialized. 21:14:07 INFO - Running cycle 1/1 for kraken test... 21:14:07 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/T/tmpSHUl38/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:14:10 INFO - PROCESS | 1675 | 2016-09-29 21:14:10.178 plugin-container[1676:10078] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x890b, name = 'com.apple.tsm.portname' 21:14:10 INFO - PROCESS | 1675 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:14:10 INFO - PROCESS | 1675 | 2016-09-29 21:14:10.180 plugin-container[1676:10078] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c17, name = 'com.apple.CFPasteboardClient' 21:14:10 INFO - PROCESS | 1675 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:14:10 INFO - PROCESS | 1675 | 2016-09-29 21:14:10.180 plugin-container[1676:10078] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:14:19 INFO - PROCESS | 1675 | RSS: Main: 239763456 21:14:19 INFO - PROCESS | 1675 | 21:14:22 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 21:14:23 INFO - PROCESS | 1675 | RSS: Main: 253329408 21:14:23 INFO - PROCESS | 1675 | 21:14:26 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 21:14:27 INFO - PROCESS | 1675 | RSS: Main: 260825088 21:14:27 INFO - PROCESS | 1675 | 21:14:30 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 21:14:31 INFO - PROCESS | 1675 | RSS: Main: 261718016 21:14:31 INFO - PROCESS | 1675 | 21:14:33 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 21:14:34 INFO - PROCESS | 1675 | RSS: Main: 263864320 21:14:34 INFO - PROCESS | 1675 | 21:14:35 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:14:36 INFO - PROCESS | 1675 | RSS: Main: 264499200 21:14:36 INFO - PROCESS | 1675 | 21:14:39 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 21:14:40 INFO - PROCESS | 1675 | RSS: Main: 265351168 21:14:40 INFO - PROCESS | 1675 | 21:14:43 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 21:14:44 INFO - PROCESS | 1675 | RSS: Main: 265625600 21:14:44 INFO - PROCESS | 1675 | 21:14:47 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 21:14:47 INFO - PROCESS | 1675 | RSS: Main: 265797632 21:14:47 INFO - PROCESS | 1675 | 21:14:49 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:14:50 INFO - PROCESS | 1675 | RSS: Main: 265039872 21:14:50 INFO - PROCESS | 1675 | 21:14:51 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:14:52 INFO - PROCESS | 1675 | RSS: Main: 265080832 21:14:52 INFO - PROCESS | 1675 | 21:14:54 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:14:55 INFO - PROCESS | 1675 | RSS: Main: 265142272 21:14:55 INFO - PROCESS | 1675 | 21:14:58 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:14:59 INFO - PROCESS | 1675 | RSS: Main: 265469952 21:14:59 INFO - PROCESS | 1675 | 21:15:02 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:15:02 INFO - PROCESS | 1675 | RSS: Main: 265261056 21:15:02 INFO - PROCESS | 1675 | 21:15:04 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 21:15:04 INFO - PROCESS | 1675 | RSS: Main: 266948608 21:15:04 INFO - PROCESS | 1675 | 21:15:04 INFO - PROCESS | 1675 | __start_tp_report 21:15:04 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 21:15:04 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 21:15:04 INFO - PROCESS | 1675 | |i|pagename|runs| 21:15:04 INFO - PROCESS | 1675 | |0;ai-astar;114;117;118;116;118;119;118;124;172;118 21:15:04 INFO - PROCESS | 1675 | |1;audio-beat-detection;144;112;112;145;117;148;144;112;160;152 21:15:04 INFO - PROCESS | 1675 | |2;audio-dft;210;243;233;235;220;202;236;256;198;205 21:15:04 INFO - PROCESS | 1675 | |3;audio-fft;85;58;60;85;83;68;75;84;109;86 21:15:04 INFO - PROCESS | 1675 | |4;audio-oscillator;87;73;87;74;69;72;89;78;60;92 21:15:04 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;90;101;127;121;115;81;125;74;119;91 21:15:04 INFO - PROCESS | 1675 | |6;imaging-darkroom;137;117;169;162;99;112;172;165;99;98 21:15:04 INFO - PROCESS | 1675 | |7;imaging-desaturate;89;95;91;74;147;143;92;143;99;81 21:15:04 INFO - PROCESS | 1675 | |8;json-parse-financial;56;59;57;59;59;58;60;58;59;57 21:15:04 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;68;75;74;85;77;69;69;70;70;70 21:15:04 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;86;91;108;106;90;86;100;87;87;87 21:15:04 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;144;142;139;140;140;150;162;156;220;141 21:15:04 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;174;171;171;174;175;174;169;174;161;157 21:15:04 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;71;69;66;69;68;69;68;66;66;62 21:15:04 INFO - PROCESS | 1675 | __end_tp_report 21:15:04 INFO - PROCESS | 1675 | __start_cc_report 21:15:04 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,930 21:15:04 INFO - PROCESS | 1675 | __end_cc_report 21:15:04 INFO - PROCESS | 1675 | __startTimestamp1475208904704__endTimestamp 21:15:04 INFO - PROCESS | 1675 | 21:15:04 INFO - PROCESS | 1675 | ------- Summary: start ------- 21:15:04 INFO - PROCESS | 1675 | Number of tests: 14 21:15:04 INFO - PROCESS | 1675 | 21:15:04 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:123.40 Median:118.00 stddev:17.26 (14.6%) stddev-sans-first:17.97 21:15:04 INFO - PROCESS | 1675 | Values: 114.0 117.0 118.0 116.0 118.0 119.0 118.0 124.0 172.0 118.0 21:15:04 INFO - PROCESS | 1675 | 21:15:04 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:134.60 Median:144.50 stddev:19.02 (13.2%) stddev-sans-first:19.86 21:15:04 INFO - PROCESS | 1675 | Values: 144.0 112.0 112.0 145.0 117.0 148.0 144.0 112.0 160.0 152.0 21:15:04 INFO - PROCESS | 1675 | 21:15:04 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:223.80 Median:234.00 stddev:19.62 (8.4%) stddev-sans-first:20.16 21:15:04 INFO - PROCESS | 1675 | Values: 210.0 243.0 233.0 235.0 220.0 202.0 236.0 256.0 198.0 205.0 21:15:04 INFO - PROCESS | 1675 | 21:15:04 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:79.30 Median:83.50 stddev:14.91 (17.9%) stddev-sans-first:15.67 21:15:04 INFO - PROCESS | 1675 | Values: 85.0 58.0 60.0 85.0 83.0 68.0 75.0 84.0 109.0 86.0 21:15:04 INFO - PROCESS | 1675 | 21:15:04 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:78.10 Median:82.50 stddev:10.33 (12.5%) stddev-sans-first:10.45 21:15:04 INFO - PROCESS | 1675 | Values: 87.0 73.0 87.0 74.0 69.0 72.0 89.0 78.0 60.0 92.0 21:15:04 INFO - PROCESS | 1675 | 21:15:04 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:104.40 Median:100.50 stddev:19.45 (19.4%) stddev-sans-first:19.92 21:15:04 INFO - PROCESS | 1675 | Values: 90.0 101.0 127.0 121.0 115.0 81.0 125.0 74.0 119.0 91.0 21:15:04 INFO - PROCESS | 1675 | 21:15:04 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:133.00 Median:170.50 stddev:31.50 (18.5%) stddev-sans-first:33.38 21:15:04 INFO - PROCESS | 1675 | Values: 137.0 117.0 169.0 162.0 99.0 112.0 172.0 165.0 99.0 98.0 21:15:04 INFO - PROCESS | 1675 | 21:15:04 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:105.40 Median:90.00 stddev:27.78 (30.9%) stddev-sans-first:28.82 21:15:04 INFO - PROCESS | 1675 | Values: 89.0 95.0 91.0 74.0 147.0 143.0 92.0 143.0 99.0 81.0 21:15:04 INFO - PROCESS | 1675 | 21:15:04 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:58.20 Median:59.00 stddev:1.23 (2.1%) stddev-sans-first:1.01 21:15:04 INFO - PROCESS | 1675 | Values: 56.0 59.0 57.0 59.0 59.0 58.0 60.0 58.0 59.0 57.0 21:15:04 INFO - PROCESS | 1675 | 21:15:04 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:72.70 Median:72.00 stddev:5.25 (7.3%) stddev-sans-first:5.29 21:15:04 INFO - PROCESS | 1675 | Values: 68.0 75.0 74.0 85.0 77.0 69.0 69.0 70.0 70.0 70.0 21:15:04 INFO - PROCESS | 1675 | 21:15:04 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:92.80 Median:87.00 stddev:8.57 (9.9%) stddev-sans-first:8.73 21:15:04 INFO - PROCESS | 1675 | Values: 86.0 91.0 108.0 106.0 90.0 86.0 100.0 87.0 87.0 87.0 21:15:04 INFO - PROCESS | 1675 | 21:15:04 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:153.40 Median:147.00 stddev:24.65 (16.8%) stddev-sans-first:25.90 21:15:04 INFO - PROCESS | 1675 | Values: 144.0 142.0 139.0 140.0 140.0 150.0 162.0 156.0 220.0 141.0 21:15:04 INFO - PROCESS | 1675 | 21:15:04 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.00 Median:174.00 stddev:6.16 (3.5%) stddev-sans-first:6.37 21:15:04 INFO - PROCESS | 1675 | Values: 174.0 171.0 171.0 174.0 175.0 174.0 169.0 174.0 161.0 157.0 21:15:04 INFO - PROCESS | 1675 | 21:15:04 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.50 stddev:2.50 (3.7%) stddev-sans-first:2.29 21:15:04 INFO - PROCESS | 1675 | Values: 71.0 69.0 66.0 69.0 68.0 69.0 68.0 66.0 66.0 62.0 21:15:04 INFO - PROCESS | 1675 | -------- Summary: end -------- 21:15:04 INFO - PROCESS | 1675 | 21:15:05 INFO - TEST-INFO | 1675: exit 0 21:15:05 INFO - TEST-OK | kraken | took 61504ms 21:15:05 INFO - SUITE-END | took 1175s 21:15:05 INFO - Completed test suite (00:19:36) 21:15:05 INFO - javascript benchmark 21:15:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7150.8446520575035, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3393.9660603393945, 3409.3522230776152, 3440.2947395783867, 3451.9137021574475, 3452.378571857066, 4979.228219625955, 5564.610477266588, 5650.0959846424585, 5734.340550836655, 5780.971095144519, 5703.372629010816, 5721.713914304289, 5755.856103597413, 5829.8251052468495, 5845.240118784552, 5219.112750832368, 5219.739013049351, 5234.476552344764, 5353.491418315273, 5356.330458692663, 5334.546563542103, 5348.197770334462, 5385.111456609655, 5397.298351214356, 5473.67157970521, 4491.169133710274, 5198.194279886624, 5207.739613019372, 5280.419153893069, 5351.625386222966, 5268.130758424855, 5274.472552744694, 5304.973475132639, 5338.519533241988, 5348.411674715778, 3990.3415936370466, 5243.449437809013, 5256.553192978601, 5268.683878967274, 5320.0, 5647.774089036434, 5652.943470565283, 5703.458171473745, 5712.11462223356, 5852.209951656557, 6143.911070236141, 6184.288806787232, 6185.226846644169, 6208.0, 6224.906626400608, 6273.843153921142, 6282.937170628281, 6293.653849038301, 6308.684565771693, 6328.905066424008, 4830.468648448667, 5891.646501209855, 5950.553708471882, 5954.255718035246, 5965.642061476326, 6339.049142628643, 6360.9045864312075, 6374.904376434357, 6379.457746091542, 6387.489000880011, 6224.537233750065, 6243.968780156161, 6250.4374606285655, 6262.3111457739615, 6263.091851681572, 6140.995089002448, 6192.535559833031, 6194.194754681863, 6199.876002480015, 6323.588966717149, 5428.728563571805, 6226.0, 6241.906371404341, 6266.060090986297, 6270.717817698214, 6228.128062071313, 6258.968705156445, 6271.090691849657, 6286.339934306832, 6287.591306565058, 4892.996935628188, 4954.603631709455, 4988.052270068675, 4993.650444468925, 5034.219695947061, 4441.555844415532, 4470.173017991617, 4500.369948207253, 4512.616427603625, 4530.456345238593, 5513.476219759116, 5844.795432159854, 5877.23595932526, 5946.405359464019, 5954.702264886739, 6208.534359923024, 6230.096635987757, 6240.31356550779, 6291.5281353898645, 6306.400891915261, 5106.259592359087, 5752.28096487939, 5840.123981402823, 5873.853153671193, 6045.093236014545, 5449.673019618756, 5496.80761173357, 5497.532709719639, 5504.366997795225, 5516.531094856982, 6370.044493325945, 6427.453666438218, 6442.871142577029, 6446.774362897276, 6451.064595633607, 6158.291796443466, 6190.350013248544, 6210.223722034746, 6229.408206220402, 6249.281332646622, 6418.807435776934, 6439.388258115471, 6443.194600674916, 6452.064450654722, 6471.546991710535, 5060.898782024413, 6111.895622426936, 6139.754409823736, 6142.468080853438, 6200.968995154995, 6126.24286675468, 6212.9068063978175, 6259.06114082873, 6261.655608941552, 6268.4671802897265, 4944.431390390151, 6165.383461653799, 6203.937960620517, 6204.193454850841, 6211.565190436626, 5037.899242015066, 5562.499375056264, 5660.377358490645, 5827.980103481958, 5920.73356698941, 4330.631896288851, 4778.904421911473, 5795.884082318415, 5854.795082172103, 5940.702964851826, 5050.343455350928, 6146.661933593696, 6153.800008998202, 6167.352427994996, 6211.844703882439, 4509.2559727644575, 5951.434613711691, 5993.460588547052, 6002.789902353396, 6033.607815491979], "value": 5671.593288401699, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [464.92328765753655, 492.9088118698043, 499.45060433523105, 523.3928642774382, 533.2987121934656, 37242.068948276305, 40048.599514004854, 40207.58235549982, 40288.1942361153, 40326.0, 33653.66346336536, 40708.592914070854, 41228.0, 41378.58621413785, 41471.0, 9584.233261339075, 9774.853377199348, 9784.36401633895, 9791.11879930806, 9800.774903137108, 9506.952465237664, 9591.520423978807, 9609.183219426322, 9625.133737963582, 9650.951745241264, 9625.566849491755, 9652.75868103296, 9654.806903861918, 9661.56522956465, 9672.22622190223, 9587.568559414844, 9594.856077158849, 9622.230221582291, 9654.71035868925, 9674.274429417752, 9622.807543849083, 9675.225981921429, 9690.75773105671, 9700.854487182627, 9713.514324283757, 38756.0, 39245.41131882995, 39383.606163938566, 39485.21029579421, 39632.00919976995, 3226.5805445291962, 3363.428217203057, 3462.8614855405754, 3508.0, 3529.5588051493564, 29139.192033314477, 29913.401731965456, 31088.8445557773, 31162.0, 31193.908213212657, 3114.9532757008665, 3261.6412194658806, 3291.1607540077125, 3360.7983520988573, 3364.814935178564, 373026.1348693239, 373967.0, 445824.0, 446411.53588464024, 452245.0, 33759.0, 34715.0, 34833.30333393318, 63282.68358658177, 64054.0, 27928.860355698092, 36027.4595881062, 36306.91079267626, 36319.0, 36334.81832590873, 14703.0, 14911.403543858341, 14918.254087295809, 14925.92537037308, 14929.850701493064, 12542.1847579907, 12636.87363126357, 12652.557160499338, 12661.0, 12666.36668166588, 7098.183708873443, 7650.8087297818, 8006.599670016476, 8079.111297757242, 8081.919180808234, 3316.8507417166284, 3357.731381489475, 3358.6641335866216, 3363.260082781833, 3385.322935412927, 843.3169133002287, 843.8776377425239, 857.6012154348258, 940.2431043010442, 949.126803340928, 26406.943722250893, 29408.558871616944, 29447.668751218163, 29494.705052949626, 30087.548686769715, 852.5992783391796, 980.367662857453, 1030.8247597908373, 1059.210887888528, 1115.4478533126155, 14628.268586570628, 15363.539093827203, 15460.669219975696, 15490.30293636789, 15718.528444146694, 654160.0, 663456.0, 664317.0, 664383.0, 665451.0, 46178.53821461742, 48013.5198648016, 56278.71860640671, 56685.0, 57476.71261643665, 1286.1575667937516, 1328.3291937571464, 1335.3857225676297, 1339.2500199888093, 1339.484298545067, 28515.857420712764, 29129.56305655459, 29198.496180458482, 29916.70083299226, 29978.85010574933, 44165.337519936584, 49893.75053124711, 49996.0, 50044.49955500398, 50608.49391506185, 550713.203641762, 551475.0, 554845.0, 555807.0, 555882.0, 26812.731872681805, 26948.865255673598, 26995.0, 27338.72661273362, 27411.84763752194, 45698.0, 45885.0, 46633.83415414642, 46652.300215496114, 47309.0, 37633.2473350537, 37860.62139378681, 38245.61754382532, 38324.0, 39054.2189156221, 37116.0, 37608.0, 37976.81011595035, 38334.233315334095, 38589.6141038586, 29664.0, 32676.673233267364, 37770.62229377671, 37933.81033094817, 38695.80652096721, 32376.676233237366, 33866.322673545896, 33876.98369048932, 34045.0, 34056.65943340535, 22894.74078925675, 24193.879030604734, 24645.01419943254, 25323.746762532875, 25946.35134121662], "value": 18200.056516586246, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.78561291998966, 362.70077186321294, 365.90486473516887, 377.47907887115775, 390.01910195857425, 18930.05349732515, 19200.135993880274, 19799.604007919832, 20434.284800032, 20675.0, 16559.66880662389, 20165.59668806623, 20273.695894561555, 20622.896885515587, 20741.17035318589, 5799.333076696171, 5825.796097136601, 5993.430624090726, 6080.847978800522, 6100.145979562864, 5860.296764388258, 5984.760609575612, 6015.608985415934, 6021.0, 6029.306629737593, 5722.284714410699, 5926.466618004379, 5962.135490353896, 5977.641341519523, 5997.250343707036, 5939.524838012949, 5972.223610930595, 5984.73068711909, 5988.221531200916, 6014.3985601439945, 4903.117438861003, 5085.872853178663, 5129.307543481619, 5138.563222126124, 5177.637565370425, 24543.509129817303, 24629.50740985188, 24821.87589062043, 24894.253172404857, 25074.874625626937, 2172.424307558498, 2174.0760176924746, 2428.659987601737, 2583.3799888026924, 3078.7536997040183, 11592.188546801728, 14566.927165364212, 14742.484013059599, 14954.62613434662, 15683.137427441487, 1631.7552367144904, 1873.681474149398, 1907.4563749331512, 1923.2018712234405, 1928.9131989060527, 114642.0, 117097.0, 118062.409687951, 118241.0, 118652.81347186504, 10348.37909725404, 10452.529636166391, 10486.37081775096, 10550.419726915094, 10860.239783215098, 12150.845669661368, 13048.934755326163, 13110.541131060367, 13113.08208425401, 13126.146800458131, 7057.788266352018, 7786.637338465745, 7804.531728096333, 7877.606119694107, 8589.613467393981, 6920.5847649141215, 7905.142232486161, 8495.447795893371, 8520.318374530023, 8747.475151490931, 5507.284053073155, 6389.329120442287, 6472.579282346633, 6529.249136349381, 6534.869302613921, 1497.445945000357, 1548.8296287408375, 1848.1221419825745, 1849.398945342769, 1884.7738271407245, 781.2734157233828, 855.2858363266741, 883.7009595894026, 887.671561522241, 985.7683444390562, 16792.748108778378, 19989.60020799605, 21134.154633814782, 21142.682859757115, 21245.57508849845, 896.8162026125452, 909.4406939732017, 967.4243824924159, 968.5351031504918, 995.6558645828075, 9165.404248723946, 10235.692929211986, 11284.32294062359, 11292.37891915936, 11323.150763692756, 586750.0, 592387.0761292418, 600514.0, 601009.0, 601125.0, 75297.62351188318, 80853.38293234102, 84478.0, 106286.0, 115070.4246478779, 842.8735689646602, 885.9202671759573, 947.7156852944089, 1000.9199264058858, 1002.9749256268651, 15334.846651533342, 18705.158267877705, 19090.427287181406, 19116.852988820716, 19139.712904306725, 54239.45760542502, 54586.727066364416, 56404.0, 56754.0, 57346.0, 67614.64770704656, 77055.2294477048, 78454.0, 83270.16729832624, 83836.06573769888, 6459.354064593503, 7653.234676532302, 7931.087924888711, 8054.23484768946, 8059.637316320663, 11459.484323205643, 12353.876461235273, 12397.008239341012, 12604.180728252633, 12661.113722039738, 9288.674896378594, 10860.456977151269, 10898.836517452086, 11062.612808551663, 11063.889361106287, 9250.537473126182, 9647.276454265817, 9889.208863290776, 10589.099926506025, 10886.727831804268, 10914.612361557547, 10953.726156846142, 10998.0, 11042.558297668325, 11189.552417903518, 6260.843478913063, 6608.900866487103, 7720.8455830884195, 7854.882176767467, 7879.133295337394, 9734.0, 10529.262951593315, 10718.678439646823, 10923.453827308762, 10936.56253749841], "value": 9389.579674494276, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1477.7192333456642, 1520.7034628247488, 1698.8980661160324, 1807.4035568262475, 1828.3692126216451, 6571.572847764898, 6801.285864984167, 8356.206160414766, 9177.08229177082, 9213.0, 8530.786730331729, 8802.647894084244, 8832.20510154086, 8884.733457996253, 8974.237189838872, 2384.237044145874, 2411.9397015074674, 2422.7213870404953, 2432.525657496789, 2436.0864675746593, 2374.263978166774, 2414.915477958272, 2421.515696860626, 2423.660687503751, 2426.1751004658413, 2381.5475059738683, 2401.111588712179, 2401.979158857487, 2424.49085692004, 2430.3559556717482, 1924.7786504552016, 2254.819614430858, 2386.6658667786437, 2389.761023897605, 2395.700537432821, 2005.5086503806515, 2386.7851893329685, 2396.2451827674226, 2398.448356877928, 2401.171595799464, 8552.444091134119, 9024.23294020009, 9082.18260356571, 9084.364094513387, 9111.0, 4884.975575122137, 4931.926021109686, 5436.483534064258, 5440.292761940962, 5458.699771512565, 3252.317013427183, 3572.214112895159, 3629.256002519477, 3673.9081522961874, 3680.6687398134295, 978.3005151316771, 1042.275618445181, 1047.8061558611682, 1066.306900514664, 1072.4744875011265, 10230.744231394201, 11962.0, 11977.22148060382, 11998.52005919762, 12005.699857503632, 4717.127331443694, 4821.112909249347, 4825.420949486014, 4919.3358896549225, 4962.8511144665, 10135.290529663, 10246.026627470379, 10247.948760256151, 10259.435731034715, 10267.486625668836, 5459.3994660587305, 5469.781208751685, 5470.480304371078, 5476.561875049986, 5496.0931446310915, 4445.2887537993765, 4606.147862645421, 4791.257355109963, 4940.950590494121, 5001.8499445016705, 4008.939865902014, 4029.234445455354, 4122.711410201317, 4148.709590328708, 4153.397757325231, 1284.2487145020268, 1288.6134159752037, 1289.9097063205486, 1304.7455746129604, 1307.8888294494884, 764.8393837294119, 880.823835232956, 909.7225346269319, 922.9769255768525, 943.8018016216671, 5786.971065144648, 5893.764249429974, 5957.582969192202, 6092.72582733778, 6291.96854015727, 886.2378354615026, 914.721010091915, 916.5554705967601, 925.8564922437033, 989.8812142542942, 3849.7103470337192, 3924.156306394139, 3983.940240896389, 4078.632923036941, 4084.9795751021056, 10472.73818154537, 10925.399103049409, 10930.01629853317, 10939.179561532917, 10997.945010274898, 10875.83686244707, 10877.510512026978, 10886.510107045202, 10902.72743181411, 10962.725931851608, 8260.05009423924, 8376.49741015541, 8387.706430274911, 8394.0, 8395.454295470774, 4348.47818261798, 5796.739146738322, 5886.852828679146, 5891.646501209942, 6041.667708276087, 7926.722564710438, 8525.914740852511, 9864.901350986398, 10196.082352588302, 10348.793024139626, 7459.328660420588, 10112.191024717858, 10130.696079117639, 10397.220208484243, 10430.061294483532, 5532.474414930575, 7056.223815380406, 7265.818354541178, 7446.0, 7758.185390534139, 8277.0, 8422.157784221528, 8498.150184981452, 8550.1449855014, 8586.957065214634, 7433.8141546461775, 7564.357029652321, 7570.280823321776, 7573.4893174044955, 7658.0, 7283.781486555412, 7362.852742945218, 7402.407807375289, 7537.434692397982, 7586.203448637813, 6971.163460384786, 7089.82275443118, 7089.964550177217, 7140.321669441395, 7150.141982962078, 4932.8520144395725, 4950.034743225036, 5055.747212639427, 5114.360704911886, 5122.564582010571, 5026.949730502648, 5072.923906141337, 5136.434992150935, 5187.792488300426, 5208.322918020633], "value": 4601.0161345723545, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [266.0, 277.3081162499564, 296.09100062807175, 299.8455795265439, 313.41704429760654, 4662.766861656921, 4900.338454308668, 4933.802647894085, 5335.493128152819, 5531.806386776464, 5243.191099070824, 5337.386200586933, 5370.409254981959, 5474.439784661362, 5501.394846566885, 3584.677379035886, 4147.543770185277, 4196.181744559812, 4214.178235244129, 4261.190373828978, 3890.396988466778, 3892.6691231245377, 3978.7175580146495, 4197.349410841324, 4232.449781528397, 4127.339625659895, 4141.295979683461, 4150.958490415087, 4171.270027745132, 4181.435506206653, 3304.0, 3422.845971931269, 4156.397322388267, 4156.958430415687, 4187.1625674865145, 2964.4219377221452, 3577.5170352002406, 3589.8025608591515, 3595.712343012579, 3607.2244467439627, 5103.9489605103845, 5230.660007099565, 5449.291592093042, 5483.8080667176855, 5514.3658479275, 3970.3647416413237, 4129.876103716893, 4400.273954797487, 4617.822455273884, 4653.302004699322, 5847.064469684872, 5873.826684126858, 5878.794242201502, 5883.676397798119, 5903.704814759245, 3284.786488878215, 3665.193657395369, 3900.4929359183407, 3912.8239229234755, 3940.4732764176188, 6145.3547377525165, 6177.876442471171, 6223.782167624111, 6232.781852635181, 6296.779612713533, 3334.6498617644797, 3981.144054028398, 4004.5594984551676, 4014.8996275092773, 4020.3969404589548, 3306.8015919044933, 3668.3763760160837, 4021.1756589899, 4048.291548978917, 4080.8775736727944, 4743.5967942724565, 4776.904461910743, 4786.497417771154, 4790.712557246577, 4799.736014519165, 4969.925451118236, 5859.20900678403, 5892.381299963527, 5924.407559244128, 5992.880142397127, 1142.1548054439654, 1279.8080287956695, 1305.922613843583, 1354.6410201296665, 1376.9672745440919, 810.7838242636046, 825.5170725125748, 839.5508403004363, 845.2730651639584, 849.6049337058298, 3874.8643797466952, 4137.565555616678, 4140.834366625361, 4152.501699796044, 4301.773994411577, 4829.806807727651, 4996.602310428871, 5029.547340739351, 5042.9243561345875, 5082.33929589151, 4108.582539024032, 4551.522090180483, 4623.930641040387, 4628.421447319085, 4681.391419115494, 2441.7192022917593, 2493.314338556875, 2497.300755788382, 2609.569421045544, 2642.167717169086, 4247.830086796555, 4479.910401792011, 4597.678162528658, 5162.483751624807, 5290.550303224269, 4526.804499817586, 4606.539346065367, 5046.495350464924, 5055.800726683904, 5141.897162056738, 4299.828006879689, 4542.386777785023, 4566.744145360052, 5204.193350030751, 5289.0, 4517.774111294357, 4960.975195124001, 5028.597712182944, 5030.2957585937265, 5031.496850315086, 3927.9770917977835, 4122.91754164909, 4351.976331006097, 4927.433345165352, 4943.036107958914, 3736.7417528193155, 4302.0, 4465.553444655508, 4559.385825671071, 4995.925061124013, 2880.9135725928254, 3023.546468029725, 3056.617922759655, 3416.4192087344713, 3445.4315038018444, 4334.349847522897, 4547.931781023221, 4665.930011049769, 4983.807124865193, 5733.426657334233, 5267.471613625405, 5695.772169113189, 5722.856928576819, 5745.988903883607, 5767.250257466502, 4399.73601583906, 5140.324667862496, 5363.222332761806, 5900.940990590211, 5935.792247271324, 5442.9455705444025, 5728.942710573007, 5759.107338362561, 5764.694122351093, 5965.850853728692, 5184.444666599929, 5307.548858347083, 5589.524890384363, 5692.829215123553, 5791.333996590278, 5079.184186741047, 5265.60507961912, 5563.8887222256135, 5867.332916926921, 5926.466618004228], "value": 3909.6383709123675, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [587.6036508978107, 687.858988907274, 715.1739740599606, 717.2576383443137, 717.6902153569737, 15191.46829860955, 15657.530274091767, 16001.439949601767, 16054.678906421865, 16154.030758154518, 13782.173069615805, 14011.859881401182, 14029.92985035076, 14081.507147249848, 14093.083949543297, 671.4460570029745, 688.9724411023553, 843.182113350051, 859.4284800607588, 860.4708104515709, 833.5207255827893, 848.5502683577714, 853.3429259470204, 868.5700578213794, 870.0864092702668, 879.4283715584857, 880.8150288439435, 911.6080085563206, 912.8767616371771, 915.6108653822099, 897.8069715011271, 902.3548163063375, 902.5036230073433, 902.8916530016375, 903.8463461211544, 641.1568787045068, 651.5829868883918, 664.5979182594509, 670.9463242940553, 673.6766352150946, 14419.855801442061, 14771.482998095122, 14847.406103755837, 14912.254387280593, 14923.552293431214, 11882.762344753142, 14385.99298049137, 14427.0, 14610.977231593793, 14620.415183392652, 9681.042451470508, 11831.822522662167, 11894.464749086312, 11904.940475297655, 11945.653686365722, 3767.6797472214894, 3801.3347664158937, 3832.42513622956, 3843.577206507282, 3870.9032274193087, 110983.0, 113352.0, 116392.41803791012, 117858.0, 119646.80353196444, 10895.673129806119, 10976.23166378346, 11035.337879727242, 11052.336859788438, 11190.94404527972, 22575.0, 22596.209132680277, 22643.43391415228, 22644.773552264596, 22664.093436262367, 7966.044074710956, 8025.959870200729, 8055.275025247756, 8214.219649133323, 8324.209200125979, 4405.493368262627, 4438.101606279453, 4458.754768487699, 4477.328400739915, 4485.585245020985, 2804.4391121775725, 3168.8415579220946, 3721.6092310307567, 3726.571444283888, 3738.065483629093, 638.396715104222, 669.5514005616249, 674.7368526274756, 676.6447615002065, 681.1179522518252, 549.5274064304695, 590.7755053079802, 605.0923614578132, 615.4891440104702, 677.4275737002225, 9178.449293042439, 9380.486831604068, 9394.342396032212, 9484.0, 9647.951760241154, 517.2990597740084, 552.7264004317889, 580.407983856464, 603.806781829819, 643.3437893348759, 3917.490726205575, 4147.714208595346, 4539.0694907543875, 4665.626749860003, 4754.572088512051, 159755.20122399315, 166048.16975915042, 166363.0, 166577.0, 169689.0, 105301.47349263205, 117889.8211017896, 139750.3012484931, 144969.0, 147163.0, 117797.41101294439, 122041.0, 122445.99999999822, 123306.0, 124507.0, 8930.240929520804, 8955.238804701412, 9029.097090290918, 9035.02189178828, 9077.273818094669, 53809.192862107884, 62216.0, 63627.6818615904, 63694.0, 64726.89180426149, 94883.52558237164, 95864.52067739617, 97544.0, 100959.49520252351, 101979.49010254901, 11276.210665253353, 11506.0, 11562.710932226426, 11596.0, 11616.535338586362, 12943.546975856176, 13014.089013769324, 13099.148555343872, 13492.5277615283, 13493.122947008413, 11973.880261197277, 12014.279143251433, 12071.0, 12102.0, 12459.314737689152, 11936.0, 11995.220310679779, 12284.508619655471, 12515.37423128858, 12582.30797306157, 11698.883011169779, 12026.338551379758, 12142.757144856876, 12217.93891030539, 12322.445489952794, 10134.037266459674, 10240.897591023995, 10410.635627752992, 10465.953404659778, 10520.105791007543, 8835.425697329652, 9236.815263694823, 10261.74345641365, 10607.151427885596, 10683.412412317397], "value": 7668.057491433681, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1596.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 117.0, 118.0, 116.0, 118.0, 119.0, 118.0, 124.0, 172.0, 118.0], "value": 123.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 112.0, 112.0, 145.0, 117.0, 148.0, 144.0, 112.0, 160.0, 152.0], "value": 134.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [210.0, 243.0, 233.0, 235.0, 220.0, 202.0, 236.0, 256.0, 198.0, 205.0], "value": 223.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 58.0, 60.0, 85.0, 83.0, 68.0, 75.0, 84.0, 109.0, 86.0], "value": 79.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 73.0, 87.0, 74.0, 69.0, 72.0, 89.0, 78.0, 60.0, 92.0], "value": 78.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 101.0, 127.0, 121.0, 115.0, 81.0, 125.0, 74.0, 119.0, 91.0], "value": 104.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 117.0, 169.0, 162.0, 99.0, 112.0, 172.0, 165.0, 99.0, 98.0], "value": 133.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 95.0, 91.0, 74.0, 147.0, 143.0, 92.0, 143.0, 99.0, 81.0], "value": 105.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 59.0, 57.0, 59.0, 59.0, 58.0, 60.0, 58.0, 59.0, 57.0], "value": 58.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 75.0, 74.0, 85.0, 77.0, 69.0, 69.0, 70.0, 70.0, 70.0], "value": 72.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 91.0, 108.0, 106.0, 90.0, 86.0, 100.0, 87.0, 87.0, 87.0], "value": 92.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 142.0, 139.0, 140.0, 140.0, 150.0, 162.0, 156.0, 220.0, 141.0], "value": 153.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 171.0, 171.0, 174.0, 175.0, 174.0, 169.0, 174.0, 161.0, 157.0], "value": 170.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 66.0, 69.0, 68.0, 69.0, 68.0, 66.0, 66.0, 62.0], "value": 67.4, "unit": "score"}]}]} 21:15:05 INFO - Return code: 0 21:15:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:15:06 ERROR - # TBPL SUCCESS # 21:15:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:15:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:15:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:15:06 INFO - 2016-09-29 21:15:06.047 screenresolution[1680:10458] starting screenresolution argv=screenresolution get 21:15:06 INFO - 2016-09-29 21:15:06.049 screenresolution[1680:10458] Display 0: 1600x1200x32@60 21:15:06 INFO - 2016-09-29 21:15:06.055 screenresolution[1681:10461] starting screenresolution argv=screenresolution list 21:15:06 INFO - Available Modes on Display 0 21:15:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:15:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:15:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:15:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:15:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:15:06 INFO - Intel Iris: 21:15:06 INFO - Chipset Model: Intel Iris 21:15:06 INFO - Type: GPU 21:15:06 INFO - Bus: Built-In 21:15:06 INFO - VRAM (Dynamic, Max): 1536 MB 21:15:06 INFO - Vendor: Intel (0x8086) 21:15:06 INFO - Device ID: 0x0a2e 21:15:06 INFO - Revision ID: 0x0009 21:15:06 INFO - Displays: 21:15:06 INFO - Display: 21:15:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:15:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:15:06 INFO - Display Serial Number: 1600x1200 60 21:15:06 INFO - Main Display: Yes 21:15:06 INFO - Mirror: Off 21:15:06 INFO - Online: Yes 21:15:06 INFO - Rotation: Supported 21:15:06 INFO - Return code: 0 21:15:06 INFO - Running post-action listener: _resource_record_post_action 21:15:06 INFO - [mozharness: 2016-09-30 04:15:06.290171Z] Finished run-tests step (success) 21:15:06 INFO - Running post-run listener: _resource_record_post_run 21:15:06 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 38894080; Write bytes: 711444992; Read time: 377; Write time: 2055 21:15:06 INFO - TinderboxPrint: CPU usage
54.4% 21:15:06 INFO - TinderboxPrint: I/O read bytes / time
38,894,080 / 377 21:15:06 INFO - TinderboxPrint: I/O write bytes / time
711,444,992 / 2,055 21:15:06 INFO - TinderboxPrint: CPU idle
2,209.0 (45.6%) 21:15:06 INFO - TinderboxPrint: CPU system
980.2 (20.2%) 21:15:06 INFO - TinderboxPrint: CPU user
1,656.5 (34.2%) 21:15:06 INFO - TinderboxPrint: Swap in / out
447,139,840 / 0 21:15:06 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 400075264; Write bytes: 391890944; Read time: 29569; Write time: 717 21:15:06 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 36891136; Write bytes: 300671488; Read time: 356; Write time: 1296 21:15:06 INFO - Running post-run listener: _upload_blobber_files 21:15:06 INFO - Blob upload gear active. 21:15:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:15:06 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 21:15:06 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:15:06 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:15:07 INFO - (blobuploader) - INFO - Open directory for files ... 21:15:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:15:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:15:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:15:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:15:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:15:08 INFO - (blobuploader) - INFO - Done attempting. 21:15:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:15:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:15:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:15:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:15:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:15:09 INFO - (blobuploader) - INFO - Done attempting. 21:15:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:15:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:15:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:15:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:15:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:15:11 INFO - (blobuploader) - INFO - Done attempting. 21:15:11 INFO - (blobuploader) - INFO - Iteration through files over. 21:15:11 INFO - Return code: 0 21:15:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:15:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:15:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fd4eef1f4e8f06d481ad7726da03da0b16913f99cf31584f75200556ac16a5fe3118eec91f9abe2ff5652f857f579a5d4430e8d404a334e4d174620eb4728e5e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/47dbd42fd93d0f068f1976bd5c3771e2786550e0e74b3af02c09f50b1f52f80c1501f619e72f14e4c027b483843b4560dc097ac5cf738680a163b6d6a746ac94", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/63875b7b90a7c1810af7e889adebe7a2ad9a821c5883d4941c71882e3da0d0a4028110d8477794469789b6316db365439257f8566776f4bd2a981f2f6a360107"} 21:15:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:15:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:15:11 INFO - Contents: 21:15:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fd4eef1f4e8f06d481ad7726da03da0b16913f99cf31584f75200556ac16a5fe3118eec91f9abe2ff5652f857f579a5d4430e8d404a334e4d174620eb4728e5e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/47dbd42fd93d0f068f1976bd5c3771e2786550e0e74b3af02c09f50b1f52f80c1501f619e72f14e4c027b483843b4560dc097ac5cf738680a163b6d6a746ac94", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/63875b7b90a7c1810af7e889adebe7a2ad9a821c5883d4941c71882e3da0d0a4028110d8477794469789b6316db365439257f8566776f4bd2a981f2f6a360107"} 21:15:11 INFO - Running post-run listener: copy_logs_to_upload_dir 21:15:11 INFO - Copying logs to upload dir... 21:15:11 INFO - mkdir: /builds/slave/test/build/upload/logs 21:15:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.430765 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-09-29 21:15:11.483899) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-29 21:15:11.487987) ========= 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.6GPdEwv5b6/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.pNJ6w9EFas/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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/Fx-Team/sha512/fd4eef1f4e8f06d481ad7726da03da0b16913f99cf31584f75200556ac16a5fe3118eec91f9abe2ff5652f857f579a5d4430e8d404a334e4d174620eb4728e5e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/47dbd42fd93d0f068f1976bd5c3771e2786550e0e74b3af02c09f50b1f52f80c1501f619e72f14e4c027b483843b4560dc097ac5cf738680a163b6d6a746ac94", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/63875b7b90a7c1810af7e889adebe7a2ad9a821c5883d4941c71882e3da0d0a4028110d8477794469789b6316db365439257f8566776f4bd2a981f2f6a360107"} build_url:https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010957 build_url: 'https://queue.taskcluster.net/v1/task/KHDhPCWFRs6HnxKbt9n6Cg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fd4eef1f4e8f06d481ad7726da03da0b16913f99cf31584f75200556ac16a5fe3118eec91f9abe2ff5652f857f579a5d4430e8d404a334e4d174620eb4728e5e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/47dbd42fd93d0f068f1976bd5c3771e2786550e0e74b3af02c09f50b1f52f80c1501f619e72f14e4c027b483843b4560dc097ac5cf738680a163b6d6a746ac94", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/63875b7b90a7c1810af7e889adebe7a2ad9a821c5883d4941c71882e3da0d0a4028110d8477794469789b6316db365439257f8566776f4bd2a981f2f6a360107"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-29 21:15:11.527161) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 21:15:11.527698) ========= 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.6GPdEwv5b6/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.pNJ6w9EFas/Listeners TMPDIR=/var/folders/d3/4p5wz9j115375p40z2tqr49w00000w/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.005008 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 21:15:11.578648) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-29 21:15:11.579066) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-29 21:15:11.579486) ========= ========= Total master_lag: 0.17 =========