builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0305 starttime: 1478386151.42 results: success (0) buildid: 20161105134127 builduid: de2e991e1fcb4cacb45b73d916a105b4 revision: c44c01dfd264370c1558b747525d220a9a89b51c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-05 15:49:11.418962) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-05 15:49:11.419406) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-05 15:49:11.419700) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-05 15:49:11.585144) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 15:49:11.585506) ========= 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.lQWc5kemVm/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.ueEG24r0WA/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-05 15:49:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.8M=0.001s 2016-11-05 15:49:11 (18.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.155476 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 15:49:11.796686) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 15:49:11.797011) ========= 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.lQWc5kemVm/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.ueEG24r0WA/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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.024922 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 15:49:11.851447) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-05 15:49:11.851754) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c44c01dfd264370c1558b747525d220a9a89b51c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev c44c01dfd264370c1558b747525d220a9a89b51c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lQWc5kemVm/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.ueEG24r0WA/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-05 15:49:11,939 truncating revision to first 12 chars 2016-11-05 15:49:11,939 Setting DEBUG logging. 2016-11-05 15:49:11,939 attempt 1/10 2016-11-05 15:49:11,939 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c44c01dfd264?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-05 15:49:12,983 unpacking tar archive at: mozilla-central-c44c01dfd264/testing/mozharness/ program finished with exit code 0 elapsedTime=1.338189 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-05 15:49:13.213418) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-05 15:49:13.213772) ========= script_repo_revision: c44c01dfd264370c1558b747525d220a9a89b51c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-05 15:49:13.214239) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-05 15:49:13.214608) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-05 15:49:13.233191) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-05 15:49:13.233550) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lQWc5kemVm/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.ueEG24r0WA/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 15:49:13 INFO - MultiFileLogger online at 20161105 15:49:13 in /builds/slave/test 15:49:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 15:49:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:49:13 INFO - {'append_to_log': False, 15:49:13 INFO - 'base_work_dir': '/builds/slave/test', 15:49:13 INFO - 'blob_upload_branch': 'Firefox', 15:49:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:49:13 INFO - 'branch': 'Firefox', 15:49:13 INFO - 'buildbot_json_path': 'buildprops.json', 15:49:13 INFO - 'config_files': ('talos/mac_config.py',), 15:49:13 INFO - 'default_actions': ('clobber', 15:49:13 INFO - 'read-buildbot-config', 15:49:13 INFO - 'download-and-extract', 15:49:13 INFO - 'populate-webroot', 15:49:13 INFO - 'create-virtualenv', 15:49:13 INFO - 'install', 15:49:13 INFO - 'run-tests'), 15:49:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:49:13 INFO - 'download_minidump_stackwalk': True, 15:49:13 INFO - 'download_symbols': 'ondemand', 15:49:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:49:13 INFO - 'tooltool.py': '/tools/tooltool.py', 15:49:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:49:13 INFO - '/tools/misc-python/virtualenv.py')}, 15:49:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:49:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:49:13 INFO - 'installer_path': 'installer.exe', 15:49:13 INFO - 'log_level': 'info', 15:49:13 INFO - 'log_name': 'talos', 15:49:13 INFO - 'log_to_console': True, 15:49:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:49:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:49:13 INFO - 'opt_config_files': (), 15:49:13 INFO - 'pip_index': False, 15:49:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:49:13 INFO - 'cmd': ('bash', 15:49:13 INFO - '-c', 15:49:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:49:13 INFO - 'enabled': True, 15:49:13 INFO - 'halt_on_failure': False, 15:49:13 INFO - 'name': 'check_screen_resolution'},), 15:49:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:49:13 INFO - 'cmd': ('bash', 15:49:13 INFO - '-c', 15:49:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:49:13 INFO - 'enabled': True, 15:49:13 INFO - 'halt_on_failure': False, 15:49:13 INFO - 'name': 'check_screen_resolution'},), 15:49:13 INFO - 'run_cmd_checks_enabled': True, 15:49:13 INFO - 'sps_profile': False, 15:49:13 INFO - 'sps_profile_interval': 0, 15:49:13 INFO - 'suite': 'dromaeojs-e10s', 15:49:13 INFO - 'system_bits': '32', 15:49:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:49:13 INFO - 'title': 't-yosemite-r7-0305', 15:49:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:49:13 INFO - 'use_talos_json': True, 15:49:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:49:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:49:13 INFO - 'work_dir': 'build'} 15:49:13 INFO - [mozharness: 2016-11-05 22:49:13.439883Z] Running clobber step. 15:49:13 INFO - Running pre-action listener: _resource_record_pre_action 15:49:13 INFO - Running main action method: clobber 15:49:13 INFO - rmtree: /builds/slave/test/build 15:49:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:49:16 INFO - Running post-action listener: _resource_record_post_action 15:49:16 INFO - [mozharness: 2016-11-05 22:49:16.208150Z] Finished clobber step (success) 15:49:16 INFO - [mozharness: 2016-11-05 22:49:16.208278Z] Running read-buildbot-config step. 15:49:16 INFO - Running pre-action listener: _resource_record_pre_action 15:49:16 INFO - Running main action method: read_buildbot_config 15:49:16 INFO - Using buildbot properties: 15:49:16 INFO - { 15:49:16 INFO - "project": "", 15:49:16 INFO - "product": "firefox", 15:49:16 INFO - "script_repo_revision": "production", 15:49:16 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 15:49:16 INFO - "repository": "", 15:49:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 15:49:16 INFO - "buildid": "20161105134127", 15:49:16 INFO - "pgo_build": "False", 15:49:16 INFO - "basedir": "/builds/slave/test", 15:49:16 INFO - "buildnumber": 9, 15:49:16 INFO - "slavename": "t-yosemite-r7-0305", 15:49:16 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 15:49:16 INFO - "platform": "yosemite_r7", 15:49:16 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 15:49:16 INFO - "branch": "mozilla-central", 15:49:16 INFO - "repo_path": "mozilla-central", 15:49:16 INFO - "revision": "c44c01dfd264370c1558b747525d220a9a89b51c", 15:49:16 INFO - "stage_platform": "macosx64", 15:49:16 INFO - "builduid": "de2e991e1fcb4cacb45b73d916a105b4", 15:49:16 INFO - "slavebuilddir": "test" 15:49:16 INFO - } 15:49:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/INfzpT0AS7-0vIXWomzqIw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 15:49:16 INFO - Running post-action listener: _resource_record_post_action 15:49:16 INFO - [mozharness: 2016-11-05 22:49:16.211490Z] Finished read-buildbot-config step (success) 15:49:16 INFO - [mozharness: 2016-11-05 22:49:16.211609Z] Running download-and-extract step. 15:49:16 INFO - Running pre-action listener: _resource_record_pre_action 15:49:16 INFO - Running main action method: download_and_extract 15:49:16 INFO - mkdir: /builds/slave/test/build/tests 15:49:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:16 INFO - trying https://queue.taskcluster.net/v1/task/INfzpT0AS7-0vIXWomzqIw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 15:49:16 INFO - Downloading https://queue.taskcluster.net/v1/task/INfzpT0AS7-0vIXWomzqIw/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 15:49:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/INfzpT0AS7-0vIXWomzqIw/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 15:49:17 INFO - Downloaded 1199 bytes. 15:49:17 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 15:49:17 INFO - Using the following test package requirements: 15:49:17 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 15:49:17 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:49:17 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 15:49:17 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:49:17 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 15:49:17 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:49:17 INFO - u'jsshell-mac.zip'], 15:49:17 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:49:17 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 15:49:17 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 15:49:17 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:49:17 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 15:49:17 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:49:17 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 15:49:17 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:49:17 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 15:49:17 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 15:49:17 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 15:49:17 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:49:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/INfzpT0AS7-0vIXWomzqIw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 15:49:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/INfzpT0AS7-0vIXWomzqIw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:49:17 INFO - Fetch https://queue.taskcluster.net/v1/task/INfzpT0AS7-0vIXWomzqIw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 15:49:18 INFO - Http code: 200 15:49:18 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 15:49:18 INFO - x-amz-cf-id: VaZaPxuF003LP6J1_WvJNmtUEtezeiU4hGw5mT5WvjJVL7g8h6Q0ag== 15:49:18 INFO - x-amz-version-id: 0MN6xD_D8zimS2cr5WWYnFa_4QfGdmME 15:49:18 INFO - Expected file size: 16308056 15:49:18 INFO - Obtained file size: 16308056 15:49:25 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 15:49:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/INfzpT0AS7-0vIXWomzqIw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 15:49:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/INfzpT0AS7-0vIXWomzqIw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:49:25 INFO - Fetch https://queue.taskcluster.net/v1/task/INfzpT0AS7-0vIXWomzqIw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 15:49:25 INFO - Http code: 200 15:49:25 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 15:49:25 INFO - x-amz-cf-id: dEYdh4FmLNk5KpjhH00f-Q2GVMB75FdyRvb1RA14yLzgmMWGUoO4qw== 15:49:25 INFO - x-amz-version-id: 0MN6xD_D8zimS2cr5WWYnFa_4QfGdmME 15:49:26 INFO - Expected file size: 16308056 15:49:26 INFO - Obtained file size: 16308056 15:49:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/INfzpT0AS7-0vIXWomzqIw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 15:49:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/INfzpT0AS7-0vIXWomzqIw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:49:32 INFO - Fetch https://queue.taskcluster.net/v1/task/INfzpT0AS7-0vIXWomzqIw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 15:49:33 INFO - Http code: 200 15:49:33 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 15:49:33 INFO - x-amz-cf-id: n8j-NEbTJvVo1ljcU9-gXiIpMdEDikZ7fj2NbIaaj4jcFOHwyVLmeQ== 15:49:33 INFO - x-amz-version-id: 1pisJGBRsc6Q4RS04.PhRftvsd3EH8Iy 15:49:34 INFO - Expected file size: 14409038 15:49:34 INFO - Obtained file size: 14409038 15:49: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')]} 15:49:34 INFO - trying https://queue.taskcluster.net/v1/task/INfzpT0AS7-0vIXWomzqIw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:49:34 INFO - Downloading https://queue.taskcluster.net/v1/task/INfzpT0AS7-0vIXWomzqIw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 15:49:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/INfzpT0AS7-0vIXWomzqIw/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 15:49:37 INFO - Downloaded 111509225 bytes. 15:49:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/INfzpT0AS7-0vIXWomzqIw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:49:37 INFO - mkdir: /builds/slave/test/properties 15:49:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:49:37 INFO - Writing to file /builds/slave/test/properties/build_url 15:49:37 INFO - Contents: 15:49:37 INFO - build_url:https://queue.taskcluster.net/v1/task/INfzpT0AS7-0vIXWomzqIw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 15:49:38 INFO - Running post-action listener: _resource_record_post_action 15:49:38 INFO - Running post-action listener: set_extra_try_arguments 15:49:38 INFO - [mozharness: 2016-11-05 22:49:38.041586Z] Finished download-and-extract step (success) 15:49:38 INFO - [mozharness: 2016-11-05 22:49:38.041753Z] Running populate-webroot step. 15:49:38 INFO - Running pre-action listener: _resource_record_pre_action 15:49:38 INFO - Running main action method: populate_webroot 15:49:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:49:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:49:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:49:38 INFO - '--tppagecycles', 15:49:38 INFO - '7'], 15:49:38 INFO - 'tests': ['tsvgm']}}, 15:49:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:49:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:49:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:49:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:49:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:49:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:49:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:49:38 INFO - 'talos_options': ['--disable-e10s'], 15:49:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:49:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:49:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:49:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:49:38 INFO - 'talos_options': ['--disable-e10s'], 15:49:38 INFO - 'tests': ['damp', 'tps']}, 15:49:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:49:38 INFO - 'tests': ['damp', 'tps']}, 15:49:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:49:38 INFO - 'tests': ['dromaeo_dom']}, 15:49:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:49:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:49:38 INFO - 'tests': ['basic_compositor_video']}, 15:49:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:49:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:49:38 INFO - 'tests': ['a11yr', 15:49:38 INFO - 'ts_paint', 15:49:38 INFO - 'tpaint', 15:49:38 INFO - 'sessionrestore', 15:49:38 INFO - 'sessionrestore_no_auto_restore', 15:49:38 INFO - 'tabpaint']}, 15:49:38 INFO - 'other-e10s': {'tests': ['a11yr', 15:49:38 INFO - 'ts_paint', 15:49:38 INFO - 'tpaint', 15:49:38 INFO - 'sessionrestore', 15:49:38 INFO - 'sessionrestore_no_auto_restore', 15:49:38 INFO - 'tabpaint']}, 15:49:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:49:38 INFO - 'ts_paint', 15:49:38 INFO - 'tpaint', 15:49:38 INFO - 'sessionrestore', 15:49:38 INFO - 'sessionrestore_no_auto_restore', 15:49:38 INFO - 'tabpaint']}, 15:49:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:49:38 INFO - 'ts_paint', 15:49:38 INFO - 'tpaint', 15:49:38 INFO - 'sessionrestore', 15:49:38 INFO - 'sessionrestore_no_auto_restore', 15:49:38 INFO - 'tabpaint']}, 15:49:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:49:38 INFO - 'tests': ['a11yr', 15:49:38 INFO - 'ts_paint', 15:49:38 INFO - 'tpaint', 15:49:38 INFO - 'sessionrestore', 15:49:38 INFO - 'sessionrestore_no_auto_restore', 15:49:38 INFO - 'tabpaint']}, 15:49:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:49:38 INFO - 'tests': ['a11yr', 15:49:38 INFO - 'ts_paint', 15:49:38 INFO - 'tpaint', 15:49:38 INFO - 'sessionrestore', 15:49:38 INFO - 'sessionrestore_no_auto_restore', 15:49:38 INFO - 'tabpaint']}, 15:49:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:49:38 INFO - 'tests': ['tsvgx', 15:49:38 INFO - 'tsvgr_opacity', 15:49:38 INFO - 'tart', 15:49:38 INFO - 'tscrollx', 15:49:38 INFO - 'cart']}, 15:49:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:49:38 INFO - 'tsvgr_opacity', 15:49:38 INFO - 'tart', 15:49:38 INFO - 'tscrollx', 15:49:38 INFO - 'cart']}, 15:49:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:49:38 INFO - 'talos_options': ['--disable-e10s'], 15:49:38 INFO - 'tests': ['tp5o']}, 15:49:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:49:38 INFO - 'tests': ['tp5o']}, 15:49:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:49:38 INFO - 'talos_options': ['--disable-e10s', 15:49:38 INFO - '--xperf_path', 15:49:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:49:38 INFO - 'tests': ['tp5n']}, 15:49:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:49:38 INFO - 'talos_options': ['--xperf_path', 15:49:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:49:38 INFO - 'tests': ['tp5n']}}, 15:49:38 INFO - 'talos.zip': {'path': '', 15:49:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:49:38 INFO - Running post-action listener: _resource_record_post_action 15:49:38 INFO - [mozharness: 2016-11-05 22:49:38.060491Z] Finished populate-webroot step (success) 15:49:38 INFO - [mozharness: 2016-11-05 22:49:38.060618Z] Running create-virtualenv step. 15:49:38 INFO - Running pre-action listener: _resource_record_pre_action 15:49:38 INFO - Running main action method: create_virtualenv 15:49:38 INFO - Creating virtualenv /builds/slave/test/build/venv 15:49:38 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 15:49:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:49:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:49:38 INFO - Using real prefix '/tools/python27' 15:49:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:49:38 INFO - Installing distribute.............................................................................................................................................................................................done. 15:49:41 INFO - Installing pip.................done. 15:49:41 INFO - Return code: 0 15:49:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:49:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:49:42 INFO - Reading from file tmpfile_stdout 15:49:42 INFO - Output received: 15:49:42 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) 15:49:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:49:42 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')]} 15:49:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:42 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')]} 15:49:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:42 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 0x104d2c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ca2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc3a4aac90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b40d30>, '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 0x104966db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b38b20>, '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.lQWc5kemVm/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.ueEG24r0WA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 15:49:42 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 15:49:42 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 15:49:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lQWc5kemVm/Render', 15:49:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:49:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:49:42 INFO - 'HOME': '/Users/cltbld', 15:49:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:49:42 INFO - 'LOGNAME': 'cltbld', 15:49:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:42 INFO - 'MOZ_NO_REMOTE': '1', 15:49:42 INFO - 'NO_EM_RESTART': '1', 15:49:42 INFO - 'PAGER': '/bin/cat', 15:49:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:49:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:42 INFO - 'PWD': '/builds/slave/test', 15:49:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:49:42 INFO - 'SHELL': '/bin/bash', 15:49:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ueEG24r0WA/Listeners', 15:49:42 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 15:49:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:49:42 INFO - 'USER': 'cltbld', 15:49:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:49:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:49:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:42 INFO - 'XPC_FLAGS': '0x0', 15:49:42 INFO - 'XPC_SERVICE_NAME': '0', 15:49:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:49:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:42 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 15:49:42 INFO - Cleaning up... 15:49:42 INFO - Return code: 0 15:49:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:49:42 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')]} 15:49:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:42 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')]} 15:49:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:42 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 0x104d2c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ca2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc3a4aac90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b40d30>, '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 0x104966db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b38b20>, '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.lQWc5kemVm/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.ueEG24r0WA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 15:49:42 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 15:49:42 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 15:49:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lQWc5kemVm/Render', 15:49:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:49:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:49:42 INFO - 'HOME': '/Users/cltbld', 15:49:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:49:42 INFO - 'LOGNAME': 'cltbld', 15:49:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:42 INFO - 'MOZ_NO_REMOTE': '1', 15:49:42 INFO - 'NO_EM_RESTART': '1', 15:49:42 INFO - 'PAGER': '/bin/cat', 15:49:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:49:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:42 INFO - 'PWD': '/builds/slave/test', 15:49:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:49:42 INFO - 'SHELL': '/bin/bash', 15:49:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ueEG24r0WA/Listeners', 15:49:42 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 15:49:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:49:42 INFO - 'USER': 'cltbld', 15:49:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:49:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:49:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:42 INFO - 'XPC_FLAGS': '0x0', 15:49:42 INFO - 'XPC_SERVICE_NAME': '0', 15:49:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:49:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:42 INFO - Downloading/unpacking psutil>=3.1.1 15:49:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:49:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:49:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:49:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:49:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:49:46 INFO - Installing collected packages: psutil 15:49:46 INFO - Running setup.py install for psutil 15:49:46 INFO - building 'psutil._psutil_osx' extension 15:49:46 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 15:49:47 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 15:49:47 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 15:49:47 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 15:49:47 INFO - building 'psutil._psutil_posix' extension 15:49:47 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 15:49:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:49:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:49:47 INFO - ^ 15:49:47 INFO - 1 warning generated. 15:49:47 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 15:49:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:49:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:49:47 INFO - Successfully installed psutil 15:49:47 INFO - Cleaning up... 15:49:47 INFO - Return code: 0 15:49:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:49: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')]} 15:49:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49: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')]} 15:49:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:47 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 0x104d2c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ca2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc3a4aac90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b40d30>, '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 0x104966db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b38b20>, '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.lQWc5kemVm/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.ueEG24r0WA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 15:49:47 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 15:49:47 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 15:49:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lQWc5kemVm/Render', 15:49:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:49:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:49:47 INFO - 'HOME': '/Users/cltbld', 15:49:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:49:47 INFO - 'LOGNAME': 'cltbld', 15:49:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:47 INFO - 'MOZ_NO_REMOTE': '1', 15:49:47 INFO - 'NO_EM_RESTART': '1', 15:49:47 INFO - 'PAGER': '/bin/cat', 15:49:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:49:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:47 INFO - 'PWD': '/builds/slave/test', 15:49:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:49:47 INFO - 'SHELL': '/bin/bash', 15:49:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ueEG24r0WA/Listeners', 15:49:47 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 15:49:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:49:47 INFO - 'USER': 'cltbld', 15:49:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:49:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:49:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:47 INFO - 'XPC_FLAGS': '0x0', 15:49:47 INFO - 'XPC_SERVICE_NAME': '0', 15:49:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:49:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:49:47 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 15:49:47 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 15:49:47 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 15:49:47 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 15:49:47 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 15:49:47 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 15:49:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:49:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:49:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:49:50 INFO - Installing collected packages: mozsystemmonitor 15:49:50 INFO - Running setup.py install for mozsystemmonitor 15:49:50 INFO - Successfully installed mozsystemmonitor 15:49:50 INFO - Cleaning up... 15:49:50 INFO - Return code: 0 15:49:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:49:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:50 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 0x104d2c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ca2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc3a4aac90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b40d30>, '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 0x104966db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b38b20>, '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.lQWc5kemVm/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.ueEG24r0WA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 15:49:50 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 15:49:50 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 15:49:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lQWc5kemVm/Render', 15:49:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:49:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:49:50 INFO - 'HOME': '/Users/cltbld', 15:49:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:49:50 INFO - 'LOGNAME': 'cltbld', 15:49:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:50 INFO - 'MOZ_NO_REMOTE': '1', 15:49:50 INFO - 'NO_EM_RESTART': '1', 15:49:50 INFO - 'PAGER': '/bin/cat', 15:49:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:49:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:50 INFO - 'PWD': '/builds/slave/test', 15:49:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:49:50 INFO - 'SHELL': '/bin/bash', 15:49:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ueEG24r0WA/Listeners', 15:49:50 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 15:49:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:49:50 INFO - 'USER': 'cltbld', 15:49:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:49:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:49:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:50 INFO - 'XPC_FLAGS': '0x0', 15:49:50 INFO - 'XPC_SERVICE_NAME': '0', 15:49:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:49:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:50 INFO - Downloading/unpacking jsonschema==2.5.1 15:49:50 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 15:49:50 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 15:49:50 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 15:49:50 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 15:49:50 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 15:49:50 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 15:49:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:49:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:49:53 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 15:49:53 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 15:49:53 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 15:49:53 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 15:49:53 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 15:49:53 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 15:49:53 INFO - Downloading functools32-3.2.3-2.tar.gz 15:49:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:49:53 INFO - warning: no files found matching '*.txt' 15:49:53 INFO - no previously-included directories found matching 'build' 15:49:53 INFO - no previously-included directories found matching 'dist' 15:49:53 INFO - no previously-included directories found matching '.git*' 15:49:53 INFO - Installing collected packages: jsonschema, functools32 15:49:54 INFO - Running setup.py install for functools32 15:49:54 INFO - warning: no files found matching '*.txt' 15:49:54 INFO - no previously-included directories found matching 'build' 15:49:54 INFO - no previously-included directories found matching 'dist' 15:49:54 INFO - no previously-included directories found matching '.git*' 15:49:54 INFO - Successfully installed jsonschema functools32 15:49:54 INFO - Cleaning up... 15:49:54 INFO - Return code: 0 15:49:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:49:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49: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', '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 0x104d2c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ca2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc3a4aac90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b40d30>, '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 0x104966db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b38b20>, '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.lQWc5kemVm/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.ueEG24r0WA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 15:49: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:49: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 functools32==3.2.3-2 15:49:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lQWc5kemVm/Render', 15:49:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:49:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:49:54 INFO - 'HOME': '/Users/cltbld', 15:49:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:49:54 INFO - 'LOGNAME': 'cltbld', 15:49:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:54 INFO - 'MOZ_NO_REMOTE': '1', 15:49:54 INFO - 'NO_EM_RESTART': '1', 15:49:54 INFO - 'PAGER': '/bin/cat', 15:49:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:49:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:54 INFO - 'PWD': '/builds/slave/test', 15:49:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:49:54 INFO - 'SHELL': '/bin/bash', 15:49:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ueEG24r0WA/Listeners', 15:49:54 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 15:49:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:49:54 INFO - 'USER': 'cltbld', 15:49:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:49:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:49:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:54 INFO - 'XPC_FLAGS': '0x0', 15:49:54 INFO - 'XPC_SERVICE_NAME': '0', 15:49:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:49:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:49:54 INFO - Cleaning up... 15:49:54 INFO - Return code: 0 15:49:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:49:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:49:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49: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', '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 0x104d2c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ca2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc3a4aac90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b40d30>, '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 0x104966db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b38b20>, '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.lQWc5kemVm/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.ueEG24r0WA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 15:49: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:49: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 blobuploader==1.2.4 15:49:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lQWc5kemVm/Render', 15:49:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:49:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:49:54 INFO - 'HOME': '/Users/cltbld', 15:49:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:49:54 INFO - 'LOGNAME': 'cltbld', 15:49:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:54 INFO - 'MOZ_NO_REMOTE': '1', 15:49:54 INFO - 'NO_EM_RESTART': '1', 15:49:54 INFO - 'PAGER': '/bin/cat', 15:49:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:49:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:54 INFO - 'PWD': '/builds/slave/test', 15:49:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:49:54 INFO - 'SHELL': '/bin/bash', 15:49:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ueEG24r0WA/Listeners', 15:49:54 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 15:49:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:49:54 INFO - 'USER': 'cltbld', 15:49:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:49:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:49:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:54 INFO - 'XPC_FLAGS': '0x0', 15:49:54 INFO - 'XPC_SERVICE_NAME': '0', 15:49:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:49:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:54 INFO - Downloading/unpacking blobuploader==1.2.4 15:49:54 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 15:49:54 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 15:49:54 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 15:49:54 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 15:49:54 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 15:49:54 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 15:49:57 INFO - Downloading blobuploader-1.2.4.tar.gz 15:49:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:49:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:49:57 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 15:49:57 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 15:49:57 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 15:49:57 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 15:49:57 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 15:49:57 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 15:49:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:49:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:49:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:49:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:49:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:49:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:49:58 INFO - Downloading docopt-0.6.1.tar.gz 15:49:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:49:58 INFO - Installing collected packages: blobuploader, requests, docopt 15:49:58 INFO - Running setup.py install for blobuploader 15:49:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:49:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:49:58 INFO - Running setup.py install for requests 15:49:59 INFO - Running setup.py install for docopt 15:49:59 INFO - Successfully installed blobuploader requests docopt 15:49:59 INFO - Cleaning up... 15:49:59 INFO - Return code: 0 15:49:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:49:59 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')]} 15:49:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:49:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:59 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')]} 15:49:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:49:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:49:59 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 0x104d2c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ca2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc3a4aac90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b40d30>, '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 0x104966db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b38b20>, '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.lQWc5kemVm/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.ueEG24r0WA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 15:49:59 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 15:49:59 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 15:49:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lQWc5kemVm/Render', 15:49:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:49:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:49:59 INFO - 'HOME': '/Users/cltbld', 15:49:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:49:59 INFO - 'LOGNAME': 'cltbld', 15:49:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:49:59 INFO - 'MOZ_NO_REMOTE': '1', 15:49:59 INFO - 'NO_EM_RESTART': '1', 15:49:59 INFO - 'PAGER': '/bin/cat', 15:49:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:49:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:49:59 INFO - 'PWD': '/builds/slave/test', 15:49:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:49:59 INFO - 'SHELL': '/bin/bash', 15:49:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ueEG24r0WA/Listeners', 15:49:59 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 15:49:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:49:59 INFO - 'USER': 'cltbld', 15:49:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:49:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:49:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:49:59 INFO - 'XPC_FLAGS': '0x0', 15:49:59 INFO - 'XPC_SERVICE_NAME': '0', 15:49:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:49:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:49:59 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-YJWV9j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:49:59 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-EpGj6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:49:59 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-_u44RX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:49:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:49:59 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-hyzm8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:50:00 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-xvYe85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:50:00 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-sX28Ie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:50:00 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-Bc8Eda-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:50:00 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-FmmOjI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:50:00 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-fi75mo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:50:00 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-W3o0Ks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:50:00 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-mzOW0F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:50:00 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-ybo4Ov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:50:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:50:00 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-IG1kqp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:50:01 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-7ueDH6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:50:01 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-nOW5Gq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:50:01 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-PVXiSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:50:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:50:01 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-1wWnVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:50:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:50:01 INFO - Running setup.py install for manifestparser 15:50:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:50:01 INFO - Running setup.py install for mozcrash 15:50:01 INFO - Running setup.py install for mozdebug 15:50:01 INFO - Running setup.py install for mozdevice 15:50:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:50:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:50:02 INFO - Running setup.py install for mozfile 15:50:02 INFO - Running setup.py install for mozhttpd 15:50:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:50:02 INFO - Running setup.py install for mozinfo 15:50:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:50:02 INFO - Running setup.py install for mozInstall 15:50:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:50:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:50:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:50:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:50:02 INFO - Running setup.py install for mozleak 15:50:02 INFO - Running setup.py install for mozlog 15:50:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:50:02 INFO - Running setup.py install for moznetwork 15:50:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:50:03 INFO - Running setup.py install for mozprocess 15:50:03 INFO - Running setup.py install for mozprofile 15:50:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:50:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:50:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:50:03 INFO - Running setup.py install for mozrunner 15:50:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:50:03 INFO - Running setup.py install for mozscreenshot 15:50:03 INFO - Running setup.py install for moztest 15:50:03 INFO - Running setup.py install for mozversion 15:50:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:50:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:50:03 INFO - Cleaning up... 15:50:03 INFO - Return code: 0 15:50:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:50:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:03 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 0x104d2c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ca2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc3a4aac90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b40d30>, '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 0x104966db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b38b20>, '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.lQWc5kemVm/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.ueEG24r0WA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 15:50:03 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 15:50:03 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 15:50:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lQWc5kemVm/Render', 15:50:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:50:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:50:03 INFO - 'HOME': '/Users/cltbld', 15:50:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:50:03 INFO - 'LOGNAME': 'cltbld', 15:50:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:03 INFO - 'MOZ_NO_REMOTE': '1', 15:50:03 INFO - 'NO_EM_RESTART': '1', 15:50:03 INFO - 'PAGER': '/bin/cat', 15:50:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:50:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:03 INFO - 'PWD': '/builds/slave/test', 15:50:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:50:03 INFO - 'SHELL': '/bin/bash', 15:50:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ueEG24r0WA/Listeners', 15:50:03 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 15:50:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:50:03 INFO - 'USER': 'cltbld', 15:50:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:50:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:50:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:03 INFO - 'XPC_FLAGS': '0x0', 15:50:03 INFO - 'XPC_SERVICE_NAME': '0', 15:50:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:50:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:50:04 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-E2c4bv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:50:04 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)) 15:50:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:50:04 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-ZEPGk9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:50:04 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)) 15:50:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:50:04 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-EnZNhv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:50:04 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)) 15:50:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:50:04 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-GbIeZ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:50:04 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)) 15:50:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:50:04 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-5OlAd5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:50:04 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)) 15:50:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:50:04 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-vZxNYI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:50:04 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)) 15:50:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:50:04 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-3rfMTM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:50:05 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)) 15:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:50:05 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-CjNR2i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:50:05 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)) 15:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:50:05 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-G8wuf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:50:05 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)) 15:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:50:05 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-Ne7Fvt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:50:05 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)) 15:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:50:05 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-0Ji78B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:50:05 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)) 15:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:50:05 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-OIOevA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:50:05 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)) 15:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:50:05 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-Ac7hhf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:50:05 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)) 15:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:50:05 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-8ZDgjd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:50:05 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)) 15:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:50:05 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-Bl6TJb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:50:05 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)) 15:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:50:05 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-ipEzBI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:50:05 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)) 15:50:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:50:05 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-6km21S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:50:06 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)) 15:50:06 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)) 15:50:06 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)) 15:50:06 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)) 15:50:06 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)) 15:50:06 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)) 15:50:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:50:06 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 15:50:06 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 15:50:06 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 15:50:06 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 15:50:06 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 15:50:06 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 15:50:08 INFO - Downloading blessings-1.6.tar.gz 15:50:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:50:08 INFO - Installing collected packages: blessings 15:50:08 INFO - Running setup.py install for blessings 15:50:08 INFO - Successfully installed blessings 15:50:08 INFO - Cleaning up... 15:50:08 INFO - Return code: 0 15:50:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:50:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:50:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:50:09 INFO - Reading from file tmpfile_stdout 15:50:09 INFO - Current package versions: 15:50:09 INFO - blessings == 1.6 15:50:09 INFO - blobuploader == 1.2.4 15:50:09 INFO - docopt == 0.6.1 15:50:09 INFO - functools32 == 3.2.3-2 15:50:09 INFO - jsonschema == 2.5.1 15:50:09 INFO - manifestparser == 1.1 15:50:09 INFO - mozInstall == 1.12 15:50:09 INFO - mozcrash == 0.17 15:50:09 INFO - mozdebug == 0.1 15:50:09 INFO - mozdevice == 0.48 15:50:09 INFO - mozfile == 1.2 15:50:09 INFO - mozhttpd == 0.7 15:50:09 INFO - mozinfo == 0.9 15:50:09 INFO - mozleak == 0.1 15:50:09 INFO - mozlog == 3.4 15:50:09 INFO - moznetwork == 0.27 15:50:09 INFO - mozprocess == 0.23 15:50:09 INFO - mozprofile == 0.28 15:50:09 INFO - mozrunner == 6.12 15:50:09 INFO - mozscreenshot == 0.1 15:50:09 INFO - mozsystemmonitor == 0.3 15:50:09 INFO - moztest == 0.7 15:50:09 INFO - mozversion == 1.4 15:50:09 INFO - psutil == 3.1.1 15:50:09 INFO - requests == 1.2.3 15:50:09 INFO - wsgiref == 0.1.2 15:50:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:50:09 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')]} 15:50:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:09 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')]} 15:50:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:09 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 0x104d2c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ca2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc3a4aac90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b40d30>, '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 0x104966db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b38b20>, '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.lQWc5kemVm/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.ueEG24r0WA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 15:50:09 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 15:50:09 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 15:50:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lQWc5kemVm/Render', 15:50:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:50:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:50:09 INFO - 'HOME': '/Users/cltbld', 15:50:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:50:09 INFO - 'LOGNAME': 'cltbld', 15:50:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:09 INFO - 'MOZ_NO_REMOTE': '1', 15:50:09 INFO - 'NO_EM_RESTART': '1', 15:50:09 INFO - 'PAGER': '/bin/cat', 15:50:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:50:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:09 INFO - 'PWD': '/builds/slave/test', 15:50:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:50:09 INFO - 'SHELL': '/bin/bash', 15:50:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ueEG24r0WA/Listeners', 15:50:09 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 15:50:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:50:09 INFO - 'USER': 'cltbld', 15:50:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:50:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:50:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:09 INFO - 'XPC_FLAGS': '0x0', 15:50:09 INFO - 'XPC_SERVICE_NAME': '0', 15:50:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:50:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:09 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)) 15:50:09 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)) 15:50:09 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)) 15:50:09 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)) 15:50:09 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)) 15:50:09 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)) 15:50:09 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)) 15:50:09 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)) 15:50:09 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)) 15:50:09 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)) 15:50:09 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)) 15:50:09 INFO - Cleaning up... 15:50:09 INFO - Return code: 0 15:50:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:50:09 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')]} 15:50:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:09 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')]} 15:50:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:09 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 0x104d2c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ca2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc3a4aac90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b40d30>, '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 0x104966db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b38b20>, '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.lQWc5kemVm/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.ueEG24r0WA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 15:50:09 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 15:50:09 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 15:50:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lQWc5kemVm/Render', 15:50:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:50:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:50:09 INFO - 'HOME': '/Users/cltbld', 15:50:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:50:09 INFO - 'LOGNAME': 'cltbld', 15:50:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:09 INFO - 'MOZ_NO_REMOTE': '1', 15:50:09 INFO - 'NO_EM_RESTART': '1', 15:50:09 INFO - 'PAGER': '/bin/cat', 15:50:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:50:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:09 INFO - 'PWD': '/builds/slave/test', 15:50:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:50:09 INFO - 'SHELL': '/bin/bash', 15:50:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ueEG24r0WA/Listeners', 15:50:09 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 15:50:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:50:09 INFO - 'USER': 'cltbld', 15:50:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:50:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:50:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:09 INFO - 'XPC_FLAGS': '0x0', 15:50:09 INFO - 'XPC_SERVICE_NAME': '0', 15:50:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:50:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:50:09 INFO - Cleaning up... 15:50:09 INFO - Return code: 0 15:50:09 INFO - Running post-action listener: _resource_record_post_action 15:50:09 INFO - Running post-action listener: _start_resource_monitoring 15:50:09 INFO - Starting resource monitoring. 15:50:09 INFO - [mozharness: 2016-11-05 22:50:09.799700Z] Finished create-virtualenv step (success) 15:50:09 INFO - [mozharness: 2016-11-05 22:50:09.800704Z] Running install step. 15:50:09 INFO - Running pre-action listener: _resource_record_pre_action 15:50:09 INFO - Running main action method: install 15:50:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:50:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:50:10 INFO - Reading from file tmpfile_stdout 15:50:10 INFO - Detecting whether we're running mozinstall >=1.0... 15:50:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:50:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:50:10 INFO - Reading from file tmpfile_stdout 15:50:10 INFO - Output received: 15:50:10 INFO - Usage: mozinstall [options] installer 15:50:10 INFO - Options: 15:50:10 INFO - -h, --help show this help message and exit 15:50:10 INFO - -d DEST, --destination=DEST 15:50:10 INFO - Directory to install application into. [default: 15:50:10 INFO - "/builds/slave/test"] 15:50:10 INFO - --app=APP Application being installed. [default: firefox] 15:50:10 INFO - mkdir: /builds/slave/test/build/application 15:50:10 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'] 15:50:10 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 15:50:44 INFO - Reading from file tmpfile_stdout 15:50:44 INFO - Output received: 15:50:44 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:50:44 INFO - Running post-action listener: _resource_record_post_action 15:50:44 INFO - [mozharness: 2016-11-05 22:50:44.366485Z] Finished install step (success) 15:50:44 INFO - [mozharness: 2016-11-05 22:50:44.366636Z] Running run-tests step. 15:50:44 INFO - Running pre-action listener: _resource_record_pre_action 15:50:44 INFO - Running main action method: run_tests 15:50:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:50:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:50:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:50:44 INFO - 2016-11-05 15:50:44.380 screenresolution[1649:8317] starting screenresolution argv=screenresolution get 15:50:44 INFO - 2016-11-05 15:50:44.383 screenresolution[1649:8317] Display 0: 1600x1200x32@60 15:50:44 INFO - 2016-11-05 15:50:44.389 screenresolution[1650:8320] starting screenresolution argv=screenresolution list 15:50:44 INFO - Available Modes on Display 0 15:50:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:50:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:50:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:50:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:50:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:50:44 INFO - Intel Iris: 15:50:44 INFO - Chipset Model: Intel Iris 15:50:44 INFO - Type: GPU 15:50:44 INFO - Bus: Built-In 15:50:44 INFO - VRAM (Dynamic, Max): 1536 MB 15:50:44 INFO - Vendor: Intel (0x8086) 15:50:44 INFO - Device ID: 0x0a2e 15:50:44 INFO - Revision ID: 0x0009 15:50:44 INFO - Displays: 15:50:44 INFO - Display: 15:50:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:50:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:50:44 INFO - Display Serial Number: 1600x1200 60 15:50:44 INFO - Main Display: Yes 15:50:44 INFO - Mirror: Off 15:50:44 INFO - Online: Yes 15:50:44 INFO - Rotation: Supported 15:50:44 INFO - Return code: 0 15:50:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:50:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:50:44 INFO - Python 2.7.3 15:50:44 INFO - Return code: 0 15:50:44 INFO - grabbing minidump binary from tooltool 15:50:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:44 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 0x104b40d30>, '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 0x104966db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b38b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:50:44 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 15:50:44 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 15:50:44 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 15:50:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:50:44 INFO - Return code: 0 15:50:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:50:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:50:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:50:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:50:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:50:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/INfzpT0AS7-0vIXWomzqIw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0305', '--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 15:50:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/INfzpT0AS7-0vIXWomzqIw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0305 --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 15:50:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lQWc5kemVm/Render', 15:50:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:50:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:50:44 INFO - 'HOME': '/Users/cltbld', 15:50:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:50:44 INFO - 'LOGNAME': 'cltbld', 15:50:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:50:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:50:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:44 INFO - 'MOZ_NO_REMOTE': '1', 15:50:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:50:44 INFO - 'NO_EM_RESTART': '1', 15:50:44 INFO - 'PAGER': '/bin/cat', 15:50:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:50:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:44 INFO - 'PWD': '/builds/slave/test', 15:50:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:50:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:50:44 INFO - 'SHELL': '/bin/bash', 15:50:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ueEG24r0WA/Listeners', 15:50:44 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 15:50:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:50:44 INFO - 'USER': 'cltbld', 15:50:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:50:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:50:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:44 INFO - 'XPC_FLAGS': '0x0', 15:50:44 INFO - 'XPC_SERVICE_NAME': '0', 15:50:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:50:44 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/INfzpT0AS7-0vIXWomzqIw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0305', '--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 15:50:44 INFO - mozversion application_buildid: 20161105134127 15:50:44 INFO - mozversion application_changeset: c44c01dfd264370c1558b747525d220a9a89b51c 15:50:44 INFO - mozversion application_display_name: Nightly 15:50:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:50:44 INFO - mozversion application_name: Firefox 15:50:44 INFO - mozversion application_remotingname: firefox 15:50:44 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:50:44 INFO - mozversion application_vendor: Mozilla 15:50:44 INFO - mozversion application_version: 52.0a1 15:50:44 INFO - mozversion platform_buildid: 20161105134127 15:50:44 INFO - mozversion platform_changeset: c44c01dfd264370c1558b747525d220a9a89b51c 15:50:44 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:50:44 INFO - mozversion platform_version: 52.0a1 15:50:44 INFO - using testdate: 1478386244 15:50:44 INFO - actual date: 1478386244 15:50:44 INFO - starting webserver on 'localhost:49272' 15:50:44 INFO - SUITE-START | Running 2 tests 15:50:44 INFO - TEST-START | dromaeo_css 15:50:44 INFO - operating with platform_type : mac_ 15:50:44 INFO - Initialising browser for dromaeo_css test... 15:50:44 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/tmp5pKdY7/profile http://localhost:49272/getInfo.html) 15:50:47 INFO - PROCESS | 1660 | 2016-11-05 15:50:47.771 plugin-container[1663:8498] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a53, name = 'com.apple.tsm.portname' 15:50:47 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:50:47 INFO - PROCESS | 1660 | 2016-11-05 15:50:47.775 plugin-container[1663:8498] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9013, name = 'com.apple.CFPasteboardClient' 15:50:47 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:50:47 INFO - PROCESS | 1660 | 2016-11-05 15:50:47.775 plugin-container[1663:8498] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:50:47 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 15:50:47 INFO - PROCESS | 1660 | colorDepth:24 15:50:47 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 15:50:47 INFO - PROCESS | 1660 | __metrics 15:50:47 ERROR - PROCESS | 1660 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3158: TypeError: this.tabs is undefined 15:50:48 INFO - TEST-INFO | 1660: exit 0 15:50:48 INFO - Browser initialized. 15:50:48 INFO - Running cycle 1/1 for dromaeo_css test... 15:50:48 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/tmp5pKdY7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:50:50 INFO - PROCESS | 1664 | 2016-11-05 15:50:50.919 plugin-container[1665:8619] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 15:50:50 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:50:50 INFO - PROCESS | 1664 | 2016-11-05 15:50:50.920 plugin-container[1665:8619] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 15:50:50 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:50:50 INFO - PROCESS | 1664 | 2016-11-05 15:50:50.920 plugin-container[1665:8619] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:51:00 INFO - PROCESS | 1664 | RSS: Main: 238542848 15:51:00 INFO - PROCESS | 1664 | 15:51:50 INFO - PROCESS | 1664 | 1478386310200 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] 15:54:03 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 15:54:05 INFO - PROCESS | 1664 | RSS: Main: 254640128 15:54:05 INFO - PROCESS | 1664 | 15:57:07 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 15:57:07 INFO - PROCESS | 1664 | RSS: Main: 263647232 15:57:07 INFO - PROCESS | 1664 | 16:00:09 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 16:00:10 INFO - PROCESS | 1664 | RSS: Main: 268029952 16:00:10 INFO - PROCESS | 1664 | 16:03:12 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 16:03:13 INFO - PROCESS | 1664 | RSS: Main: 266633216 16:03:13 INFO - PROCESS | 1664 | 16:06:15 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 16:06:16 INFO - PROCESS | 1664 | RSS: Main: 271089664 16:06:16 INFO - PROCESS | 1664 | 16:09:19 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 16:09:19 INFO - PROCESS | 1664 | RSS: Main: 273842176 16:09:19 INFO - PROCESS | 1664 | 16:09:19 INFO - PROCESS | 1664 | __start_tp_report 16:09:19 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 16:09:19 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 16:09:19 INFO - PROCESS | 1664 | |i|pagename|runs| 16:09:19 INFO - PROCESS | 1664 | |0;dojo.html;2961.526155815069;3385.4075536781056;3387.424137896557;3441.587009558855;3454.3609432255034;4827.903441931159;5541.556675465963;5732.455416735414;5751.338596061444;5764.279465066867;5536.889262214763;5698.401667824871;5708.628939118955;5709.457601527848;5735.655860648364;5120.513551212638;5171.689698618076;5207.713575753332;5214.348206474191;5234.607404444663;5185.196294574347;5258.92111618326;5272.841814745564;5280.5247527722495;5303.602229832778;5236.031334203147;5264.078786212397;5300.946990530084;5301.469853014687;5314.813981510648;4555.931661025087;4706;5091.79632814687;5200.29795977542;5225.320708307896;5116.232565115225;5143.819966301162;5144.3312369392115;5159.303494028296;5182.559482443997;5484.314460692413;5538.086215774392;5679.659220446786;5700.942990570083;5709.800156994527;6040.305364883051;6071.058985857199;6107.7957376609465;6126.823043872694;6157.815265542041;6191.752329906798;6192.473639740628;6196.504279657662;6197.16338294329;6222.751089956441;5832.766689332464;5835;5846.678432686158;5848.795292164797;5857.560682948753;6125.785597504066;6132.263729246545;6139.6454669416935;6172.197614310201;6213.782517611952;5456.317960254968;5835.533157347402;6146.907796383058;6153.230846144232;6155.72299246535;6157.753689852445;6188.412100850412;6192.442680158807;6227.252729672468;6252.218472690914;6052.394760524;6119.938800612026;6128.938710612926;6130.3369889931655;6220.129181914535;6121.96939015311;6147.66187859663;6155.261368635793;6174.629522228681;6215.223097112861;4921.876953076202;4923.433805112386;4938.703677779345;4952.232403977389;4956.959038601862;4326.351047342924;4425.181341451842;4431.512533621299;4435.977820110878;4446.866594002185;5204.42751297357;5661.631993920382;5780.786034932711;5781.53747700183;5831.562632802556;5125.897482050338;6230.096635987847;6237.251529816451;6243.43809057178;6263.843403914848;4422.850058984623;5605.691686957175;5793.710314484259;5816.069428891357;5828.446297601721;4432.556744325606;4492.842750503717;5285.656432331886;5379.892402151935;5446.183072539072;5773.942260577341;6256.624602523863;6300.432961033529;6315.084312774714;6320.115183874262;5504.587155963239;6188.164597779242;6236.844078897882;6242.812715618538;6255.343188965093;5079.796808127633;6191.473724733448;6207.596506227081;6223.937760622336;6236.407541283571;6143.327652471189;6178.351273116438;6190.164327815687;6190.845228869314;6203.844903877439;5500.147477141049;5900.244813175366;6171.290301615371;6219.0982307564245;6222.751089956351;5897.439743224387;5983.880322393615;6102.877942441215;6114.57197996136;6135.969320153371;5941.881162376815;5963.254593175853;5980.700964951822;5997.580169388101;6083.604565703392;5624.634398764068;5849.502792262705;5892.587518873809;5900.1149827526215;5900.734466948912;5766.875459285399;5800.8259752207505;5962.672053037125;6089.238845144357;6112.266528016667;5900.321463031806;5923.111533270044;5925.644461332334;5960.672163030901;5961.701914904151 16:09:19 INFO - PROCESS | 1664 | |1;ext.html;485.84452975048;504.8914483386071;516.4087120247317;524.480764043597;526.2527211359869;37648.623513764855;39247.80376098119;39334;39516;41016.76949691507;35476.290474190566;36800.44799328005;39814;40334.79832600833;40351.79824100883;9171.082891710843;9338.159565639091;9353.204977576914;9354.81290374192;9407.20038796703;9297.767555811091;9308.581113849894;9371.484568348738;9374.578143983537;9403.529823508832;9337.253019758436;9372.906270937272;9389.718308450758;9398.201152901976;9407.2474202064;9317.44095354281;9395.671151509665;9408.623655053789;9416.952915235448;9417.858732119024;9156.404833685856;9182.08179182083;9234.168924796733;9247.6300947962;9273.768155796159;37979.430308545394;38257.617423825686;38306.42540361897;38313.23373532542;38358;3199.936001279985;3303.5540202072652;3324.634290228073;3677.558692956862;3896.493455850721;29427.11718648444;29839.403211935638;29955.25111872199;30541;30677.539836902466;3133.2480204750923;3157.763167762427;3181.904542863718;3199.8720051197924;3267.18320419895;465704;470294;470428;472289;472867;52261.73869130706;52267.73866130645;52347.73826130845;52517.7374113127;52554.73722631362;48749.51250487521;48888.75555622199;57131.42868571344;57630.4236957625;57686.71156644274;15188.01277916954;15231.695366092838;15260.694786104215;15393.769093463607;15449.691006180039;9771.853422198672;12028.939855300667;12077.09421793369;12082.214656047505;12135.029197664166;9632;13029.022823288291;13632.15965844618;14690.338934747962;14877.851221487646;3158.684131586823;3230.78999865008;4888.169011268093;5272.446393128743;6307.306196318401;845.6490556419017;931.855562387831;986.8963758805365;1007.5365331947386;1039.2569312941284;28319.858400707864;29237.415251694845;29350;29876.55185172224;30007.249818754706;1035.3270374256792;1039.5581877701945;1069.893010698924;1073.9194560408;1115.9218854680094;14439.566812995627;14822.184779836996;14889.776653350209;15162;15183.316750746242;641544;643906;649818;651304;652183;54174.45825541694;54638.72680636571;54803.72598137063;54809.72595136999;54975;1302.3618426970716;1329.7274058817918;1330.2284674888542;1357.2467280659132;1361.8229630148217;28286.292842679097;28703.282417939718;29107.981220657177;29376.41247175087;29449.705502944696;44117.77941110402;48283.551493455256;48307;48434;48447.5155248443;581439;584363;584694;586056.4880229172;587132;25218.873905630353;25354.366140846625;25671.87164064168;25690.871545642898;25769.613455798553;44092;44344.11311773811;44520.33219501775;45601;46082.53917460916;29752.702472974994;36983.6301636991;37602.81198594099;38421.807890960365;39210;30859.382812344076;32636.347273053932;33665;33866.49200261949;34134.487982679784;27938.441231174857;36325.273494529436;36753.89738307855;37038;37171.628283717895;32372.676273236964;32669.673303266663;33291.500627490124;33302.66697332996;33594.664053359156;19168.61662766765;22235;24678;25252.242432726314;25487.617685734356 16:09:19 INFO - PROCESS | 1664 | |2;jquery.html;365.4098630711401;367.23248410821395;372.4208855230117;379.546442001808;396.86506587760175;18846.34037808677;19336.90331548342;19529.902350488264;20071.698924516142;20288.98555072248;18163.910165390087;18936.147873345697;18948.957807320592;19559.924204168765;19565.315213967515;4146.3780432935;4997.800087996485;5150.7424628768595;5162.690238585678;5171.09505836479;5226.738663066851;5345.33183352081;5350.892982140354;5353.5984801139875;5354.170103633943;5334.759935802879;5351.892962140755;5355.303810504629;5356.9732151339185;5623.718814059281;5918.556108291874;6040.305364883051;6056.303525094627;6073.544484163684;6077.11881777142;5022.698638081727;5331.600129990227;5379.462053794628;5861.325947516048;5871.3541510433815;12991.935040324737;13216.86783132166;13235.933820330836;14600.70798584033;24313.756862431324;2089.0808044460387;2140.4220860367614;2160.962737885808;2165.512759629071;2445.193086281523;9827.4103553787;10260.333078349884;10384.221183411199;12273.079519036108;14425;1765.5762616971833;1868.6356160548698;1873.1851644534536;1884.2463014794055;1914.607505461377;115704;122578.38710806478;123189.38405308005;123758.76241237653;131078.54753798374;11185.94407027976;11377;11639.301641901513;11656.242344247597;11684.941575292069;14506.911981601423;14582.927085364505;14596.635084122769;14621.26893655313;14631.780523292158;6705.832354191184;6883.552569082994;7332.156801967735;8060.637271322687;8310.085890552034;6492.415682588589;7822.256885595859;8130.978842106824;8155.510669359857;8431.95784021076;4025.9331277211563;4535.9773201133785;4561.452625684873;4572.428446444194;4578.49636539987;1654.7186978213733;1940.8156225158586;1966.6263409952064;1980.3563841751488;1985.7120717495882;718.9180283673143;823.97940051498;891.3092353426017;910.3613495707832;950.5437390052675;19577.902110489642;19770;20053.298134565513;20623.865687387337;20638.174473021212;885.4819930340823;905.6875377994581;909.077286554148;916.812053529025;931.5575101826713;8804;10773.515191816387;10803.351798892092;10840.837387439195;10842.457877106113;531831;537858;571831;592116;595466;124834;125940.1108983366;126595.13834031529;128973;130497.34751326183;951.8048799995984;971.703630392737;992.9404235745878;1000.1898462245459;1002.0230275481493;18483.630327393646;18709.71935420997;18880.905595471933;18941.242350305834;19042.714359284346;51750;56053;57055;57290.713546432;58021.70989145027;52657;73996.2600374011;79024.60487697525;79037;81041.59479202802;6037.45662890342;7231.674574644048;7371.447141464519;8080.313173380328;8284.420090593841;11171.66485005451;11597.536098555964;11605;12420.875791241971;12983.026273029373;10596.629117980834;11061.115110790957;11147.72130696739;11286.379249141048;11288.604898828502;10285.74285642865;10470.685879423629;10701.411422371844;10823.945880270549;10979.28634638746;9142.862857057016;9317.39436936597;9979.800403991734;10122.544485498318;10946.890531094587;7289.416846652149;7615.504992175491;7924.286814186751;8182.936218291584;8253.587320633824;8701.782455438664;9016.737656727932;10860.511276992396;11150.944245278723;11227.66317010491 16:09:19 INFO - PROCESS | 1664 | |3;mootools.html;1338.3977210255387;1474.8451412601676;1648.958776030599;1772.167081471708;1837.3661086925006;7624.08510978682;7809.336206422461;8008.879866802003;8562.957185214073;8565.528895910722;7086.822829429267;8080.111187769356;8161.347092232622;8268.627911743972;8404;2305.0203663443053;2329.7087864017;2330.9650355244685;2332.556814205304;2339.7543257957923;2315.3169814904645;2349.9647505287435;2373.489699714561;2379.8096152307776;2382.7736365045293;2293.621552443845;2307.896144673485;2332.790048895599;2339.6256598944174;2346.6480027995767;2293.9655905161435;2294.598445272071;2303.1593468384085;2329.743728189898;2330.067972810872;2310.9422264443356;2333.871637059961;2335.7547457516885;2340.0873659272897;2348.8003519700846;8400.285975692073;8444.324454043723;8503.61733721984;8507.489550626982;8516.70191543293;5082.263071854597;5233.738313084331;5253.894922101575;5262.657927234717;5264.341957255343;2557.8465292082346;2931.516299810529;3385.2044769479153;3560.839762210707;3578.2664553766676;926.6432423516927;1055.3509591601187;1071.6463567022943;1085.625459216569;1107.595727559443;10707.839382409271;10710.982456666605;10713.250072494928;10842.566297348176;10883.23817332787;3717.4609681596016;3891.9610803892165;4429.911401771947;4499.190145773792;4499.6400287976885;9424.057594240521;9505.952470237742;9510.619575217052;9513.904860951441;9517.095875891917;5210.869728256824;5217.947820521822;5221.347331583552;5221.608379371486;5234.895302093937;4295.613394794484;4423.469183697976;4621.445426548836;4702.52974702527;4791.9936813269605;3671.761335513183;3749.981250093732;3796.259729352805;3831.272058308912;3840.983483671095;1045.8640376751125;1102.8199826186064;1228.3735294999615;1249.993750031244;1260.7478504299177;710.7561766907911;843.4522650935479;888.3736965439479;891.4963045879058;909.1499448015994;4585.449746030497;5584.776608935678;5825.097109947965;5825.242718446575;5828.76684932598;932.7481579973369;939.6006697153682;959.5154447004218;970.4807927758616;985.1872205430549;3639.1448009717697;3734.1971476132217;3798.9430158547643;3892.163184915257;3937.7637341759587;9861.063198996084;10315.690529284133;10348;10352.171826254033;10364;9671.274654400948;10189.286749927582;10280.434576098387;10290.073893349485;10300.124489418333;7697.4226932980255;7998.80002999918;8021.7994550135545;8042.356611471068;8052.114267430578;5471.6169868108855;5482.479164479528;5682.943170568242;5855.94144058554;5968.403159683997;8502.107278735644;8805.42764720291;9495.477748723886;9538;9579.568919398784;9438.48088355147;9490.762730931781;9617.807643847224;9688.12806847387;9762.75593110178;7095.361417472453;7249.710011599688;7299.197088320174;7364.41084713211;7421.332080112816;5926.4666180044005;6615.834604134935;7691.1539730628465;7918.089419716576;7968.920310796818;5891.882162356815;6865.382115609619;7065.752698655522;7072.575645461083;7235.674394652148;5754.5684073695475;6870.45036397077;6876.862462750617;6928.203256625553;6937.202221744564;6603.735850565923;6668.466522678271;6756.493263005196;6761.492888033319;6764.255931847394;4832.710037397767;4860.513948605111;4872.610191184625;4895.118878601744;4964.230544265644;4799.28010798383;4821.927671084866;4830.637702172281;4843.24929635907;4955.876103097451 16:09:19 INFO - PROCESS | 1664 | |4;prototype.html;298.72890849435646;300.1939791659393;301.45135852747995;308.72677680252974;314.7230437215252;5102.953894451638;5154.27840102385;5214.791408343662;5494.80768173114;5521.834344969648;4475.583185080053;4555.476120246164;4882.389701287339;5170.689758614486;5389.78440862365;4094.815733292009;4147;4148.937765933513;4156.605122513356;4179.352200408926;3109.937801243974;4147.523034851;4150.730202536841;4178.665706743468;4182.686298527623;3723.646253605903;3820.541535015788;4097.16008218316;4131.194417088669;4143.316352801799;3474.0569453670014;4047.1298670785923;4085.673146148316;4104.343305071189;4153.314703073989;3026.9848650756603;3052.900955655962;3462.9307413851834;3561.3589553880292;3600.9099772505633;4540.296254080623;4829.734364609945;4897.897972956056;5124.692518448905;5227.26818245446;3588.4258518636893;3831.3295173344554;4500.7299562026055;4536.364908912755;4810.2063159578975;4903.41159060915;4971;5674.3190817101795;5716.914246286309;5757.395473475309;3961.3859851723028;3984.820683069269;3990.700697447703;3993.520777506689;4000.159966407058;4985;5190.558802501791;5365.570754339644;5977.386105751466;6005.399460054004;3413.6857309935854;3925.941110883339;4063.7358571693335;4067.7152599317765;4080.000399902043;3455.94816077759;3711.1278849470355;4052.5339585947404;4076.6534844537955;4146.896327591834;4126.401671757579;4733.36099626546;4765.523447655207;4766.642501812378;4774.4509381421585;5579.6931168785295;5766.0016791269145;5801.3908539602735;5812.302523697099;5826.970865145647;1155.4973586489905;1187.7980743273665;1319.8482174549858;1340.7464021140265;1343.2813444806982;791.746641074862;888.6356593796512;919.2599957034652;924.8474001789764;927.698497988143;4343.391925130484;4610.354550362952;5098.694078355311;5160.7419629018395;5173.586113110942;5484.314460692413;5605.635633683797;5609.186667933127;5621.915671264935;5667.676252736358;4402.669799765031;4450.109978004412;4525.79633916468;5117.181250999822;5180.300659411007;2543.7074736405552;2763.350612606036;2824.322162680942;2934.618499595039;2951.4539810135193;4961.833969017278;5761.4526619971875;5799.5650326225705;5813.651180929158;5826.475617194473;5022.321986531844;5102.061216737616;5547.889042219133;5592.217089607459;5725.2232667156395;4746.477887432379;5097.8725531861255;5249.724027352487;5823.56323275756;5843.912341314969;5003.799848006039;5242.270050883123;5243.213517972335;5613.607047506635;5629.7748090077575;4555.749433781174;4745.430548334222;4827.730966445765;5138.691678499302;5200.817971370984;5138.48214374961;5218.095395180808;5545.805896793593;5555.833325000256;5824.242848429677;3483.345410929788;3490.016036289038;3861.682990205854;3891.708121890813;3941.6452519273403;4998.950220453746;5850.853728656818;5866.266716660418;5874.853128671817;5899.351071382059;4395.560443955579;4973.5523802857915;5053.646244762978;5414.642921388607;5699.2590963174525;4929.531694489017;5366.865828354166;5633.549316054788;5719.370869204301;5726.341470730919;4779.378680771617;5565.526930210977;5585.441455854382;5604.85987850307;5620.887582248414;5128.692278463304;5484.2596249505805;5723.828285151452;5752.906947679928;5787.739551720267;4510.406516253102;5580.2466666999435;5580.274564306615;5590.9592998556045;5596.7127560660465 16:09:19 INFO - PROCESS | 1664 | |5;yui.html;609.8810731907279;685.4687617096753;725.1986554856886;758.2947858491603;773.4856320546837;13294.7341053179;13991.950603704725;14121.717565648682;14175.71648567028;14462.192225971754;11064.782873883887;12221.144519883612;12648.177868438568;12722.218889355474;12724.363781810918;653.4674240493982;678.7183318922641;805.5408417202198;821.5317269156583;837.4054421360842;770.3336613829025;824.4929368438427;838.4256784102879;839.9412041157121;840.1094839470151;883.9292856571491;891.4873947480224;893.5889490834224;893.65147592439;894.995525022374;874.7900503879055;879.6305551668314;885.2784980241083;886.2024178239571;886.8315020146216;641.5990006246096;650.8958566629317;653.3270731146927;662.787907869482;662.8044726805604;14085.436582536686;14378.35297411619;14483;14493;14507.492237771732;13573.321333933263;13664.590062298048;13822;13997;14078.507252246121;10629.574817007311;10873.401962892038;11492.42537873103;11802;11804.881951180465;3615.114296997226;3704.944425833615;3720.5535335759882;3832.1186127190904;3832.616738326173;96325.51837240839;96962.0303796967;97900.72042435512;99109.01781964392;99231;10124.34191777532;10561.260711750258;10573.841392379121;10576.31253968505;10894.61868834587;20641.380758577267;20683.793162068487;20685.069171887302;20691;20729.79270207309;7671.846563068707;7671.961640191764;7686.385089192851;7692.384609231248;7753.806154846174;4204.180184863983;4245.363195520635;4302.161078589645;4321.546237645065;4448.488423831301;3249.3663735571336;3351.296227792191;3357.865685372558;3358.9160270992934;3365.3437579671727;552.6324993879106;592.9288485381782;614.2935624032309;628.6605233174034;634.058423241486;515.6210185513645;591.3967752892027;598.6826981699706;605.9426301104513;624.5752888036179;7111.395531379891;8435.493870367798;8914.821703565893;8986.32602554798;8992.145746154105;485.27936015017673;498.3197934818946;517.7023211653315;589.7379607639655;651.9771807986698;3601.657842504958;4350.260455722535;4362.21480133579;4373.693841431133;4441.689081764245;105933;106748;107185;108649.4567527173;112921;90049.54975225213;95977;98267.01732982579;98536.50731746295;99258;80106.19893801105;85374;86151.56924215339;86997.56501217453;88576.55711721528;7358.889616655862;7909.8813517796125;8568.35737319691;8654.177853103945;8684.869726953975;32527.349453010334;51995;53371;55613.44386556245;55720.72139639276;77634;78529.60735196287;78690.6065469669;80079.59960200162;82510.58744706238;10555.472226388803;10779.568817247222;10880.510377033212;11009.119270458184;11228.55085796559;11622.88377116218;11663.766724665289;11865.110116741107;11931.642050738144;12263.26420414778;11236.887631123584;11566.65300041;11849.763004739685;11871.109666775199;11906.464209110438;11182.32906025641;11408.543658253577;11676.065914726632;11698.35659038761;11979.760404791681;10898.673039808818;11084.61203857861;11356.148288878201;11475.196736228383;11503.424828758361;9403.952980235055;9459.290553208675;9542.379745316532;9711.271654626073;9746.122848943345;8616.698415555426;8697.782555436164;8905.643774248958;9705.6603018894;9738.805223895624 16:09:19 INFO - PROCESS | 1664 | __end_tp_report 16:09:19 INFO - PROCESS | 1664 | __start_cc_report 16:09:19 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,366 16:09:19 INFO - PROCESS | 1664 | __end_cc_report 16:09:19 INFO - PROCESS | 1664 | __startTimestamp1478387359458__endTimestamp 16:09:19 INFO - PROCESS | 1664 | 16:09:19 INFO - PROCESS | 1664 | ------- Summary: start ------- 16:09:19 INFO - PROCESS | 1664 | Number of tests: 6 16:09:19 INFO - PROCESS | 1664 | 16:09:19 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5650.50 Median:5846.68 stddev:652.78 (11.2%) stddev-sans-first:619.76 16:09:19 INFO - PROCESS | 1664 | Values: 2961.5 3385.4 3387.4 3441.6 3454.4 4827.9 5541.6 5732.5 5751.3 5764.3 5536.9 5698.4 5708.6 5709.5 5735.7 5120.5 5171.7 5207.7 5214.3 5234.6 5185.2 5258.9 5272.8 5280.5 5303.6 5236.0 5264.1 5300.9 5301.5 5314.8 4555.9 4706.0 5091.8 5200.3 5225.3 5116.2 5143.8 5144.3 5159.3 5182.6 5484.3 5538.1 5679.7 5700.9 5709.8 6040.3 6071.1 6107.8 6126.8 6157.8 6191.8 6192.5 6196.5 6197.2 6222.8 5832.8 5835.0 5846.7 5848.8 5857.6 6125.8 6132.3 6139.6 6172.2 6213.8 5456.3 5835.5 6146.9 6153.2 6155.7 6157.8 6188.4 6192.4 6227.3 6252.2 6052.4 6119.9 6128.9 6130.3 6220.1 6122.0 6147.7 6155.3 6174.6 6215.2 4921.9 4923.4 4938.7 4952.2 4957.0 4326.4 4425.2 4431.5 4436.0 4446.9 5204.4 5661.6 5780.8 5781.5 5831.6 5125.9 6230.1 6237.3 6243.4 6263.8 4422.9 5605.7 5793.7 5816.1 5828.4 4432.6 4492.8 5285.7 5379.9 5446.2 5773.9 6256.6 6300.4 6315.1 6320.1 5504.6 6188.2 6236.8 6242.8 6255.3 5079.8 6191.5 6207.6 6223.9 6236.4 6143.3 6178.4 6190.2 6190.8 6203.8 5500.1 5900.2 6171.3 6219.1 6222.8 5897.4 5983.9 6102.9 6114.6 6136.0 5941.9 5963.3 5980.7 5997.6 6083.6 5624.6 5849.5 5892.6 5900.1 5900.7 5766.9 5800.8 5962.7 6089.2 6112.3 5900.3 5923.1 5925.6 5960.7 5961.7 16:09:19 INFO - PROCESS | 1664 | 16:09:19 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:68447.87 Median:38335.62 stddev:153300.87 (399.9%) stddev-sans-first:153645.99 16:09:19 INFO - PROCESS | 1664 | Values: 485.8 504.9 516.4 524.5 526.3 37648.6 39247.8 39334.0 39516.0 41016.8 35476.3 36800.4 39814.0 40334.8 40351.8 9171.1 9338.2 9353.2 9354.8 9407.2 9297.8 9308.6 9371.5 9374.6 9403.5 9337.3 9372.9 9389.7 9398.2 9407.2 9317.4 9395.7 9408.6 9417.0 9417.9 9156.4 9182.1 9234.2 9247.6 9273.8 37979.4 38257.6 38306.4 38313.2 38358.0 3199.9 3303.6 3324.6 3677.6 3896.5 29427.1 29839.4 29955.3 30541.0 30677.5 3133.2 3157.8 3181.9 3199.9 3267.2 465704.0 470294.0 470428.0 472289.0 472867.0 52261.7 52267.7 52347.7 52517.7 52554.7 48749.5 48888.8 57131.4 57630.4 57686.7 15188.0 15231.7 15260.7 15393.8 15449.7 9771.9 12028.9 12077.1 12082.2 12135.0 9632.0 13029.0 13632.2 14690.3 14877.9 3158.7 3230.8 4888.2 5272.4 6307.3 845.6 931.9 986.9 1007.5 1039.3 28319.9 29237.4 29350.0 29876.6 30007.2 1035.3 1039.6 1069.9 1073.9 1115.9 14439.6 14822.2 14889.8 15162.0 15183.3 641544.0 643906.0 649818.0 651304.0 652183.0 54174.5 54638.7 54803.7 54809.7 54975.0 1302.4 1329.7 1330.2 1357.2 1361.8 28286.3 28703.3 29108.0 29376.4 29449.7 44117.8 48283.6 48307.0 48434.0 48447.5 581439.0 584363.0 584694.0 586056.5 587132.0 25218.9 25354.4 25671.9 25690.9 25769.6 44092.0 44344.1 44520.3 45601.0 46082.5 29752.7 36983.6 37602.8 38421.8 39210.0 30859.4 32636.3 33665.0 33866.5 34134.5 27938.4 36325.3 36753.9 37038.0 37171.6 32372.7 32669.7 33291.5 33302.7 33594.7 19168.6 22235.0 24678.0 25252.2 25487.6 16:09:19 INFO - PROCESS | 1664 | 16:09:19 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:33663.19 Median:366.32 stddev:95056.16 (25948.9%) stddev-sans-first:95289.93 16:09:19 INFO - PROCESS | 1664 | Values: 365.4 367.2 372.4 379.5 396.9 18846.3 19336.9 19529.9 20071.7 20289.0 18163.9 18936.1 18949.0 19559.9 19565.3 4146.4 4997.8 5150.7 5162.7 5171.1 5226.7 5345.3 5350.9 5353.6 5354.2 5334.8 5351.9 5355.3 5357.0 5623.7 5918.6 6040.3 6056.3 6073.5 6077.1 5022.7 5331.6 5379.5 5861.3 5871.4 12991.9 13216.9 13235.9 14600.7 24313.8 2089.1 2140.4 2161.0 2165.5 2445.2 9827.4 10260.3 10384.2 12273.1 14425.0 1765.6 1868.6 1873.2 1884.2 1914.6 115704.0 122578.4 123189.4 123758.8 131078.5 11185.9 11377.0 11639.3 11656.2 11684.9 14506.9 14582.9 14596.6 14621.3 14631.8 6705.8 6883.6 7332.2 8060.6 8310.1 6492.4 7822.3 8131.0 8155.5 8432.0 4025.9 4536.0 4561.5 4572.4 4578.5 1654.7 1940.8 1966.6 1980.4 1985.7 718.9 824.0 891.3 910.4 950.5 19577.9 19770.0 20053.3 20623.9 20638.2 885.5 905.7 909.1 916.8 931.6 8804.0 10773.5 10803.4 10840.8 10842.5 531831.0 537858.0 571831.0 592116.0 595466.0 124834.0 125940.1 126595.1 128973.0 130497.3 951.8 971.7 992.9 1000.2 1002.0 18483.6 18709.7 18880.9 18941.2 19042.7 51750.0 56053.0 57055.0 57290.7 58021.7 52657.0 73996.3 79024.6 79037.0 81041.6 6037.5 7231.7 7371.4 8080.3 8284.4 11171.7 11597.5 11605.0 12420.9 12983.0 10596.6 11061.1 11147.7 11286.4 11288.6 10285.7 10470.7 10701.4 10823.9 10979.3 9142.9 9317.4 9979.8 10122.5 10946.9 7289.4 7615.5 7924.3 8182.9 8253.6 8701.8 9016.7 10860.5 11150.9 11227.7 16:09:19 INFO - PROCESS | 1664 | 16:09:19 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5415.93 Median:5219.65 stddev:2989.62 (57.3%) stddev-sans-first:2982.30 16:09:19 INFO - PROCESS | 1664 | Values: 1338.4 1474.8 1649.0 1772.2 1837.4 7624.1 7809.3 8008.9 8563.0 8565.5 7086.8 8080.1 8161.3 8268.6 8404.0 2305.0 2329.7 2331.0 2332.6 2339.8 2315.3 2350.0 2373.5 2379.8 2382.8 2293.6 2307.9 2332.8 2339.6 2346.6 2294.0 2294.6 2303.2 2329.7 2330.1 2310.9 2333.9 2335.8 2340.1 2348.8 8400.3 8444.3 8503.6 8507.5 8516.7 5082.3 5233.7 5253.9 5262.7 5264.3 2557.8 2931.5 3385.2 3560.8 3578.3 926.6 1055.4 1071.6 1085.6 1107.6 10707.8 10711.0 10713.3 10842.6 10883.2 3717.5 3892.0 4429.9 4499.2 4499.6 9424.1 9506.0 9510.6 9513.9 9517.1 5210.9 5217.9 5221.3 5221.6 5234.9 4295.6 4423.5 4621.4 4702.5 4792.0 3671.8 3750.0 3796.3 3831.3 3841.0 1045.9 1102.8 1228.4 1250.0 1260.7 710.8 843.5 888.4 891.5 909.1 4585.4 5584.8 5825.1 5825.2 5828.8 932.7 939.6 959.5 970.5 985.2 3639.1 3734.2 3798.9 3892.2 3937.8 9861.1 10315.7 10348.0 10352.2 10364.0 9671.3 10189.3 10280.4 10290.1 10300.1 7697.4 7998.8 8021.8 8042.4 8052.1 5471.6 5482.5 5682.9 5855.9 5968.4 8502.1 8805.4 9495.5 9538.0 9579.6 9438.5 9490.8 9617.8 9688.1 9762.8 7095.4 7249.7 7299.2 7364.4 7421.3 5926.5 6615.8 7691.2 7918.1 7968.9 5891.9 6865.4 7065.8 7072.6 7235.7 5754.6 6870.5 6876.9 6928.2 6937.2 6603.7 6668.5 6756.5 6761.5 6764.3 4832.7 4860.5 4872.6 4895.1 4964.2 4799.3 4821.9 4830.6 4843.2 4955.9 16:09:19 INFO - PROCESS | 1664 | 16:09:19 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:4466.72 Median:4966.42 stddev:1364.97 (27.5%) stddev-sans-first:1332.47 16:09:19 INFO - PROCESS | 1664 | Values: 298.7 300.2 301.5 308.7 314.7 5103.0 5154.3 5214.8 5494.8 5521.8 4475.6 4555.5 4882.4 5170.7 5389.8 4094.8 4147.0 4148.9 4156.6 4179.4 3109.9 4147.5 4150.7 4178.7 4182.7 3723.6 3820.5 4097.2 4131.2 4143.3 3474.1 4047.1 4085.7 4104.3 4153.3 3027.0 3052.9 3462.9 3561.4 3600.9 4540.3 4829.7 4897.9 5124.7 5227.3 3588.4 3831.3 4500.7 4536.4 4810.2 4903.4 4971.0 5674.3 5716.9 5757.4 3961.4 3984.8 3990.7 3993.5 4000.2 4985.0 5190.6 5365.6 5977.4 6005.4 3413.7 3925.9 4063.7 4067.7 4080.0 3455.9 3711.1 4052.5 4076.7 4146.9 4126.4 4733.4 4765.5 4766.6 4774.5 5579.7 5766.0 5801.4 5812.3 5827.0 1155.5 1187.8 1319.8 1340.7 1343.3 791.7 888.6 919.3 924.8 927.7 4343.4 4610.4 5098.7 5160.7 5173.6 5484.3 5605.6 5609.2 5621.9 5667.7 4402.7 4450.1 4525.8 5117.2 5180.3 2543.7 2763.4 2824.3 2934.6 2951.5 4961.8 5761.5 5799.6 5813.7 5826.5 5022.3 5102.1 5547.9 5592.2 5725.2 4746.5 5097.9 5249.7 5823.6 5843.9 5003.8 5242.3 5243.2 5613.6 5629.8 4555.7 4745.4 4827.7 5138.7 5200.8 5138.5 5218.1 5545.8 5555.8 5824.2 3483.3 3490.0 3861.7 3891.7 3941.6 4999.0 5850.9 5866.3 5874.9 5899.4 4395.6 4973.6 5053.6 5414.6 5699.3 4929.5 5366.9 5633.5 5719.4 5726.3 4779.4 5565.5 5585.4 5604.9 5620.9 5128.7 5484.3 5723.8 5752.9 5787.7 4510.4 5580.2 5580.3 5591.0 5596.7 16:09:19 INFO - PROCESS | 1664 | 16:09:19 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:20470.35 Median:52683.00 stddev:30928.56 (58.7%) stddev-sans-first:30979.37 16:09:19 INFO - PROCESS | 1664 | Values: 609.9 685.5 725.2 758.3 773.5 13294.7 13992.0 14121.7 14175.7 14462.2 11064.8 12221.1 12648.2 12722.2 12724.4 653.5 678.7 805.5 821.5 837.4 770.3 824.5 838.4 839.9 840.1 883.9 891.5 893.6 893.7 895.0 874.8 879.6 885.3 886.2 886.8 641.6 650.9 653.3 662.8 662.8 14085.4 14378.4 14483.0 14493.0 14507.5 13573.3 13664.6 13822.0 13997.0 14078.5 10629.6 10873.4 11492.4 11802.0 11804.9 3615.1 3704.9 3720.6 3832.1 3832.6 96325.5 96962.0 97900.7 99109.0 99231.0 10124.3 10561.3 10573.8 10576.3 10894.6 20641.4 20683.8 20685.1 20691.0 20729.8 7671.8 7672.0 7686.4 7692.4 7753.8 4204.2 4245.4 4302.2 4321.5 4448.5 3249.4 3351.3 3357.9 3358.9 3365.3 552.6 592.9 614.3 628.7 634.1 515.6 591.4 598.7 605.9 624.6 7111.4 8435.5 8914.8 8986.3 8992.1 485.3 498.3 517.7 589.7 652.0 3601.7 4350.3 4362.2 4373.7 4441.7 105933.0 106748.0 107185.0 108649.5 112921.0 90049.5 95977.0 98267.0 98536.5 99258.0 80106.2 85374.0 86151.6 86997.6 88576.6 7358.9 7909.9 8568.4 8654.2 8684.9 32527.3 51995.0 53371.0 55613.4 55720.7 77634.0 78529.6 78690.6 80079.6 82510.6 10555.5 10779.6 10880.5 11009.1 11228.6 11622.9 11663.8 11865.1 11931.6 12263.3 11236.9 11566.7 11849.8 11871.1 11906.5 11182.3 11408.5 11676.1 11698.4 11979.8 10898.7 11084.6 11356.1 11475.2 11503.4 9404.0 9459.3 9542.4 9711.3 9746.1 8616.7 8697.8 8905.6 9705.7 9738.8 16:09:19 INFO - PROCESS | 1664 | -------- Summary: end -------- 16:09:19 INFO - PROCESS | 1664 | 16:09:20 INFO - TEST-INFO | 1664: exit 0 16:09:20 INFO - TEST-OK | dromaeo_css | took 1115146ms 16:09:20 INFO - TEST-START | kraken 16:09:20 INFO - operating with platform_type : mac_ 16:09:20 INFO - Initialising browser for kraken test... 16:09:20 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/tmpVoIcGP/profile http://localhost:49272/getInfo.html) 16:09:22 INFO - PROCESS | 1672 | 2016-11-05 16:09:22.683 plugin-container[1673:10786] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a43, name = 'com.apple.tsm.portname' 16:09:22 INFO - PROCESS | 1672 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:09:22 INFO - PROCESS | 1672 | 2016-11-05 16:09:22.686 plugin-container[1673:10786] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x440f, name = 'com.apple.CFPasteboardClient' 16:09:22 INFO - PROCESS | 1672 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:09:22 INFO - PROCESS | 1672 | 2016-11-05 16:09:22.686 plugin-container[1673:10786] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:09:22 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 16:09:22 INFO - PROCESS | 1672 | colorDepth:24 16:09:22 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 16:09:22 INFO - PROCESS | 1672 | __metrics 16:09:22 ERROR - PROCESS | 1672 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3158: TypeError: this.tabs is undefined 16:09:23 INFO - TEST-INFO | 1672: exit 0 16:09:23 INFO - Browser initialized. 16:09:23 INFO - Running cycle 1/1 for kraken test... 16:09:23 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/tmpVoIcGP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:09:25 INFO - PROCESS | 1674 | 2016-11-05 16:09:25.981 plugin-container[1675:10900] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 16:09:25 INFO - PROCESS | 1674 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:09:25 INFO - PROCESS | 1674 | 2016-11-05 16:09:25.982 plugin-container[1675:10900] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 16:09:25 INFO - PROCESS | 1674 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:09:25 INFO - PROCESS | 1674 | 2016-11-05 16:09:25.982 plugin-container[1675:10900] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:09:35 INFO - PROCESS | 1674 | RSS: Main: 238673920 16:09:35 INFO - PROCESS | 1674 | 16:09:38 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 16:09:39 INFO - PROCESS | 1674 | RSS: Main: 251338752 16:09:39 INFO - PROCESS | 1674 | 16:09:42 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 16:09:42 INFO - PROCESS | 1674 | RSS: Main: 258949120 16:09:42 INFO - PROCESS | 1674 | 16:09:45 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 16:09:46 INFO - PROCESS | 1674 | RSS: Main: 262000640 16:09:46 INFO - PROCESS | 1674 | 16:09:48 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 16:09:49 INFO - PROCESS | 1674 | RSS: Main: 263561216 16:09:49 INFO - PROCESS | 1674 | 16:09:51 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:09:51 INFO - PROCESS | 1674 | RSS: Main: 263938048 16:09:51 INFO - PROCESS | 1674 | 16:09:54 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 16:09:55 INFO - PROCESS | 1674 | RSS: Main: 264761344 16:09:55 INFO - PROCESS | 1674 | 16:09:58 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 16:09:58 INFO - PROCESS | 1674 | RSS: Main: 264642560 16:09:58 INFO - PROCESS | 1674 | 16:10:01 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 16:10:02 INFO - PROCESS | 1674 | RSS: Main: 265240576 16:10:02 INFO - PROCESS | 1674 | 16:10:04 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:10:04 INFO - PROCESS | 1674 | RSS: Main: 264835072 16:10:04 INFO - PROCESS | 1674 | 16:10:06 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:10:07 INFO - PROCESS | 1674 | RSS: Main: 264957952 16:10:07 INFO - PROCESS | 1674 | 16:10:09 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:10:10 INFO - PROCESS | 1674 | RSS: Main: 264986624 16:10:10 INFO - PROCESS | 1674 | 16:10:13 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:10:14 INFO - PROCESS | 1674 | RSS: Main: 265359360 16:10:14 INFO - PROCESS | 1674 | 16:10:17 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:10:17 INFO - PROCESS | 1674 | RSS: Main: 265371648 16:10:17 INFO - PROCESS | 1674 | 16:10:19 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 16:10:19 INFO - PROCESS | 1674 | RSS: Main: 266702848 16:10:19 INFO - PROCESS | 1674 | 16:10:19 INFO - PROCESS | 1674 | __start_tp_report 16:10:19 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 16:10:19 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 16:10:19 INFO - PROCESS | 1674 | |i|pagename|runs| 16:10:19 INFO - PROCESS | 1674 | |0;ai-astar;113;119;118;118;119;118;118;160;173;175 16:10:19 INFO - PROCESS | 1674 | |1;audio-beat-detection;186;120;115;162;145;139;118;113;133;155 16:10:19 INFO - PROCESS | 1674 | |2;audio-dft;197;207;203;207;195;193;196;193;205;212 16:10:19 INFO - PROCESS | 1674 | |3;audio-fft;81;61;73;112;84;85;83;77;79;60 16:10:19 INFO - PROCESS | 1674 | |4;audio-oscillator;90;70;93;103;100;83;87;70;71;88 16:10:19 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;89;87;88;85;88;85;103;133;88;87 16:10:19 INFO - PROCESS | 1674 | |6;imaging-darkroom;95;95;95;95;95;95;96;97;108;131 16:10:19 INFO - PROCESS | 1674 | |7;imaging-desaturate;136;141;97;137;89;88;91;93;101;90 16:10:19 INFO - PROCESS | 1674 | |8;json-parse-financial;57;57;56;63;71;72;48;71;76;77 16:10:19 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;67;78;67;83;65;97;65;77;107;86 16:10:19 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;92;80;87;112;113;109;85;92;89;93 16:10:19 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;161;150;133;161;165;167;142;139;275;170 16:10:19 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;235;181;176;189;162;219;177;176;174;175 16:10:19 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;73;89;88;92;91;83;69;69;66;69 16:10:19 INFO - PROCESS | 1674 | __end_tp_report 16:10:19 INFO - PROCESS | 1674 | __start_cc_report 16:10:19 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,952 16:10:19 INFO - PROCESS | 1674 | __end_cc_report 16:10:19 INFO - PROCESS | 1674 | __startTimestamp1478387419861__endTimestamp 16:10:19 INFO - PROCESS | 1674 | 16:10:19 INFO - PROCESS | 1674 | ------- Summary: start ------- 16:10:19 INFO - PROCESS | 1674 | Number of tests: 14 16:10:19 INFO - PROCESS | 1674 | 16:10:19 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:133.10 Median:119.00 stddev:25.35 (21.3%) stddev-sans-first:25.83 16:10:19 INFO - PROCESS | 1674 | Values: 113.0 119.0 118.0 118.0 119.0 118.0 118.0 160.0 173.0 175.0 16:10:19 INFO - PROCESS | 1674 | 16:10:19 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:138.60 Median:142.00 stddev:23.85 (16.8%) stddev-sans-first:18.10 16:10:19 INFO - PROCESS | 1674 | Values: 186.0 120.0 115.0 162.0 145.0 139.0 118.0 113.0 133.0 155.0 16:10:19 INFO - PROCESS | 1674 | 16:10:19 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:200.80 Median:204.00 stddev:6.81 (3.3%) stddev-sans-first:7.08 16:10:19 INFO - PROCESS | 1674 | Values: 197.0 207.0 203.0 207.0 195.0 193.0 196.0 193.0 205.0 212.0 16:10:19 INFO - PROCESS | 1674 | 16:10:19 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:79.50 Median:80.00 stddev:14.50 (18.1%) stddev-sans-first:15.37 16:10:19 INFO - PROCESS | 1674 | Values: 81.0 61.0 73.0 112.0 84.0 85.0 83.0 77.0 79.0 60.0 16:10:19 INFO - PROCESS | 1674 | 16:10:19 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:85.50 Median:85.00 stddev:12.01 (14.1%) stddev-sans-first:12.63 16:10:19 INFO - PROCESS | 1674 | Values: 90.0 70.0 93.0 103.0 100.0 83.0 87.0 70.0 71.0 88.0 16:10:19 INFO - PROCESS | 1674 | 16:10:19 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:93.30 Median:87.50 stddev:14.87 (17.0%) stddev-sans-first:15.69 16:10:19 INFO - PROCESS | 1674 | Values: 89.0 87.0 88.0 85.0 88.0 85.0 103.0 133.0 88.0 87.0 16:10:19 INFO - PROCESS | 1674 | 16:10:19 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:100.20 Median:95.00 stddev:11.55 (12.2%) stddev-sans-first:12.09 16:10:19 INFO - PROCESS | 1674 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 96.0 97.0 108.0 131.0 16:10:19 INFO - PROCESS | 1674 | 16:10:19 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:106.30 Median:89.50 stddev:22.25 (24.9%) stddev-sans-first:20.84 16:10:19 INFO - PROCESS | 1674 | Values: 136.0 141.0 97.0 137.0 89.0 88.0 91.0 93.0 101.0 90.0 16:10:19 INFO - PROCESS | 1674 | 16:10:19 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:64.80 Median:71.00 stddev:9.93 (14.0%) stddev-sans-first:10.12 16:10:19 INFO - PROCESS | 1674 | Values: 57.0 57.0 56.0 63.0 71.0 72.0 48.0 71.0 76.0 77.0 16:10:19 INFO - PROCESS | 1674 | 16:10:19 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:79.20 Median:77.50 stddev:14.37 (18.5%) stddev-sans-first:14.54 16:10:19 INFO - PROCESS | 1674 | Values: 67.0 78.0 67.0 83.0 65.0 97.0 65.0 77.0 107.0 86.0 16:10:19 INFO - PROCESS | 1674 | 16:10:19 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:95.20 Median:88.00 stddev:11.81 (13.4%) stddev-sans-first:12.47 16:10:19 INFO - PROCESS | 1674 | Values: 92.0 80.0 87.0 112.0 113.0 109.0 85.0 92.0 89.0 93.0 16:10:19 INFO - PROCESS | 1674 | 16:10:19 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:166.30 Median:163.00 stddev:40.27 (24.7%) stddev-sans-first:42.67 16:10:19 INFO - PROCESS | 1674 | Values: 161.0 150.0 133.0 161.0 165.0 167.0 142.0 139.0 275.0 170.0 16:10:19 INFO - PROCESS | 1674 | 16:10:19 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.40 Median:179.00 stddev:22.72 (12.7%) stddev-sans-first:15.89 16:10:19 INFO - PROCESS | 1674 | Values: 235.0 181.0 176.0 189.0 162.0 219.0 177.0 176.0 174.0 175.0 16:10:19 INFO - PROCESS | 1674 | 16:10:19 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.90 Median:85.50 stddev:10.62 (12.4%) stddev-sans-first:11.05 16:10:19 INFO - PROCESS | 1674 | Values: 73.0 89.0 88.0 92.0 91.0 83.0 69.0 69.0 66.0 69.0 16:10:19 INFO - PROCESS | 1674 | -------- Summary: end -------- 16:10:19 INFO - PROCESS | 1674 | 16:10:20 INFO - TEST-INFO | 1674: exit 0 16:10:20 INFO - TEST-OK | kraken | took 60567ms 16:10:20 INFO - SUITE-END | took 1175s 16:10:21 INFO - Completed test suite (00:19:36) 16:10:21 INFO - javascript benchmark 16:10:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7012.749450116851, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2961.526155815069, 3385.4075536781056, 3387.424137896557, 3441.587009558855, 3454.3609432255034, 4827.903441931159, 5541.556675465963, 5732.455416735414, 5751.338596061444, 5764.279465066867, 5536.889262214763, 5698.401667824871, 5708.628939118955, 5709.457601527848, 5735.655860648364, 5120.513551212638, 5171.689698618076, 5207.713575753332, 5214.348206474191, 5234.607404444663, 5185.196294574347, 5258.92111618326, 5272.841814745564, 5280.5247527722495, 5303.602229832778, 5236.031334203147, 5264.078786212397, 5300.946990530084, 5301.469853014687, 5314.813981510648, 4555.931661025087, 4706.0, 5091.79632814687, 5200.29795977542, 5225.320708307896, 5116.232565115225, 5143.819966301162, 5144.3312369392115, 5159.303494028296, 5182.559482443997, 5484.314460692413, 5538.086215774392, 5679.659220446786, 5700.942990570083, 5709.800156994527, 6040.305364883051, 6071.058985857199, 6107.7957376609465, 6126.823043872694, 6157.815265542041, 6191.752329906798, 6192.473639740628, 6196.504279657662, 6197.16338294329, 6222.751089956441, 5832.766689332464, 5835.0, 5846.678432686158, 5848.795292164797, 5857.560682948753, 6125.785597504066, 6132.263729246545, 6139.6454669416935, 6172.197614310201, 6213.782517611952, 5456.317960254968, 5835.533157347402, 6146.907796383058, 6153.230846144232, 6155.72299246535, 6157.753689852445, 6188.412100850412, 6192.442680158807, 6227.252729672468, 6252.218472690914, 6052.394760524, 6119.938800612026, 6128.938710612926, 6130.3369889931655, 6220.129181914535, 6121.96939015311, 6147.66187859663, 6155.261368635793, 6174.629522228681, 6215.223097112861, 4921.876953076202, 4923.433805112386, 4938.703677779345, 4952.232403977389, 4956.959038601862, 4326.351047342924, 4425.181341451842, 4431.512533621299, 4435.977820110878, 4446.866594002185, 5204.42751297357, 5661.631993920382, 5780.786034932711, 5781.53747700183, 5831.562632802556, 5125.897482050338, 6230.096635987847, 6237.251529816451, 6243.43809057178, 6263.843403914848, 4422.850058984623, 5605.691686957175, 5793.710314484259, 5816.069428891357, 5828.446297601721, 4432.556744325606, 4492.842750503717, 5285.656432331886, 5379.892402151935, 5446.183072539072, 5773.942260577341, 6256.624602523863, 6300.432961033529, 6315.084312774714, 6320.115183874262, 5504.587155963239, 6188.164597779242, 6236.844078897882, 6242.812715618538, 6255.343188965093, 5079.796808127633, 6191.473724733448, 6207.596506227081, 6223.937760622336, 6236.407541283571, 6143.327652471189, 6178.351273116438, 6190.164327815687, 6190.845228869314, 6203.844903877439, 5500.147477141049, 5900.244813175366, 6171.290301615371, 6219.0982307564245, 6222.751089956351, 5897.439743224387, 5983.880322393615, 6102.877942441215, 6114.57197996136, 6135.969320153371, 5941.881162376815, 5963.254593175853, 5980.700964951822, 5997.580169388101, 6083.604565703392, 5624.634398764068, 5849.502792262705, 5892.587518873809, 5900.1149827526215, 5900.734466948912, 5766.875459285399, 5800.8259752207505, 5962.672053037125, 6089.238845144357, 6112.266528016667, 5900.321463031806, 5923.111533270044, 5925.644461332334, 5960.672163030901, 5961.701914904151], "value": 5610.781039268494, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [485.84452975048, 504.8914483386071, 516.4087120247317, 524.480764043597, 526.2527211359869, 37648.623513764855, 39247.80376098119, 39334.0, 39516.0, 41016.76949691507, 35476.290474190566, 36800.44799328005, 39814.0, 40334.79832600833, 40351.79824100883, 9171.082891710843, 9338.159565639091, 9353.204977576914, 9354.81290374192, 9407.20038796703, 9297.767555811091, 9308.581113849894, 9371.484568348738, 9374.578143983537, 9403.529823508832, 9337.253019758436, 9372.906270937272, 9389.718308450758, 9398.201152901976, 9407.2474202064, 9317.44095354281, 9395.671151509665, 9408.623655053789, 9416.952915235448, 9417.858732119024, 9156.404833685856, 9182.08179182083, 9234.168924796733, 9247.6300947962, 9273.768155796159, 37979.430308545394, 38257.617423825686, 38306.42540361897, 38313.23373532542, 38358.0, 3199.936001279985, 3303.5540202072652, 3324.634290228073, 3677.558692956862, 3896.493455850721, 29427.11718648444, 29839.403211935638, 29955.25111872199, 30541.0, 30677.539836902466, 3133.2480204750923, 3157.763167762427, 3181.904542863718, 3199.8720051197924, 3267.18320419895, 465704.0, 470294.0, 470428.0, 472289.0, 472867.0, 52261.73869130706, 52267.73866130645, 52347.73826130845, 52517.7374113127, 52554.73722631362, 48749.51250487521, 48888.75555622199, 57131.42868571344, 57630.4236957625, 57686.71156644274, 15188.01277916954, 15231.695366092838, 15260.694786104215, 15393.769093463607, 15449.691006180039, 9771.853422198672, 12028.939855300667, 12077.09421793369, 12082.214656047505, 12135.029197664166, 9632.0, 13029.022823288291, 13632.15965844618, 14690.338934747962, 14877.851221487646, 3158.684131586823, 3230.78999865008, 4888.169011268093, 5272.446393128743, 6307.306196318401, 845.6490556419017, 931.855562387831, 986.8963758805365, 1007.5365331947386, 1039.2569312941284, 28319.858400707864, 29237.415251694845, 29350.0, 29876.55185172224, 30007.249818754706, 1035.3270374256792, 1039.5581877701945, 1069.893010698924, 1073.9194560408, 1115.9218854680094, 14439.566812995627, 14822.184779836996, 14889.776653350209, 15162.0, 15183.316750746242, 641544.0, 643906.0, 649818.0, 651304.0, 652183.0, 54174.45825541694, 54638.72680636571, 54803.72598137063, 54809.72595136999, 54975.0, 1302.3618426970716, 1329.7274058817918, 1330.2284674888542, 1357.2467280659132, 1361.8229630148217, 28286.292842679097, 28703.282417939718, 29107.981220657177, 29376.41247175087, 29449.705502944696, 44117.77941110402, 48283.551493455256, 48307.0, 48434.0, 48447.5155248443, 581439.0, 584363.0, 584694.0, 586056.4880229172, 587132.0, 25218.873905630353, 25354.366140846625, 25671.87164064168, 25690.871545642898, 25769.613455798553, 44092.0, 44344.11311773811, 44520.33219501775, 45601.0, 46082.53917460916, 29752.702472974994, 36983.6301636991, 37602.81198594099, 38421.807890960365, 39210.0, 30859.382812344076, 32636.347273053932, 33665.0, 33866.49200261949, 34134.487982679784, 27938.441231174857, 36325.273494529436, 36753.89738307855, 37038.0, 37171.628283717895, 32372.676273236964, 32669.673303266663, 33291.500627490124, 33302.66697332996, 33594.664053359156, 19168.61662766765, 22235.0, 24678.0, 25252.242432726314, 25487.617685734356], "value": 18725.627663507235, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.4098630711401, 367.23248410821395, 372.4208855230117, 379.546442001808, 396.86506587760175, 18846.34037808677, 19336.90331548342, 19529.902350488264, 20071.698924516142, 20288.98555072248, 18163.910165390087, 18936.147873345697, 18948.957807320592, 19559.924204168765, 19565.315213967515, 4146.3780432935, 4997.800087996485, 5150.7424628768595, 5162.690238585678, 5171.09505836479, 5226.738663066851, 5345.33183352081, 5350.892982140354, 5353.5984801139875, 5354.170103633943, 5334.759935802879, 5351.892962140755, 5355.303810504629, 5356.9732151339185, 5623.718814059281, 5918.556108291874, 6040.305364883051, 6056.303525094627, 6073.544484163684, 6077.11881777142, 5022.698638081727, 5331.600129990227, 5379.462053794628, 5861.325947516048, 5871.3541510433815, 12991.935040324737, 13216.86783132166, 13235.933820330836, 14600.70798584033, 24313.756862431324, 2089.0808044460387, 2140.4220860367614, 2160.962737885808, 2165.512759629071, 2445.193086281523, 9827.4103553787, 10260.333078349884, 10384.221183411199, 12273.079519036108, 14425.0, 1765.5762616971833, 1868.6356160548698, 1873.1851644534536, 1884.2463014794055, 1914.607505461377, 115704.0, 122578.38710806478, 123189.38405308005, 123758.76241237653, 131078.54753798374, 11185.94407027976, 11377.0, 11639.301641901513, 11656.242344247597, 11684.941575292069, 14506.911981601423, 14582.927085364505, 14596.635084122769, 14621.26893655313, 14631.780523292158, 6705.832354191184, 6883.552569082994, 7332.156801967735, 8060.637271322687, 8310.085890552034, 6492.415682588589, 7822.256885595859, 8130.978842106824, 8155.510669359857, 8431.95784021076, 4025.9331277211563, 4535.9773201133785, 4561.452625684873, 4572.428446444194, 4578.49636539987, 1654.7186978213733, 1940.8156225158586, 1966.6263409952064, 1980.3563841751488, 1985.7120717495882, 718.9180283673143, 823.97940051498, 891.3092353426017, 910.3613495707832, 950.5437390052675, 19577.902110489642, 19770.0, 20053.298134565513, 20623.865687387337, 20638.174473021212, 885.4819930340823, 905.6875377994581, 909.077286554148, 916.812053529025, 931.5575101826713, 8804.0, 10773.515191816387, 10803.351798892092, 10840.837387439195, 10842.457877106113, 531831.0, 537858.0, 571831.0, 592116.0, 595466.0, 124834.0, 125940.1108983366, 126595.13834031529, 128973.0, 130497.34751326183, 951.8048799995984, 971.703630392737, 992.9404235745878, 1000.1898462245459, 1002.0230275481493, 18483.630327393646, 18709.71935420997, 18880.905595471933, 18941.242350305834, 19042.714359284346, 51750.0, 56053.0, 57055.0, 57290.713546432, 58021.70989145027, 52657.0, 73996.2600374011, 79024.60487697525, 79037.0, 81041.59479202802, 6037.45662890342, 7231.674574644048, 7371.447141464519, 8080.313173380328, 8284.420090593841, 11171.66485005451, 11597.536098555964, 11605.0, 12420.875791241971, 12983.026273029373, 10596.629117980834, 11061.115110790957, 11147.72130696739, 11286.379249141048, 11288.604898828502, 10285.74285642865, 10470.685879423629, 10701.411422371844, 10823.945880270549, 10979.28634638746, 9142.862857057016, 9317.39436936597, 9979.800403991734, 10122.544485498318, 10946.890531094587, 7289.416846652149, 7615.504992175491, 7924.286814186751, 8182.936218291584, 8253.587320633824, 8701.782455438664, 9016.737656727932, 10860.511276992396, 11150.944245278723, 11227.66317010491], "value": 9124.565682205779, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1338.3977210255387, 1474.8451412601676, 1648.958776030599, 1772.167081471708, 1837.3661086925006, 7624.08510978682, 7809.336206422461, 8008.879866802003, 8562.957185214073, 8565.528895910722, 7086.822829429267, 8080.111187769356, 8161.347092232622, 8268.627911743972, 8404.0, 2305.0203663443053, 2329.7087864017, 2330.9650355244685, 2332.556814205304, 2339.7543257957923, 2315.3169814904645, 2349.9647505287435, 2373.489699714561, 2379.8096152307776, 2382.7736365045293, 2293.621552443845, 2307.896144673485, 2332.790048895599, 2339.6256598944174, 2346.6480027995767, 2293.9655905161435, 2294.598445272071, 2303.1593468384085, 2329.743728189898, 2330.067972810872, 2310.9422264443356, 2333.871637059961, 2335.7547457516885, 2340.0873659272897, 2348.8003519700846, 8400.285975692073, 8444.324454043723, 8503.61733721984, 8507.489550626982, 8516.70191543293, 5082.263071854597, 5233.738313084331, 5253.894922101575, 5262.657927234717, 5264.341957255343, 2557.8465292082346, 2931.516299810529, 3385.2044769479153, 3560.839762210707, 3578.2664553766676, 926.6432423516927, 1055.3509591601187, 1071.6463567022943, 1085.625459216569, 1107.595727559443, 10707.839382409271, 10710.982456666605, 10713.250072494928, 10842.566297348176, 10883.23817332787, 3717.4609681596016, 3891.9610803892165, 4429.911401771947, 4499.190145773792, 4499.6400287976885, 9424.057594240521, 9505.952470237742, 9510.619575217052, 9513.904860951441, 9517.095875891917, 5210.869728256824, 5217.947820521822, 5221.347331583552, 5221.608379371486, 5234.895302093937, 4295.613394794484, 4423.469183697976, 4621.445426548836, 4702.52974702527, 4791.9936813269605, 3671.761335513183, 3749.981250093732, 3796.259729352805, 3831.272058308912, 3840.983483671095, 1045.8640376751125, 1102.8199826186064, 1228.3735294999615, 1249.993750031244, 1260.7478504299177, 710.7561766907911, 843.4522650935479, 888.3736965439479, 891.4963045879058, 909.1499448015994, 4585.449746030497, 5584.776608935678, 5825.097109947965, 5825.242718446575, 5828.76684932598, 932.7481579973369, 939.6006697153682, 959.5154447004218, 970.4807927758616, 985.1872205430549, 3639.1448009717697, 3734.1971476132217, 3798.9430158547643, 3892.163184915257, 3937.7637341759587, 9861.063198996084, 10315.690529284133, 10348.0, 10352.171826254033, 10364.0, 9671.274654400948, 10189.286749927582, 10280.434576098387, 10290.073893349485, 10300.124489418333, 7697.4226932980255, 7998.80002999918, 8021.7994550135545, 8042.356611471068, 8052.114267430578, 5471.6169868108855, 5482.479164479528, 5682.943170568242, 5855.94144058554, 5968.403159683997, 8502.107278735644, 8805.42764720291, 9495.477748723886, 9538.0, 9579.568919398784, 9438.48088355147, 9490.762730931781, 9617.807643847224, 9688.12806847387, 9762.75593110178, 7095.361417472453, 7249.710011599688, 7299.197088320174, 7364.41084713211, 7421.332080112816, 5926.4666180044005, 6615.834604134935, 7691.1539730628465, 7918.089419716576, 7968.920310796818, 5891.882162356815, 6865.382115609619, 7065.752698655522, 7072.575645461083, 7235.674394652148, 5754.5684073695475, 6870.45036397077, 6876.862462750617, 6928.203256625553, 6937.202221744564, 6603.735850565923, 6668.466522678271, 6756.493263005196, 6761.492888033319, 6764.255931847394, 4832.710037397767, 4860.513948605111, 4872.610191184625, 4895.118878601744, 4964.230544265644, 4799.28010798383, 4821.927671084866, 4830.637702172281, 4843.24929635907, 4955.876103097451], "value": 4388.475489293019, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.72890849435646, 300.1939791659393, 301.45135852747995, 308.72677680252974, 314.7230437215252, 5102.953894451638, 5154.27840102385, 5214.791408343662, 5494.80768173114, 5521.834344969648, 4475.583185080053, 4555.476120246164, 4882.389701287339, 5170.689758614486, 5389.78440862365, 4094.815733292009, 4147.0, 4148.937765933513, 4156.605122513356, 4179.352200408926, 3109.937801243974, 4147.523034851, 4150.730202536841, 4178.665706743468, 4182.686298527623, 3723.646253605903, 3820.541535015788, 4097.16008218316, 4131.194417088669, 4143.316352801799, 3474.0569453670014, 4047.1298670785923, 4085.673146148316, 4104.343305071189, 4153.314703073989, 3026.9848650756603, 3052.900955655962, 3462.9307413851834, 3561.3589553880292, 3600.9099772505633, 4540.296254080623, 4829.734364609945, 4897.897972956056, 5124.692518448905, 5227.26818245446, 3588.4258518636893, 3831.3295173344554, 4500.7299562026055, 4536.364908912755, 4810.2063159578975, 4903.41159060915, 4971.0, 5674.3190817101795, 5716.914246286309, 5757.395473475309, 3961.3859851723028, 3984.820683069269, 3990.700697447703, 3993.520777506689, 4000.159966407058, 4985.0, 5190.558802501791, 5365.570754339644, 5977.386105751466, 6005.399460054004, 3413.6857309935854, 3925.941110883339, 4063.7358571693335, 4067.7152599317765, 4080.000399902043, 3455.94816077759, 3711.1278849470355, 4052.5339585947404, 4076.6534844537955, 4146.896327591834, 4126.401671757579, 4733.36099626546, 4765.523447655207, 4766.642501812378, 4774.4509381421585, 5579.6931168785295, 5766.0016791269145, 5801.3908539602735, 5812.302523697099, 5826.970865145647, 1155.4973586489905, 1187.7980743273665, 1319.8482174549858, 1340.7464021140265, 1343.2813444806982, 791.746641074862, 888.6356593796512, 919.2599957034652, 924.8474001789764, 927.698497988143, 4343.391925130484, 4610.354550362952, 5098.694078355311, 5160.7419629018395, 5173.586113110942, 5484.314460692413, 5605.635633683797, 5609.186667933127, 5621.915671264935, 5667.676252736358, 4402.669799765031, 4450.109978004412, 4525.79633916468, 5117.181250999822, 5180.300659411007, 2543.7074736405552, 2763.350612606036, 2824.322162680942, 2934.618499595039, 2951.4539810135193, 4961.833969017278, 5761.4526619971875, 5799.5650326225705, 5813.651180929158, 5826.475617194473, 5022.321986531844, 5102.061216737616, 5547.889042219133, 5592.217089607459, 5725.2232667156395, 4746.477887432379, 5097.8725531861255, 5249.724027352487, 5823.56323275756, 5843.912341314969, 5003.799848006039, 5242.270050883123, 5243.213517972335, 5613.607047506635, 5629.7748090077575, 4555.749433781174, 4745.430548334222, 4827.730966445765, 5138.691678499302, 5200.817971370984, 5138.48214374961, 5218.095395180808, 5545.805896793593, 5555.833325000256, 5824.242848429677, 3483.345410929788, 3490.016036289038, 3861.682990205854, 3891.708121890813, 3941.6452519273403, 4998.950220453746, 5850.853728656818, 5866.266716660418, 5874.853128671817, 5899.351071382059, 4395.560443955579, 4973.5523802857915, 5053.646244762978, 5414.642921388607, 5699.2590963174525, 4929.531694489017, 5366.865828354166, 5633.549316054788, 5719.370869204301, 5726.341470730919, 4779.378680771617, 5565.526930210977, 5585.441455854382, 5604.85987850307, 5620.887582248414, 5128.692278463304, 5484.2596249505805, 5723.828285151452, 5752.906947679928, 5787.739551720267, 4510.406516253102, 5580.2466666999435, 5580.274564306615, 5590.9592998556045, 5596.7127560660465], "value": 4033.499976965798, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [609.8810731907279, 685.4687617096753, 725.1986554856886, 758.2947858491603, 773.4856320546837, 13294.7341053179, 13991.950603704725, 14121.717565648682, 14175.71648567028, 14462.192225971754, 11064.782873883887, 12221.144519883612, 12648.177868438568, 12722.218889355474, 12724.363781810918, 653.4674240493982, 678.7183318922641, 805.5408417202198, 821.5317269156583, 837.4054421360842, 770.3336613829025, 824.4929368438427, 838.4256784102879, 839.9412041157121, 840.1094839470151, 883.9292856571491, 891.4873947480224, 893.5889490834224, 893.65147592439, 894.995525022374, 874.7900503879055, 879.6305551668314, 885.2784980241083, 886.2024178239571, 886.8315020146216, 641.5990006246096, 650.8958566629317, 653.3270731146927, 662.787907869482, 662.8044726805604, 14085.436582536686, 14378.35297411619, 14483.0, 14493.0, 14507.492237771732, 13573.321333933263, 13664.590062298048, 13822.0, 13997.0, 14078.507252246121, 10629.574817007311, 10873.401962892038, 11492.42537873103, 11802.0, 11804.881951180465, 3615.114296997226, 3704.944425833615, 3720.5535335759882, 3832.1186127190904, 3832.616738326173, 96325.51837240839, 96962.0303796967, 97900.72042435512, 99109.01781964392, 99231.0, 10124.34191777532, 10561.260711750258, 10573.841392379121, 10576.31253968505, 10894.61868834587, 20641.380758577267, 20683.793162068487, 20685.069171887302, 20691.0, 20729.79270207309, 7671.846563068707, 7671.961640191764, 7686.385089192851, 7692.384609231248, 7753.806154846174, 4204.180184863983, 4245.363195520635, 4302.161078589645, 4321.546237645065, 4448.488423831301, 3249.3663735571336, 3351.296227792191, 3357.865685372558, 3358.9160270992934, 3365.3437579671727, 552.6324993879106, 592.9288485381782, 614.2935624032309, 628.6605233174034, 634.058423241486, 515.6210185513645, 591.3967752892027, 598.6826981699706, 605.9426301104513, 624.5752888036179, 7111.395531379891, 8435.493870367798, 8914.821703565893, 8986.32602554798, 8992.145746154105, 485.27936015017673, 498.3197934818946, 517.7023211653315, 589.7379607639655, 651.9771807986698, 3601.657842504958, 4350.260455722535, 4362.21480133579, 4373.693841431133, 4441.689081764245, 105933.0, 106748.0, 107185.0, 108649.4567527173, 112921.0, 90049.54975225213, 95977.0, 98267.01732982579, 98536.50731746295, 99258.0, 80106.19893801105, 85374.0, 86151.56924215339, 86997.56501217453, 88576.55711721528, 7358.889616655862, 7909.8813517796125, 8568.35737319691, 8654.177853103945, 8684.869726953975, 32527.349453010334, 51995.0, 53371.0, 55613.44386556245, 55720.72139639276, 77634.0, 78529.60735196287, 78690.6065469669, 80079.59960200162, 82510.58744706238, 10555.472226388803, 10779.568817247222, 10880.510377033212, 11009.119270458184, 11228.55085796559, 11622.88377116218, 11663.766724665289, 11865.110116741107, 11931.642050738144, 12263.26420414778, 11236.887631123584, 11566.65300041, 11849.763004739685, 11871.109666775199, 11906.464209110438, 11182.32906025641, 11408.543658253577, 11676.065914726632, 11698.35659038761, 11979.760404791681, 10898.673039808818, 11084.61203857861, 11356.148288878201, 11475.196736228383, 11503.424828758361, 9403.952980235055, 9459.290553208675, 9542.379745316532, 9711.271654626073, 9746.122848943345, 8616.698415555426, 8697.782555436164, 8905.643774248958, 9705.6603018894, 9738.805223895624], "value": 7008.371074919385, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1608.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 119.0, 118.0, 118.0, 119.0, 118.0, 118.0, 160.0, 173.0, 175.0], "value": 133.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 120.0, 115.0, 162.0, 145.0, 139.0, 118.0, 113.0, 133.0, 155.0], "value": 138.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 207.0, 203.0, 207.0, 195.0, 193.0, 196.0, 193.0, 205.0, 212.0], "value": 200.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 61.0, 73.0, 112.0, 84.0, 85.0, 83.0, 77.0, 79.0, 60.0], "value": 79.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 93.0, 103.0, 100.0, 83.0, 87.0, 70.0, 71.0, 88.0], "value": 85.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 88.0, 85.0, 88.0, 85.0, 103.0, 133.0, 88.0, 87.0], "value": 93.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 97.0, 108.0, 131.0], "value": 100.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 141.0, 97.0, 137.0, 89.0, 88.0, 91.0, 93.0, 101.0, 90.0], "value": 106.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 56.0, 63.0, 71.0, 72.0, 48.0, 71.0, 76.0, 77.0], "value": 64.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 78.0, 67.0, 83.0, 65.0, 97.0, 65.0, 77.0, 107.0, 86.0], "value": 79.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 80.0, 87.0, 112.0, 113.0, 109.0, 85.0, 92.0, 89.0, 93.0], "value": 95.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 150.0, 133.0, 161.0, 165.0, 167.0, 142.0, 139.0, 275.0, 170.0], "value": 166.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [235.0, 181.0, 176.0, 189.0, 162.0, 219.0, 177.0, 176.0, 174.0, 175.0], "value": 186.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 89.0, 88.0, 92.0, 91.0, 83.0, 69.0, 69.0, 66.0, 69.0], "value": 78.9, "unit": "score"}]}]} 16:10:21 INFO - Return code: 0 16:10:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:10:21 ERROR - # TBPL SUCCESS # 16:10:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:10:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:10:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:10:21 INFO - 2016-11-05 16:10:21.193 screenresolution[1679:11281] starting screenresolution argv=screenresolution get 16:10:21 INFO - 2016-11-05 16:10:21.196 screenresolution[1679:11281] Display 0: 1600x1200x32@60 16:10:21 INFO - 2016-11-05 16:10:21.201 screenresolution[1680:11284] starting screenresolution argv=screenresolution list 16:10:21 INFO - Available Modes on Display 0 16:10:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:10:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:10:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:10:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:10:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:10:21 INFO - Intel Iris: 16:10:21 INFO - Chipset Model: Intel Iris 16:10:21 INFO - Type: GPU 16:10:21 INFO - Bus: Built-In 16:10:21 INFO - VRAM (Dynamic, Max): 1536 MB 16:10:21 INFO - Vendor: Intel (0x8086) 16:10:21 INFO - Device ID: 0x0a2e 16:10:21 INFO - Revision ID: 0x0009 16:10:21 INFO - Displays: 16:10:21 INFO - Display: 16:10:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:10:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:10:21 INFO - Display Serial Number: 1600x1200 60 16:10:21 INFO - Main Display: Yes 16:10:21 INFO - Mirror: Off 16:10:21 INFO - Online: Yes 16:10:21 INFO - Rotation: Supported 16:10:21 INFO - Return code: 0 16:10:21 INFO - Running post-action listener: _resource_record_post_action 16:10:21 INFO - [mozharness: 2016-11-05 23:10:21.435708Z] Finished run-tests step (success) 16:10:21 INFO - Running post-run listener: _resource_record_post_run 16:10:21 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 33198592; Write bytes: 709283840; Read time: 201; Write time: 2059 16:10:21 INFO - TinderboxPrint: CPU usage
54.4% 16:10:21 INFO - TinderboxPrint: I/O read bytes / time
33,198,592 / 201 16:10:21 INFO - TinderboxPrint: I/O write bytes / time
709,283,840 / 2,059 16:10:21 INFO - TinderboxPrint: CPU idle
2,207.3 (45.6%) 16:10:21 INFO - TinderboxPrint: CPU system
979.8 (20.2%) 16:10:21 INFO - TinderboxPrint: CPU user
1,658.7 (34.2%) 16:10:21 INFO - TinderboxPrint: Swap in / out
433,029,120 / 0 16:10:21 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 392428032; Write bytes: 386443264; Read time: 29080; Write time: 844 16:10:21 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 31302144; Write bytes: 302688256; Read time: 186; Write time: 1161 16:10:21 INFO - Running post-run listener: _upload_blobber_files 16:10:21 INFO - Blob upload gear active. 16:10:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:10:21 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 16:10:21 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:10:21 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:10:22 INFO - (blobuploader) - INFO - Open directory for files ... 16:10:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:10:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:10:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:10:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:10:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:10:23 INFO - (blobuploader) - INFO - Done attempting. 16:10:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:10:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:10:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:10:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:10:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:10:24 INFO - (blobuploader) - INFO - Done attempting. 16:10:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:10:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:10:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:10:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:10:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:10:26 INFO - (blobuploader) - INFO - Done attempting. 16:10:26 INFO - (blobuploader) - INFO - Iteration through files over. 16:10:26 INFO - Return code: 0 16:10:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:10:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:10:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/05a93459e8534cc3c6873a6214e62af633994daa20e63609a8e4b5aa876f6d0deb42f0357193c6491b8ea25e38be3a17769c174bbca2a02659e88b594a65b33c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/95663db3a20c83a9e116d913b9e90949dd35ae81f44515be21e14137413b79adb727b5b63b8e420e54eae145d2f96d50bd6fa531f651a5013feadc9d3f9d0305", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/192ccbc06f3bfda8626740b9a559def1ed5c5f1b57eba2c9a2e9673b959127a453df9aeee81aeb1df3410073dedf5d7bdc44f6fc0582d3f8af48f3ad6ce03d8c"} 16:10:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:10:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:10:26 INFO - Contents: 16:10:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/05a93459e8534cc3c6873a6214e62af633994daa20e63609a8e4b5aa876f6d0deb42f0357193c6491b8ea25e38be3a17769c174bbca2a02659e88b594a65b33c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/95663db3a20c83a9e116d913b9e90949dd35ae81f44515be21e14137413b79adb727b5b63b8e420e54eae145d2f96d50bd6fa531f651a5013feadc9d3f9d0305", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/192ccbc06f3bfda8626740b9a559def1ed5c5f1b57eba2c9a2e9673b959127a453df9aeee81aeb1df3410073dedf5d7bdc44f6fc0582d3f8af48f3ad6ce03d8c"} 16:10:26 INFO - Running post-run listener: copy_logs_to_upload_dir 16:10:26 INFO - Copying logs to upload dir... 16:10:26 INFO - mkdir: /builds/slave/test/build/upload/logs 16:10:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.402360 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-05 16:10:26.676481) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-05 16:10:26.680588) ========= 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.lQWc5kemVm/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.ueEG24r0WA/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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/Firefox/sha512/05a93459e8534cc3c6873a6214e62af633994daa20e63609a8e4b5aa876f6d0deb42f0357193c6491b8ea25e38be3a17769c174bbca2a02659e88b594a65b33c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/95663db3a20c83a9e116d913b9e90949dd35ae81f44515be21e14137413b79adb727b5b63b8e420e54eae145d2f96d50bd6fa531f651a5013feadc9d3f9d0305", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/192ccbc06f3bfda8626740b9a559def1ed5c5f1b57eba2c9a2e9673b959127a453df9aeee81aeb1df3410073dedf5d7bdc44f6fc0582d3f8af48f3ad6ce03d8c"} build_url:https://queue.taskcluster.net/v1/task/INfzpT0AS7-0vIXWomzqIw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010972 build_url: 'https://queue.taskcluster.net/v1/task/INfzpT0AS7-0vIXWomzqIw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/05a93459e8534cc3c6873a6214e62af633994daa20e63609a8e4b5aa876f6d0deb42f0357193c6491b8ea25e38be3a17769c174bbca2a02659e88b594a65b33c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/95663db3a20c83a9e116d913b9e90949dd35ae81f44515be21e14137413b79adb727b5b63b8e420e54eae145d2f96d50bd6fa531f651a5013feadc9d3f9d0305", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/192ccbc06f3bfda8626740b9a559def1ed5c5f1b57eba2c9a2e9673b959127a453df9aeee81aeb1df3410073dedf5d7bdc44f6fc0582d3f8af48f3ad6ce03d8c"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-05 16:10:26.719122) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:10:26.719651) ========= 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.lQWc5kemVm/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.ueEG24r0WA/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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.005123 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:10:26.768798) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-05 16:10:26.769124) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-05 16:10:26.769480) ========= ========= Total master_lag: 0.22 =========