builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0244 starttime: 1484857303.53 results: success (0) buildid: 20170119092828 builduid: 12faf1523a9b4ba3a1c7428280bceaa6 revision: 338a1630d1db0573f0ac96be426f5b6f5198d474 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-19 12:21:43.530124) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-19 12:21:43.530597) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-19 12:21:43.530874) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-19 12:21:43.555672) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 12:21:43.555981) ========= 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.yaw0vmisAo/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.FQaCmcBLi7/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-19 12:21:43-- 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% 62.4M=0s 2017-01-19 12:21:43 (62.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.134439 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 12:21:43.713916) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 12:21:43.714261) ========= 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.yaw0vmisAo/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.FQaCmcBLi7/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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.020624 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 12:21:43.763330) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-01-19 12:21:43.763721) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 338a1630d1db0573f0ac96be426f5b6f5198d474 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 338a1630d1db0573f0ac96be426f5b6f5198d474 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yaw0vmisAo/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.FQaCmcBLi7/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-19 12:21:43,861 truncating revision to first 12 chars 2017-01-19 12:21:43,861 Setting DEBUG logging. 2017-01-19 12:21:43,861 attempt 1/10 2017-01-19 12:21:43,861 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/338a1630d1db?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-19 12:21:45,367 unpacking tar archive at: mozilla-aurora-338a1630d1db/testing/mozharness/ program finished with exit code 0 elapsedTime=1.749032 ========= master_lag: 3.16 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-01-19 12:21:48.670937) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-19 12:21:48.671292) ========= script_repo_revision: 338a1630d1db0573f0ac96be426f5b6f5198d474 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-19 12:21:48.671783) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-19 12:21:48.672076) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-19 12:21:49.413982) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-01-19 12:21:49.414450) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yaw0vmisAo/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.FQaCmcBLi7/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 12:21:49 INFO - MultiFileLogger online at 20170119 12:21:49 in /builds/slave/test 12:21:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 12:21:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:21:49 INFO - {'append_to_log': False, 12:21:49 INFO - 'base_work_dir': '/builds/slave/test', 12:21:49 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:21:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:21:49 INFO - 'branch': 'Mozilla-Aurora', 12:21:49 INFO - 'buildbot_json_path': 'buildprops.json', 12:21:49 INFO - 'config_files': ('talos/mac_config.py',), 12:21:49 INFO - 'default_actions': ('clobber', 12:21:49 INFO - 'read-buildbot-config', 12:21:49 INFO - 'download-and-extract', 12:21:49 INFO - 'populate-webroot', 12:21:49 INFO - 'create-virtualenv', 12:21:49 INFO - 'install', 12:21:49 INFO - 'run-tests'), 12:21:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:21:49 INFO - 'download_minidump_stackwalk': True, 12:21:49 INFO - 'download_symbols': 'ondemand', 12:21:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:21:49 INFO - 'tooltool.py': '/tools/tooltool.py', 12:21:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:21:49 INFO - '/tools/misc-python/virtualenv.py')}, 12:21:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:21:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:21:49 INFO - 'installer_path': 'installer.exe', 12:21:49 INFO - 'log_level': 'info', 12:21:49 INFO - 'log_name': 'talos', 12:21:49 INFO - 'log_to_console': True, 12:21:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:21:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:21:49 INFO - 'opt_config_files': (), 12:21:49 INFO - 'pip_index': False, 12:21:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:21:49 INFO - 'cmd': ('bash', 12:21:49 INFO - '-c', 12:21:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:21:49 INFO - 'enabled': True, 12:21:49 INFO - 'halt_on_failure': False, 12:21:49 INFO - 'name': 'check_screen_resolution'},), 12:21:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:21:49 INFO - 'cmd': ('bash', 12:21:49 INFO - '-c', 12:21:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:21:49 INFO - 'enabled': True, 12:21:49 INFO - 'halt_on_failure': False, 12:21:49 INFO - 'name': 'check_screen_resolution'},), 12:21:49 INFO - 'run_cmd_checks_enabled': True, 12:21:49 INFO - 'sps_profile': False, 12:21:49 INFO - 'sps_profile_interval': 0, 12:21:49 INFO - 'suite': 'dromaeojs-e10s', 12:21:49 INFO - 'system_bits': '32', 12:21:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:21:49 INFO - 'title': 't-yosemite-r7-0244', 12:21:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:21:49 INFO - 'use_talos_json': True, 12:21:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:21:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:21:49 INFO - 'work_dir': 'build'} 12:21:49 INFO - [mozharness: 2017-01-19 20:21:49.633525Z] Running clobber step. 12:21:49 INFO - Running pre-action listener: _resource_record_pre_action 12:21:49 INFO - Running main action method: clobber 12:21:49 INFO - rmtree: /builds/slave/test/build 12:21:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:21:51 INFO - Running post-action listener: _resource_record_post_action 12:21:51 INFO - [mozharness: 2017-01-19 20:21:51.580640Z] Finished clobber step (success) 12:21:51 INFO - [mozharness: 2017-01-19 20:21:51.580825Z] Running read-buildbot-config step. 12:21:51 INFO - Running pre-action listener: _resource_record_pre_action 12:21:51 INFO - Running main action method: read_buildbot_config 12:21:51 INFO - Using buildbot properties: 12:21:51 INFO - { 12:21:51 INFO - "project": "", 12:21:51 INFO - "product": "firefox", 12:21:51 INFO - "script_repo_revision": "production", 12:21:51 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 12:21:51 INFO - "repository": "", 12:21:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 12:21:51 INFO - "buildid": "20170119092828", 12:21:51 INFO - "pgo_build": "False", 12:21:51 INFO - "basedir": "/builds/slave/test", 12:21:51 INFO - "buildnumber": 31, 12:21:51 INFO - "slavename": "t-yosemite-r7-0244", 12:21:51 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 12:21:51 INFO - "platform": "yosemite_r7", 12:21:51 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 12:21:51 INFO - "branch": "mozilla-aurora", 12:21:51 INFO - "repo_path": "releases/mozilla-aurora", 12:21:51 INFO - "revision": "338a1630d1db0573f0ac96be426f5b6f5198d474", 12:21:51 INFO - "stage_platform": "macosx64", 12:21:51 INFO - "builduid": "12faf1523a9b4ba3a1c7428280bceaa6", 12:21:51 INFO - "slavebuilddir": "test" 12:21:51 INFO - } 12:21:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/cdA6A_rMQJKEQZlAaaL9rQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 12:21:51 INFO - Running post-action listener: _resource_record_post_action 12:21:51 INFO - [mozharness: 2017-01-19 20:21:51.584848Z] Finished read-buildbot-config step (success) 12:21:51 INFO - [mozharness: 2017-01-19 20:21:51.584973Z] Running download-and-extract step. 12:21:51 INFO - Running pre-action listener: _resource_record_pre_action 12:21:51 INFO - Running main action method: download_and_extract 12:21:51 INFO - mkdir: /builds/slave/test/build/tests 12:21:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:21:51 INFO - trying https://queue.taskcluster.net/v1/task/cdA6A_rMQJKEQZlAaaL9rQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 12:21:51 INFO - Downloading https://queue.taskcluster.net/v1/task/cdA6A_rMQJKEQZlAaaL9rQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 12:21:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cdA6A_rMQJKEQZlAaaL9rQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 12:21:52 INFO - Downloaded 1199 bytes. 12:21:52 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 12:21:52 INFO - Using the following test package requirements: 12:21:52 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 12:21:52 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:21:52 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 12:21:52 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:21:52 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 12:21:52 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:21:52 INFO - u'jsshell-mac.zip'], 12:21:52 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:21:52 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 12:21:52 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 12:21:52 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:21:52 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 12:21:52 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:21:52 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 12:21:52 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:21:52 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 12:21:52 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:21:52 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 12:21:52 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 12:21:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cdA6A_rMQJKEQZlAaaL9rQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 12:21:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cdA6A_rMQJKEQZlAaaL9rQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:21:52 INFO - Fetch https://queue.taskcluster.net/v1/task/cdA6A_rMQJKEQZlAaaL9rQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 12:21:53 INFO - Http code: 200 12:21:53 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 12:21:53 INFO - x-amz-cf-id: TgxoSZ4lhVuOP7--k3ljMSGulH2RvxHY1sFdChO6rs-LVBOwat54ag== 12:21:53 INFO - x-amz-version-id: pTPeHWALBqaMylLdVX.CTJi6Buo4cSt9 12:21:53 INFO - Expected file size: 16276220 12:21:53 INFO - Obtained file size: 16276220 12:22:00 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 12:22:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cdA6A_rMQJKEQZlAaaL9rQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 12:22:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cdA6A_rMQJKEQZlAaaL9rQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:22:00 INFO - Fetch https://queue.taskcluster.net/v1/task/cdA6A_rMQJKEQZlAaaL9rQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 12:22:00 INFO - Http code: 200 12:22:00 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 12:22:00 INFO - x-amz-cf-id: nrvQK0JVh6lmYMs6CNyf7ORDIW3h_ssTBs4dAcmqk3ekiV-zgyn6DQ== 12:22:00 INFO - x-amz-version-id: pTPeHWALBqaMylLdVX.CTJi6Buo4cSt9 12:22:01 INFO - Expected file size: 16276220 12:22:01 INFO - Obtained file size: 16276220 12:22:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cdA6A_rMQJKEQZlAaaL9rQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 12:22:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cdA6A_rMQJKEQZlAaaL9rQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 12:22:07 INFO - Fetch https://queue.taskcluster.net/v1/task/cdA6A_rMQJKEQZlAaaL9rQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 12:22:07 INFO - Http code: 200 12:22:07 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 12:22:07 INFO - x-amz-cf-id: rADC3MqaXjR_PR6gMzdpMWx27M659GiIS1ubHo5Cjch_nCRV4VP4qQ== 12:22:07 INFO - x-amz-version-id: 4V1W4XyQvG6i4JItpXmaxcpIWwebp18I 12:22:08 INFO - Expected file size: 14409038 12:22:08 INFO - Obtained file size: 14409038 12:22:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:08 INFO - trying https://queue.taskcluster.net/v1/task/cdA6A_rMQJKEQZlAaaL9rQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 12:22:08 INFO - Downloading https://queue.taskcluster.net/v1/task/cdA6A_rMQJKEQZlAaaL9rQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 12:22:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cdA6A_rMQJKEQZlAaaL9rQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 12:22:11 INFO - Downloaded 109970992 bytes. 12:22:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cdA6A_rMQJKEQZlAaaL9rQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 12:22:11 INFO - mkdir: /builds/slave/test/properties 12:22:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:22:11 INFO - Writing to file /builds/slave/test/properties/build_url 12:22:11 INFO - Contents: 12:22:11 INFO - build_url:https://queue.taskcluster.net/v1/task/cdA6A_rMQJKEQZlAaaL9rQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 12:22:12 INFO - Running post-action listener: _resource_record_post_action 12:22:12 INFO - Running post-action listener: set_extra_try_arguments 12:22:12 INFO - [mozharness: 2017-01-19 20:22:12.043973Z] Finished download-and-extract step (success) 12:22:12 INFO - [mozharness: 2017-01-19 20:22:12.044114Z] Running populate-webroot step. 12:22:12 INFO - Running pre-action listener: _resource_record_pre_action 12:22:12 INFO - Running main action method: populate_webroot 12:22:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:22:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:22:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:22:12 INFO - '--tppagecycles', 12:22:12 INFO - '7'], 12:22:12 INFO - 'tests': ['tsvgm']}}, 12:22:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:22:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:22:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:22:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:22:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:22:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:22:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:22:12 INFO - 'talos_options': ['--disable-e10s'], 12:22:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:22:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:22:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:22:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:22:12 INFO - 'talos_options': ['--disable-e10s'], 12:22:12 INFO - 'tests': ['damp', 'tps']}, 12:22:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:22:12 INFO - 'tests': ['damp', 'tps']}, 12:22:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:22:12 INFO - 'tests': ['dromaeo_dom']}, 12:22:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:22:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:22:12 INFO - 'tests': ['basic_compositor_video']}, 12:22:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:22:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:22:12 INFO - 'tests': ['a11yr', 12:22:12 INFO - 'ts_paint', 12:22:12 INFO - 'tpaint', 12:22:12 INFO - 'sessionrestore', 12:22:12 INFO - 'sessionrestore_no_auto_restore', 12:22:12 INFO - 'tabpaint']}, 12:22:12 INFO - 'other-e10s': {'tests': ['a11yr', 12:22:12 INFO - 'ts_paint', 12:22:12 INFO - 'tpaint', 12:22:12 INFO - 'sessionrestore', 12:22:12 INFO - 'sessionrestore_no_auto_restore', 12:22:12 INFO - 'tabpaint']}, 12:22:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:22:12 INFO - 'ts_paint', 12:22:12 INFO - 'tpaint', 12:22:12 INFO - 'sessionrestore', 12:22:12 INFO - 'sessionrestore_no_auto_restore', 12:22:12 INFO - 'tabpaint']}, 12:22:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:22:12 INFO - 'ts_paint', 12:22:12 INFO - 'tpaint', 12:22:12 INFO - 'sessionrestore', 12:22:12 INFO - 'sessionrestore_no_auto_restore', 12:22:12 INFO - 'tabpaint']}, 12:22:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:22:12 INFO - 'tests': ['a11yr', 12:22:12 INFO - 'ts_paint', 12:22:12 INFO - 'tpaint', 12:22:12 INFO - 'sessionrestore', 12:22:12 INFO - 'sessionrestore_no_auto_restore', 12:22:12 INFO - 'tabpaint']}, 12:22:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:22:12 INFO - 'tests': ['a11yr', 12:22:12 INFO - 'ts_paint', 12:22:12 INFO - 'tpaint', 12:22:12 INFO - 'sessionrestore', 12:22:12 INFO - 'sessionrestore_no_auto_restore', 12:22:12 INFO - 'tabpaint']}, 12:22:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:22:12 INFO - 'tests': ['tsvgx', 12:22:12 INFO - 'tsvgr_opacity', 12:22:12 INFO - 'tart', 12:22:12 INFO - 'tscrollx', 12:22:12 INFO - 'cart']}, 12:22:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:22:12 INFO - 'tsvgr_opacity', 12:22:12 INFO - 'tart', 12:22:12 INFO - 'tscrollx', 12:22:12 INFO - 'cart']}, 12:22:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:22:12 INFO - 'talos_options': ['--disable-e10s'], 12:22:12 INFO - 'tests': ['tp5o']}, 12:22:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:22:12 INFO - 'tests': ['tp5o']}, 12:22:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:22:12 INFO - 'talos_options': ['--disable-e10s', 12:22:12 INFO - '--xperf_path', 12:22:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:22:12 INFO - 'tests': ['tp5n']}, 12:22:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:22:12 INFO - 'talos_options': ['--xperf_path', 12:22:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:22:12 INFO - 'tests': ['tp5n']}}, 12:22:12 INFO - 'talos.zip': {'path': '', 12:22:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:22:12 INFO - Running post-action listener: _resource_record_post_action 12:22:12 INFO - [mozharness: 2017-01-19 20:22:12.062994Z] Finished populate-webroot step (success) 12:22:12 INFO - [mozharness: 2017-01-19 20:22:12.063102Z] Running create-virtualenv step. 12:22:12 INFO - Running pre-action listener: _resource_record_pre_action 12:22:12 INFO - Running main action method: create_virtualenv 12:22:12 INFO - Creating virtualenv /builds/slave/test/build/venv 12:22:12 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 12:22:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:22:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:22:12 INFO - Using real prefix '/tools/python27' 12:22:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:22:12 INFO - Installing distribute.............................................................................................................................................................................................done. 12:22:15 INFO - Installing pip.................done. 12:22:15 INFO - Return code: 0 12:22:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:22:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:22:16 INFO - Reading from file tmpfile_stdout 12:22:16 INFO - Output received: 12:22:16 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 12:22:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:22: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')]} 12:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22: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')]} 12:22:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10811c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108092570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf13c730e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f30d30>, '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 0x107d96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107f28b20>, '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.yaw0vmisAo/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.FQaCmcBLi7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 12:22:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:22:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:22:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yaw0vmisAo/Render', 12:22:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:16 INFO - 'HOME': '/Users/cltbld', 12:22:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:16 INFO - 'LOGNAME': 'cltbld', 12:22:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:16 INFO - 'MOZ_NO_REMOTE': '1', 12:22:16 INFO - 'NO_EM_RESTART': '1', 12:22:16 INFO - 'PAGER': '/bin/cat', 12:22:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:16 INFO - 'PWD': '/builds/slave/test', 12:22:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:16 INFO - 'SHELL': '/bin/bash', 12:22:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FQaCmcBLi7/Listeners', 12:22:16 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 12:22:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:16 INFO - 'USER': 'cltbld', 12:22:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:16 INFO - 'XPC_FLAGS': '0x0', 12:22:16 INFO - 'XPC_SERVICE_NAME': '0', 12:22:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:16 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 12:22:16 INFO - Cleaning up... 12:22:16 INFO - Return code: 0 12:22:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:22: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')]} 12:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22: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')]} 12:22:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10811c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108092570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf13c730e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f30d30>, '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 0x107d96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107f28b20>, '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.yaw0vmisAo/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.FQaCmcBLi7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 12:22:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:22:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:22:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yaw0vmisAo/Render', 12:22:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:16 INFO - 'HOME': '/Users/cltbld', 12:22:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:16 INFO - 'LOGNAME': 'cltbld', 12:22:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:16 INFO - 'MOZ_NO_REMOTE': '1', 12:22:16 INFO - 'NO_EM_RESTART': '1', 12:22:16 INFO - 'PAGER': '/bin/cat', 12:22:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:16 INFO - 'PWD': '/builds/slave/test', 12:22:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:16 INFO - 'SHELL': '/bin/bash', 12:22:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FQaCmcBLi7/Listeners', 12:22:16 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 12:22:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:16 INFO - 'USER': 'cltbld', 12:22:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:16 INFO - 'XPC_FLAGS': '0x0', 12:22:16 INFO - 'XPC_SERVICE_NAME': '0', 12:22:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:16 INFO - Downloading/unpacking psutil>=3.1.1 12:22:16 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 12:22:16 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 12:22:16 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 12:22:16 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 12:22:16 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 12:22:16 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 12:22:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:22:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:22:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:22:20 INFO - Installing collected packages: psutil 12:22:20 INFO - Running setup.py install for psutil 12:22:20 INFO - building 'psutil._psutil_osx' extension 12:22:20 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 12:22:20 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 12:22:20 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 12:22:20 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 12:22:20 INFO - building 'psutil._psutil_posix' extension 12:22:20 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 12:22:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:22:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:22:20 INFO - ^ 12:22:20 INFO - 1 warning generated. 12:22:20 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 12:22:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:22:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:22:20 INFO - Successfully installed psutil 12:22:20 INFO - Cleaning up... 12:22:20 INFO - Return code: 0 12:22:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:22:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:20 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 0x10811c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108092570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf13c730e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f30d30>, '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 0x107d96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107f28b20>, '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.yaw0vmisAo/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.FQaCmcBLi7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 12:22:20 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 12:22:20 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 12:22:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yaw0vmisAo/Render', 12:22:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:20 INFO - 'HOME': '/Users/cltbld', 12:22:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:20 INFO - 'LOGNAME': 'cltbld', 12:22:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:20 INFO - 'MOZ_NO_REMOTE': '1', 12:22:20 INFO - 'NO_EM_RESTART': '1', 12:22:20 INFO - 'PAGER': '/bin/cat', 12:22:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:20 INFO - 'PWD': '/builds/slave/test', 12:22:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:20 INFO - 'SHELL': '/bin/bash', 12:22:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FQaCmcBLi7/Listeners', 12:22:20 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 12:22:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:20 INFO - 'USER': 'cltbld', 12:22:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:20 INFO - 'XPC_FLAGS': '0x0', 12:22:20 INFO - 'XPC_SERVICE_NAME': '0', 12:22:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:22:21 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 12:22:21 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 12:22:21 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 12:22:21 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 12:22:21 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 12:22:21 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 12:22:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:22:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:22:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:22:23 INFO - Installing collected packages: mozsystemmonitor 12:22:23 INFO - Running setup.py install for mozsystemmonitor 12:22:23 INFO - Successfully installed mozsystemmonitor 12:22:23 INFO - Cleaning up... 12:22:24 INFO - Return code: 0 12:22:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:22:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10811c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108092570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf13c730e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f30d30>, '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 0x107d96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107f28b20>, '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.yaw0vmisAo/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.FQaCmcBLi7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 12:22:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:22:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:22:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yaw0vmisAo/Render', 12:22:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:24 INFO - 'HOME': '/Users/cltbld', 12:22:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:24 INFO - 'LOGNAME': 'cltbld', 12:22:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:24 INFO - 'MOZ_NO_REMOTE': '1', 12:22:24 INFO - 'NO_EM_RESTART': '1', 12:22:24 INFO - 'PAGER': '/bin/cat', 12:22:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:24 INFO - 'PWD': '/builds/slave/test', 12:22:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:24 INFO - 'SHELL': '/bin/bash', 12:22:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FQaCmcBLi7/Listeners', 12:22:24 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 12:22:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:24 INFO - 'USER': 'cltbld', 12:22:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:24 INFO - 'XPC_FLAGS': '0x0', 12:22:24 INFO - 'XPC_SERVICE_NAME': '0', 12:22:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:24 INFO - Downloading/unpacking jsonschema==2.5.1 12:22:24 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 12:22:24 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 12:22:24 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 12:22:24 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 12:22:24 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 12:22:24 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 12:22:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:22:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:22:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:27 INFO - Downloading functools32-3.2.3-2.tar.gz 12:22:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:22:27 INFO - warning: no files found matching '*.txt' 12:22:27 INFO - no previously-included directories found matching 'build' 12:22:27 INFO - no previously-included directories found matching 'dist' 12:22:27 INFO - no previously-included directories found matching '.git*' 12:22:27 INFO - Installing collected packages: jsonschema, functools32 12:22:27 INFO - Running setup.py install for functools32 12:22:27 INFO - warning: no files found matching '*.txt' 12:22:27 INFO - no previously-included directories found matching 'build' 12:22:27 INFO - no previously-included directories found matching 'dist' 12:22:27 INFO - no previously-included directories found matching '.git*' 12:22:27 INFO - Successfully installed jsonschema functools32 12:22:27 INFO - Cleaning up... 12:22:27 INFO - Return code: 0 12:22:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:22:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10811c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108092570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf13c730e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f30d30>, '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 0x107d96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107f28b20>, '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.yaw0vmisAo/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.FQaCmcBLi7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 12:22:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:22:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:22:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yaw0vmisAo/Render', 12:22:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:27 INFO - 'HOME': '/Users/cltbld', 12:22:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:27 INFO - 'LOGNAME': 'cltbld', 12:22:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:27 INFO - 'MOZ_NO_REMOTE': '1', 12:22:27 INFO - 'NO_EM_RESTART': '1', 12:22:27 INFO - 'PAGER': '/bin/cat', 12:22:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:27 INFO - 'PWD': '/builds/slave/test', 12:22:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:27 INFO - 'SHELL': '/bin/bash', 12:22:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FQaCmcBLi7/Listeners', 12:22:27 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 12:22:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:27 INFO - 'USER': 'cltbld', 12:22:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:27 INFO - 'XPC_FLAGS': '0x0', 12:22:27 INFO - 'XPC_SERVICE_NAME': '0', 12:22:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:22:28 INFO - Cleaning up... 12:22:28 INFO - Return code: 0 12:22:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:22:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10811c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108092570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf13c730e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f30d30>, '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 0x107d96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107f28b20>, '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.yaw0vmisAo/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.FQaCmcBLi7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 12:22:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:22:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:22:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yaw0vmisAo/Render', 12:22:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:28 INFO - 'HOME': '/Users/cltbld', 12:22:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:28 INFO - 'LOGNAME': 'cltbld', 12:22:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:28 INFO - 'MOZ_NO_REMOTE': '1', 12:22:28 INFO - 'NO_EM_RESTART': '1', 12:22:28 INFO - 'PAGER': '/bin/cat', 12:22:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:28 INFO - 'PWD': '/builds/slave/test', 12:22:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:28 INFO - 'SHELL': '/bin/bash', 12:22:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FQaCmcBLi7/Listeners', 12:22:28 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 12:22:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:28 INFO - 'USER': 'cltbld', 12:22:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:28 INFO - 'XPC_FLAGS': '0x0', 12:22:28 INFO - 'XPC_SERVICE_NAME': '0', 12:22:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:28 INFO - Downloading/unpacking blobuploader==1.2.4 12:22:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:30 INFO - Downloading blobuploader-1.2.4.tar.gz 12:22:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:22:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:22:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:22:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:22:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:32 INFO - Downloading docopt-0.6.1.tar.gz 12:22:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:22:32 INFO - Installing collected packages: blobuploader, requests, docopt 12:22:32 INFO - Running setup.py install for blobuploader 12:22:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:22:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:22:32 INFO - Running setup.py install for requests 12:22:32 INFO - Running setup.py install for docopt 12:22:32 INFO - Successfully installed blobuploader requests docopt 12:22:32 INFO - Cleaning up... 12:22:33 INFO - Return code: 0 12:22:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:22:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10811c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108092570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf13c730e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f30d30>, '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 0x107d96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107f28b20>, '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.yaw0vmisAo/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.FQaCmcBLi7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 12:22:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:22:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:22:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yaw0vmisAo/Render', 12:22:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:33 INFO - 'HOME': '/Users/cltbld', 12:22:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:33 INFO - 'LOGNAME': 'cltbld', 12:22:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:33 INFO - 'MOZ_NO_REMOTE': '1', 12:22:33 INFO - 'NO_EM_RESTART': '1', 12:22:33 INFO - 'PAGER': '/bin/cat', 12:22:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:33 INFO - 'PWD': '/builds/slave/test', 12:22:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:33 INFO - 'SHELL': '/bin/bash', 12:22:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FQaCmcBLi7/Listeners', 12:22:33 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 12:22:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:33 INFO - 'USER': 'cltbld', 12:22:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:33 INFO - 'XPC_FLAGS': '0x0', 12:22:33 INFO - 'XPC_SERVICE_NAME': '0', 12:22:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:22:33 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-SNHZEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:22:33 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-oAd13r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:22:33 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-4bK8PO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:22:33 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-LxTlng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:22:33 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-TklqWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:22:33 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-HWaxMh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:22:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:22:33 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-i3W5EY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:22:34 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-bXRRDz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:22:34 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-tZr71h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:22:34 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-5JET4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:22:34 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-fgg9Zj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:22:34 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-dZprSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:22:34 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-Bdz2gQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:22:34 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-bh2BKl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:22:34 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-f45oOl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:22:34 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-dQ9M6h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:22:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:22:35 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-MdW_F2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:22:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:22:35 INFO - Running setup.py install for manifestparser 12:22:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:22:35 INFO - Running setup.py install for mozcrash 12:22:35 INFO - Running setup.py install for mozdebug 12:22:35 INFO - Running setup.py install for mozdevice 12:22:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:22:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:22:35 INFO - Running setup.py install for mozfile 12:22:35 INFO - Running setup.py install for mozhttpd 12:22:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:22:35 INFO - Running setup.py install for mozinfo 12:22:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:22:36 INFO - Running setup.py install for mozInstall 12:22:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:22:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:22:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:22:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:22:36 INFO - Running setup.py install for mozleak 12:22:36 INFO - Running setup.py install for mozlog 12:22:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:22:36 INFO - Running setup.py install for moznetwork 12:22:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:22:36 INFO - Running setup.py install for mozprocess 12:22:36 INFO - Running setup.py install for mozprofile 12:22:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:22:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:22:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:22:37 INFO - Running setup.py install for mozrunner 12:22:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:22:37 INFO - Running setup.py install for mozscreenshot 12:22:37 INFO - Running setup.py install for moztest 12:22:37 INFO - Running setup.py install for mozversion 12:22:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:22:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:22:37 INFO - Cleaning up... 12:22:37 INFO - Return code: 0 12:22:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:22:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:37 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 0x10811c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108092570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf13c730e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f30d30>, '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 0x107d96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107f28b20>, '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.yaw0vmisAo/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.FQaCmcBLi7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 12:22:37 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 12:22:37 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 12:22:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yaw0vmisAo/Render', 12:22:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:37 INFO - 'HOME': '/Users/cltbld', 12:22:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:37 INFO - 'LOGNAME': 'cltbld', 12:22:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:37 INFO - 'MOZ_NO_REMOTE': '1', 12:22:37 INFO - 'NO_EM_RESTART': '1', 12:22:37 INFO - 'PAGER': '/bin/cat', 12:22:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:37 INFO - 'PWD': '/builds/slave/test', 12:22:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:37 INFO - 'SHELL': '/bin/bash', 12:22:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FQaCmcBLi7/Listeners', 12:22:37 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 12:22:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:37 INFO - 'USER': 'cltbld', 12:22:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:37 INFO - 'XPC_FLAGS': '0x0', 12:22:37 INFO - 'XPC_SERVICE_NAME': '0', 12:22:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:22:37 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-cCyVPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:22:38 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:22:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:22:38 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-oF1epV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:22:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:22:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:22:38 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-QBCuMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:22:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:22:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:22:38 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-B_JWXK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:22:38 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)) 12:22:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:22:38 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-uo83nZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:22:38 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)) 12:22:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:22:38 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-aGBjsO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:22:38 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)) 12:22:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:22:38 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-0COyLZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:22:38 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)) 12:22:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:22:38 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-3YCd3A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:22:38 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)) 12:22:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:22:38 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-dH3ZB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:22:38 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)) 12:22:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:22:38 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-fgZzzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:22:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:22:39 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-zu313F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:22:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:22:39 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-G2Rk3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:22:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:22:39 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-d2x9CB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:22:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:22:39 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-Rk0VOW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:22:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:22:39 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-8oW_Gj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:22:39 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)) 12:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:22:39 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-rk1xF8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:22:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:22:39 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-0oaKlN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:22:39 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)) 12:22:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:22:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:22:39 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)) 12:22:39 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)) 12:22:39 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)) 12:22:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:22:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:42 INFO - Downloading blessings-1.6.tar.gz 12:22:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:22:42 INFO - Installing collected packages: blessings 12:22:42 INFO - Running setup.py install for blessings 12:22:42 INFO - Successfully installed blessings 12:22:42 INFO - Cleaning up... 12:22:43 INFO - Return code: 0 12:22:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:22:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:22:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:22:43 INFO - Reading from file tmpfile_stdout 12:22:43 INFO - Current package versions: 12:22:43 INFO - blessings == 1.6 12:22:43 INFO - blobuploader == 1.2.4 12:22:43 INFO - docopt == 0.6.1 12:22:43 INFO - functools32 == 3.2.3-2 12:22:43 INFO - jsonschema == 2.5.1 12:22:43 INFO - manifestparser == 1.1 12:22:43 INFO - mozInstall == 1.12 12:22:43 INFO - mozcrash == 1.0 12:22:43 INFO - mozdebug == 0.1 12:22:43 INFO - mozdevice == 0.48 12:22:43 INFO - mozfile == 1.2 12:22:43 INFO - mozhttpd == 0.7 12:22:43 INFO - mozinfo == 0.9 12:22:43 INFO - mozleak == 0.1 12:22:43 INFO - mozlog == 3.4 12:22:43 INFO - moznetwork == 0.27 12:22:43 INFO - mozprocess == 0.23 12:22:43 INFO - mozprofile == 0.28 12:22:43 INFO - mozrunner == 6.13 12:22:43 INFO - mozscreenshot == 0.1 12:22:43 INFO - mozsystemmonitor == 0.3 12:22:43 INFO - moztest == 0.8 12:22:43 INFO - mozversion == 1.4 12:22:43 INFO - psutil == 3.1.1 12:22:43 INFO - requests == 1.2.3 12:22:43 INFO - wsgiref == 0.1.2 12:22:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:22:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:22:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:43 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 0x10811c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108092570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf13c730e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f30d30>, '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 0x107d96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107f28b20>, '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.yaw0vmisAo/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.FQaCmcBLi7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 12:22:43 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 12:22:43 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 12:22:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yaw0vmisAo/Render', 12:22:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:43 INFO - 'HOME': '/Users/cltbld', 12:22:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:43 INFO - 'LOGNAME': 'cltbld', 12:22:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:43 INFO - 'MOZ_NO_REMOTE': '1', 12:22:43 INFO - 'NO_EM_RESTART': '1', 12:22:43 INFO - 'PAGER': '/bin/cat', 12:22:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:43 INFO - 'PWD': '/builds/slave/test', 12:22:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:43 INFO - 'SHELL': '/bin/bash', 12:22:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FQaCmcBLi7/Listeners', 12:22:43 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 12:22:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:43 INFO - 'USER': 'cltbld', 12:22:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:43 INFO - 'XPC_FLAGS': '0x0', 12:22:43 INFO - 'XPC_SERVICE_NAME': '0', 12:22:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:22:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:22:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:22:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:22:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:22:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:22:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:22:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:22:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:22:44 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:22:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:22:44 INFO - Cleaning up... 12:22:44 INFO - Return code: 0 12:22:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:22: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')]} 12:22:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:22:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22: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')]} 12:22:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:22:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:22:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10811c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108092570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdf13c730e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f30d30>, '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 0x107d96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107f28b20>, '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.yaw0vmisAo/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.FQaCmcBLi7/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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 12:22:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:22:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:22:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yaw0vmisAo/Render', 12:22:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:44 INFO - 'HOME': '/Users/cltbld', 12:22:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:44 INFO - 'LOGNAME': 'cltbld', 12:22:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:44 INFO - 'MOZ_NO_REMOTE': '1', 12:22:44 INFO - 'NO_EM_RESTART': '1', 12:22:44 INFO - 'PAGER': '/bin/cat', 12:22:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:44 INFO - 'PWD': '/builds/slave/test', 12:22:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:44 INFO - 'SHELL': '/bin/bash', 12:22:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FQaCmcBLi7/Listeners', 12:22:44 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 12:22:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:44 INFO - 'USER': 'cltbld', 12:22:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:44 INFO - 'XPC_FLAGS': '0x0', 12:22:44 INFO - 'XPC_SERVICE_NAME': '0', 12:22:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:22:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:22:44 INFO - Cleaning up... 12:22:44 INFO - Return code: 0 12:22:44 INFO - Running post-action listener: _resource_record_post_action 12:22:44 INFO - Running post-action listener: _start_resource_monitoring 12:22:44 INFO - Starting resource monitoring. 12:22:44 INFO - [mozharness: 2017-01-19 20:22:44.426470Z] Finished create-virtualenv step (success) 12:22:44 INFO - [mozharness: 2017-01-19 20:22:44.427517Z] Running install step. 12:22:44 INFO - Running pre-action listener: _resource_record_pre_action 12:22:44 INFO - Running main action method: install 12:22:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:22:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:22:44 INFO - Reading from file tmpfile_stdout 12:22:44 INFO - Detecting whether we're running mozinstall >=1.0... 12:22:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:22:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:22:44 INFO - Reading from file tmpfile_stdout 12:22:44 INFO - Output received: 12:22:44 INFO - Usage: mozinstall [options] installer 12:22:44 INFO - Options: 12:22:44 INFO - -h, --help show this help message and exit 12:22:44 INFO - -d DEST, --destination=DEST 12:22:44 INFO - Directory to install application into. [default: 12:22:44 INFO - "/builds/slave/test"] 12:22:44 INFO - --app=APP Application being installed. [default: firefox] 12:22:44 INFO - mkdir: /builds/slave/test/build/application 12:22:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:22:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 12:23:16 INFO - Reading from file tmpfile_stdout 12:23:16 INFO - Output received: 12:23:16 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 12:23:16 INFO - Running post-action listener: _resource_record_post_action 12:23:16 INFO - [mozharness: 2017-01-19 20:23:16.179429Z] Finished install step (success) 12:23:16 INFO - [mozharness: 2017-01-19 20:23:16.179587Z] Running run-tests step. 12:23:16 INFO - Running pre-action listener: _resource_record_pre_action 12:23:16 INFO - Running main action method: run_tests 12:23:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:23:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:23:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:23:16 INFO - 2017-01-19 12:23:16.192 screenresolution[1905:18159] starting screenresolution argv=screenresolution get 12:23:16 INFO - 2017-01-19 12:23:16.195 screenresolution[1905:18159] Display 0: 1600x1200x32@60 12:23:16 INFO - 2017-01-19 12:23:16.200 screenresolution[1906:18161] starting screenresolution argv=screenresolution list 12:23:16 INFO - Available Modes on Display 0 12:23:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:23:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:23:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:23:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:23:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:23:16 INFO - Intel Iris: 12:23:16 INFO - Chipset Model: Intel Iris 12:23:16 INFO - Type: GPU 12:23:16 INFO - Bus: Built-In 12:23:16 INFO - VRAM (Dynamic, Max): 1536 MB 12:23:16 INFO - Vendor: Intel (0x8086) 12:23:16 INFO - Device ID: 0x0a2e 12:23:16 INFO - Revision ID: 0x0009 12:23:16 INFO - Displays: 12:23:16 INFO - Display: 12:23:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:23:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:23:16 INFO - Display Serial Number: 1600x1200 60 12:23:16 INFO - Main Display: Yes 12:23:16 INFO - Mirror: Off 12:23:16 INFO - Online: Yes 12:23:16 INFO - Rotation: Supported 12:23:16 INFO - Return code: 0 12:23:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:23:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:23:16 INFO - Python 2.7.3 12:23:16 INFO - Return code: 0 12:23:16 INFO - grabbing minidump binary from tooltool 12:23: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')]} 12:23:16 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 0x107f30d30>, '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 0x107d96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107f28b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:23:16 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 12:23:16 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 12:23:16 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 12:23:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:23:16 INFO - Return code: 0 12:23:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:23:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:23:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:23:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:23:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:23:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cdA6A_rMQJKEQZlAaaL9rQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0244', '--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 12:23:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cdA6A_rMQJKEQZlAaaL9rQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0244 --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 12:23:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yaw0vmisAo/Render', 12:23:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:23:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:23:16 INFO - 'HOME': '/Users/cltbld', 12:23:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:23:16 INFO - 'LOGNAME': 'cltbld', 12:23:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:23:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:23:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:23:16 INFO - 'MOZ_NO_REMOTE': '1', 12:23:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:23:16 INFO - 'NO_EM_RESTART': '1', 12:23:16 INFO - 'PAGER': '/bin/cat', 12:23:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:23:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:23:16 INFO - 'PWD': '/builds/slave/test', 12:23:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:23:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:23:16 INFO - 'SHELL': '/bin/bash', 12:23:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FQaCmcBLi7/Listeners', 12:23:16 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 12:23:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:23:16 INFO - 'USER': 'cltbld', 12:23:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:23:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:23:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:23:16 INFO - 'XPC_FLAGS': '0x0', 12:23:16 INFO - 'XPC_SERVICE_NAME': '0', 12:23:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:23:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cdA6A_rMQJKEQZlAaaL9rQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0244', '--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 12:23:16 INFO - mozversion application_buildid: 20170119092828 12:23:16 INFO - mozversion application_changeset: 338a1630d1db0573f0ac96be426f5b6f5198d474 12:23:16 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:23:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:23:16 INFO - mozversion application_name: Firefox 12:23:16 INFO - mozversion application_remotingname: firefox-dev 12:23:16 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:23:16 INFO - mozversion application_vendor: Mozilla 12:23:16 INFO - mozversion application_version: 52.0a2 12:23:16 INFO - mozversion platform_buildid: 20170119092828 12:23:16 INFO - mozversion platform_changeset: 338a1630d1db0573f0ac96be426f5b6f5198d474 12:23:16 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:23:16 INFO - mozversion platform_version: 52.0a2 12:23:16 INFO - using testdate: 1484857396 12:23:16 INFO - actual date: 1484857396 12:23:16 INFO - starting webserver on 'localhost:56549' 12:23:16 INFO - SUITE-START | Running 2 tests 12:23:16 INFO - TEST-START | dromaeo_css 12:23:16 INFO - operating with platform_type : mac_ 12:23:16 INFO - Initialising browser for dromaeo_css test... 12:23:16 INFO - TEST-INFO | started process 1916 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/tmpWZPN1J/profile http://localhost:56549/getInfo.html) 12:23:19 INFO - PROCESS | 1916 | 2017-01-19 12:23:19.841 plugin-container[1918:18327] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.tsm.portname' 12:23:19 INFO - PROCESS | 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:23:19 INFO - PROCESS | 1916 | 2017-01-19 12:23:19.843 plugin-container[1918:18327] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b07, name = 'com.apple.CFPasteboardClient' 12:23:19 INFO - PROCESS | 1916 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:23:19 INFO - PROCESS | 1916 | 2017-01-19 12:23:19.843 plugin-container[1918:18327] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:23:19 INFO - PROCESS | 1916 | __metrics Screen width/height:1600/1200 12:23:19 INFO - PROCESS | 1916 | colorDepth:24 12:23:19 INFO - PROCESS | 1916 | Browser inner width/height: 1024/663 12:23:19 INFO - PROCESS | 1916 | __metrics 12:23:20 ERROR - PROCESS | 1916 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3181: TypeError: this.tabs is undefined 12:23:20 INFO - TEST-INFO | 1916: exit 0 12:23:20 INFO - Browser initialized. 12:23:20 INFO - Running cycle 1/1 for dromaeo_css test... 12:23:20 INFO - TEST-INFO | started process 1919 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/tmpWZPN1J/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:23:23 INFO - PROCESS | 1919 | 2017-01-19 12:23:23.562 plugin-container[1920:18458] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 12:23:23 INFO - PROCESS | 1919 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:23:23 INFO - PROCESS | 1919 | 2017-01-19 12:23:23.562 plugin-container[1920:18458] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 12:23:23 INFO - PROCESS | 1919 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:23:23 INFO - PROCESS | 1919 | 2017-01-19 12:23:23.562 plugin-container[1920:18458] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:23:33 INFO - PROCESS | 1919 | RSS: Main: 241229824 12:23:33 INFO - PROCESS | 1919 | 12:26:34 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:56549/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56549/tests/dromaeo/cssquery-ext.html) 12:26:36 INFO - PROCESS | 1919 | RSS: Main: 247492608 12:26:36 INFO - PROCESS | 1919 | 12:29:38 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:56549/tests/dromaeo/cssquery-ext.html (next: http://localhost:56549/tests/dromaeo/cssquery-jquery.html) 12:29:39 INFO - PROCESS | 1919 | RSS: Main: 253968384 12:29:39 INFO - PROCESS | 1919 | 12:32:41 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:56549/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56549/tests/dromaeo/cssquery-mootools.html) 12:32:41 INFO - PROCESS | 1919 | RSS: Main: 256987136 12:32:41 INFO - PROCESS | 1919 | 12:35:43 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:56549/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56549/tests/dromaeo/cssquery-prototype.html) 12:35:44 INFO - PROCESS | 1919 | RSS: Main: 256303104 12:35:44 INFO - PROCESS | 1919 | 12:38:47 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:56549/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56549/tests/dromaeo/cssquery-yui.html) 12:38:47 INFO - PROCESS | 1919 | RSS: Main: 259862528 12:38:47 INFO - PROCESS | 1919 | 12:41:50 INFO - PROCESS | 1919 | Cycle 1(1): loaded http://localhost:56549/tests/dromaeo/cssquery-yui.html (next: http://localhost:56549/tests/dromaeo/cssquery-dojo.html) 12:41:50 INFO - PROCESS | 1919 | RSS: Main: 261206016 12:41:50 INFO - PROCESS | 1919 | 12:41:50 INFO - PROCESS | 1919 | __start_tp_report 12:41:50 INFO - PROCESS | 1919 | _x_x_mozilla_page_load 12:41:50 INFO - PROCESS | 1919 | _x_x_mozilla_page_load_details 12:41:50 INFO - PROCESS | 1919 | |i|pagename|runs| 12:41:50 INFO - PROCESS | 1919 | |0;dojo.html;3310.553075334829;3321.8505167267476;3365.4951757236413;3382.3066271414364;3384.458486642138;4589.242774942139;5521.475459831319;5524.723763811813;5557.527610153137;5630.958272719549;5564.443555644433;5581.0512212923795;5596.2165296858475;5606.299212598426;5629.774809007645;4879.682820616648;5086.491350864902;5129.179331306992;5161.9483805162035;5201.947980520203;5052.696838189702;5143.588512918976;5157.7936882524655;5173.534381905646;5205.115130427817;4995.975020124913;5030.84907452777;5058.645894787366;5079.3650793650795;5105.055564720538;4997.400311962551;5037.143685573461;5059.16523773577;5081.085404627165;5106.412762532319;4957.231629097496;4962.702237865739;5027.798888044474;5053.317802096743;5106.412762532319;5002.274670172805;5098.464661210556;5482.259894914176;5505.146702261116;5513.3108361454815;4893.241547560134;4989.126902791997;5921.851953701149;5972.134040564138;6069.3627169147485;6068.787592434288;6114.144019837227;6120.265568131808;6135.754569817202;6152.046432802923;5462.863428414242;5512.641678290898;5639.323281206241;5657.660540367591;5680.062789639664;6114.023656277693;6135.630783975068;6203.937960620336;6215.12988181655;6229.626222426669;5971.552133589998;5974.634670593982;5984.820455386345;5992.610480318765;6028.125921741323;5908.556858235649;6108.786192483241;6130.172426722335;6140.539459540552;6145.508359331243;5868.295804503401;5901.7639294427745;5959.106134079922;5968.850778730566;6032.698365081728;6040.607360521552;6108.342071131513;6111.724972376253;6137.601055931439;6144.2488890665945;4760.523947605212;4762.761861906891;4785.377900872864;4815.15734746418;4821.228603423436;3452.430348992388;3651.9409371282563;4227.175700738388;4307.117041006586;4307.784610769449;4513.616342610915;5714.828555143353;5778.27771528559;5831.970840145772;5838.416158384128;5978.671173085522;6176.567640265138;6183.350748171377;6183.412575805291;6204.875902481925;4507.752073635916;5648.406917273627;5683.318001839806;5749.798757043567;5826.213461182686;5228.712420816815;5263.5525980292105;5266.3943646480375;5271.947280527223;5277.445868183862;5463.945360546502;6232.75068997235;6306.936930630635;6311.084892690626;6332.905006424908;5980;6029.366916473882;6097.542684298606;6119.07606531266;6149.0776383542825;6282.780102696383;6295.370462953668;6302.590331628613;6312.242530896322;6317.368263173646;5999.670018149044;6030.306514750684;6080.0575910733905;6081.087836824512;6102.176206212104;5888.263967004124;6002.639841609517;6010.429009244115;6169.96915015422;6173.59871608344;5705.115707065412;5780.739866705924;5785.334686511105;5785.884282314414;5796.594238403271;5699.886002279848;5731.627444216113;5732.684702341245;5768.2501274834;5768.278965129359;4153.190127925026;4827.831025914076;4876.756162191805;5503.394626591152;5595.272614560081;5762.5389968801555;5799.594028417971;5812.9709351452975;5823.272090988627;5836.212111364912;5751.48236658686;5752.223449834219;5758.395368486249;5775.7400916957995;5780.3930587289415 12:41:50 INFO - PROCESS | 1919 | |1;ext.html;462.4081176094598;472.9101470720563;497.1647631978276;529.3118945371021;529.9019681358949;38071;39646;39738.00654983627;39821.402678959836;40070.79964600177;39958.400623990594;40300;40457.79771101148;40486.19027619446;40552.18895622086;9267.675631352904;9376.343655944087;9404.341696081277;9414.52927353633;9431.481268530228;9210.815783684322;9275.257979361635;9289.396189247711;9307.674231401903;9326.020767819384;7343.926560734378;8015.278624923756;8548.674955381895;9034.322425818056;9258.027907069762;8634.136586341316;8824.470531768115;9320.673776417827;9373.109554592336;9378;9320.627174913063;9410.058994100604;9410.482423466707;9421.670241541513;9452.338336316461;32852.014439566614;38562.228755425014;38945.41581876274;38988.63539776122;39236.803815981024;3169.6988786065285;3319.319539494422;3321.4021476134285;3326.3347330533747;3725.9627403725885;29210.561841572395;29263.707362926525;29424.55863162033;29457;29678.703212968026;3115.5638210650527;3115.672854350306;3149.700778426046;3157.6526582075953;3181.809091454497;507119;510534;512242;592910;598110;59488;60002.699986499785;60090;60262;60387.698061509414;62035.6898215515;62278.68860655668;62348.68825655933;62696.686516568036;62715.372846271865;15719.68560628781;15868.761968570481;15883.52349429519;16120.193990300439;16181.514554563382;12275.877241227652;12286.508539658493;12303.507859685513;12317.137800354069;12318;18259;18289.26842926268;18474;18494;18505;6728.461723062242;7765.883511747328;7827.608619568999;7833.5691536966015;7975.441719079725;872.4416373520952;921.5714692667941;934.8921527989219;953.93322467428;1000.2298230362726;23518.163488737464;28567.57148642772;29643.703562964525;29689;29696.851515742714;984.3552473130111;1026.6612018034032;1049.0977759127145;1052.736815796051;1081.848541204232;13833.861661383458;15058.32237549313;15360.308786104652;15374;15659.295331709873;627929;637012;637500;638841;641238;56428;56620;56802.43197567971;56893.43106568964;56938.145927811114;1241.3296819717432;1265.7278685082752;1269.2004037456343;1278.1628033637983;1318.202487495071;28331.291717707223;28758.568621470276;29234.707652924048;29350.266243344086;29660.961866334575;47928.76035619799;48083;48142.51857481521;48224;48244;580105;582318;584276;585451;586828;25481.235562933143;25733.613995789703;25959.61060584055;25967;26017.47965040651;44322;44824.77587612041;44922.550774491836;46081.76959115317;46363.53636463592;36294;37564.43653345147;37586.436203456426;38533.614663853004;38700.03249918774;30888.38223235478;36981.44527832031;37136.442953356265;37228.44157337588;37303.44044839384;31106.533401998535;32518.674813251564;34523.48214776831;37858.81070594629;38394.23211535809;32732;34112.317753645286;34572;34582;34662.480062798575;24714.258572242863;24936;25374.619380709675;25399.746002539738;25456.109036183643 12:41:50 INFO - PROCESS | 1919 | |2;jquery.html;371.754641936322;373.9326921154193;380.5642539292509;384.89992601923524;398.68703068091554;18400.90799546002;19708.70436943447;20177;20421.795782042194;20583.176672933103;19709.90145049276;19751.40745777629;19884.20463181471;19991.300304489345;20280.695789563168;4791.928121078169;5466.207399927008;5701.916635839178;6038.849028774293;6128.693565321739;5959.1359252908305;6077.969610151965;6078.604890682113;6082.847928801793;6088.1476593276975;5732.0828667413225;5974.641521508702;5990.400959903996;5994.370591087938;6002.279726432834;5717.485426311652;5953.732082056296;5989.431004054608;6006.21919150512;6021.367756385582;5175.585953123741;5224.660397074178;5243.187305967581;5250.65870718402;5250.789968401259;24441.388965275833;24584;24603.63094553583;24650;25153.24540263795;2034.7761746207905;2154.5906277807167;2208.8012078912816;2326.953460930772;2915.1254623613045;5691.971540142315;9897.15874150698;9963.754530683664;9988.101070903653;10343.689689309333;1555.60332115311;1662.6924019056514;1916.367598692442;1925.8149491261504;1928.980710192894;116049.99999999916;116970;119305;120943;122525;9878.308518403643;11503.942480287713;11669.299842009506;11757.76484470323;11761.529538818522;12145.574904878464;14674.706505869823;14681.119132852064;14692.853071469148;14694.265286735621;7585.393168546503;8215;8473.279771219391;8492.141429347652;8613.26787223093;8329.292010179202;8393.160683931557;8401.285890699359;8515.489070655656;8658;4444.73331600105;4500.234960056799;4606.585407313358;4633;4712.552307530779;1560.9853595163236;1790.6955817511055;1908.8091190881078;1955.1397385150487;1960.6372821028156;867.8698195270633;900.2572877376191;924.5977999570208;957.8658987741721;985.8521221816785;20150.59698806045;20269.493262668257;21878.671819922714;22346.55306893853;22562.774372256066;932.7574830544107;943;966.9951650241844;1002.4185972136144;1018.8115198688266;10874.619388321371;11106.389148596743;11113.499892504695;11397.658070257905;11399.715007124889;536573;605369;610119;614853.8514614886;624290;82415;121800;129827;130416;134271.3286433581;834.7579202031465;953.7091187187974;989.2531138990016;992.7766252593253;993.7714325704984;18820.811791881904;19378.8062119377;19890.104945277788;19950.90024549926;19959.700604491234;38741.418878717406;44786;51000.489995099575;54066;54743;75669;79722.00694982671;79739.60130199311;80769.19230807616;81559;5774.307083150049;5875.441833025856;6599.505037122139;7074.68163932614;7846.17615150401;11563.843615638705;12083.662233342891;12378.319192444142;13029.739405212033;13250.337483125997;10969.945150274198;11107.944460277648;11168.441577920908;11410.486528106421;11439.256448330832;9449.196818270524;10723.19576031785;10815.621453249414;11056.226064175753;11104.111671066134;11266.94366528195;11277.097832173571;11337.603183888525;11715.765684686088;11730.648080557597;7215.891761623475;7400.111986561647;7547.358474529727;7557;7675.808104797201;10503.579856805947;10937.12502999774;11027.448627568428;11276.605318813803;11315.490802913724 12:41:50 INFO - PROCESS | 1919 | |3;mootools.html;1441.8486058963813;1462.3054049326568;1643.6137507685692;1785.6339450412668;1797.1283927295267;7585.203553626872;7846.882296765553;8194.344452443805;8671.653133874644;8678.218960293572;8185.222403871638;8478;8541.017782954961;8593.828123437543;8608.225259726623;2328.3131476214517;2350.0247397330086;2353.9293821185393;2354.788069073783;2361.3860396297;2340.9531809363802;2371.905123795046;2388.10446082719;2395.9041638334447;2403.086827005744;2337.415646088478;2346.507233480971;2356.4815740537056;2364.219807463533;2367.7868991790733;2306.1582522379376;2308.792208701225;2329.3827135809024;2330.6853574767442;2331.883405829719;2333.265021518216;2335.9182428614918;2365.7634236576378;2368.1593034472685;2372.8220383471134;8245.711400101027;8358.916410835875;8389.454685445487;8538.87191692125;8605.784855378603;5131.230315452674;5268.841934741926;5298.788048478056;5299.761510732026;5309.044372013036;3027.8031927924612;3487.773294735834;3490.284491679225;3548.946765798515;3620.606066664327;1058.915286777056;1077.181342179941;1095.1840878545534;1101.8898110188998;1106.80630889594;11096.389698566576;11165.106791456745;11219.382933938712;11241.100711943025;11251.662450126509;4501.144782491317;4600.585947264763;4781.569658730731;4785.234362501983;4796.688215265996;9531.237500999903;9592.664256751119;9593.856092158623;9596.856047159297;9601.663941762004;5242.606804489678;5242.951409718072;5248.501392367719;5265.131253343232;5266.420693723688;4286.828526858953;4307.590778876001;4785.425748910153;4815.6388270879825;4848.0788650156355;3227.370662720747;3828.425736139601;3901.941470877939;3926.1951299983434;3929.724919255682;1254.667513109027;1265.2977597433328;1267.3726505379896;1275.0628288208345;1280.219066369514;743.985120297591;841.3857883744901;892.2861710631469;894.6466145872344;910.9590068446936;5499.615026948075;5528.914526374593;5552.972235138879;5757.481826635623;5758.848230353946;832.1886160993105;877.946464242911;943.528235882059;947.5215016416665;976.5556671714353;3284.408806414868;3347.163209197701;3704.592494825567;3757.098296408842;3796;10144.492775361203;10396.740081498023;10424.791504169874;10521.52653130611;10535.473226338805;10159.288849780445;10182;10490.527926243338;10528.315659482108;10543.31468454548;6766.357196066366;7551.773446796605;7657.885131722917;7954.8067789831985;8138.83722325562;5166.586673066221;5506.972465137648;5595.160725890986;5683.346415162309;5739.856503587277;9012.648102784635;9043.366964312436;9696.806063878825;9711.951440242754;9913.45475998827;9099.226565741987;9474.621015159317;9520.857187142337;9691.5154242289;9797.167240784613;6182.690865456799;6635;7133.5719856808755;7253.310935461122;7410.036695229585;7915.762527124196;8198.59007049657;8212.384071194803;8231.259186673227;8231.547264900488;7235.529690570096;7305.2329505403295;7367.889481657672;7477.476576639583;7513.661885215069;7149.82125446868;7227.530210536298;7249.238829922782;7273.672684729306;7342.559446433231;6713.395794378529;6870.862582748417;6891.724331026903;6902.24075351721;6903.447724182154;4852.2479015752615;4867.5132486751045;4917.483664215206;4975.50244975514;5046.142155833591;4966.602671786176;5035.144025515744;5048.772805223847;5061.089003979319;5120.743962801919 12:41:50 INFO - PROCESS | 1919 | |4;prototype.html;302.51446428482274;303.84352058689774;311.50937273793767;315.77579918258033;316.6200559328806;5107.923381149286;5325;5339;5346.946530534694;5494.2857428534335;4148.315527937887;5220.947790522094;5268.420473747885;5301.6818990860575;5313.601479889014;3438.638942910996;3617.5839778425425;3907.765534067965;4090.8772736817796;4120.814563344657;3185.2514659055105;3902.7268091233627;4113.403556484308;4120;4142.875713728593;4035.374516949863;4035.778032208227;4037.4953130858644;4040.838366465338;4080.6735461163184;3314.585676790401;3956.030772460737;4038.6163314485075;4060.2894493463527;4061.675065994728;3313.9015020955244;3539.734519911017;3554.537910071674;3560.430331147004;3561.3411518868834;4298.229965709284;4701.153792317382;4738.696858363977;5108.748356652643;5185.662931909414;3885.9805700971597;4008.7394319369146;4058.959410405888;4619.69971951822;4647.976760116211;4242.766647834368;5206.635535512516;5507.924136139268;5559.388467268597;5570.275864137677;3544.521489598897;3634.418493041127;3893.1629699614437;3896.4350169225586;3900.258950799339;4844.685095468819;4849.733264670442;4882.853514394538;5062.468440813698;5868.530517558585;3721.027855236729;3840.8285472930465;3846.288436639231;3867.284552357823;3881.84472621092;3433.5021421893684;3924.234774219;3936.2717897189114;3945.743526670757;3952.664023557972;4237.745735255895;4322.286822674224;4477.776111194427;4531.207038768268;4532.841350552715;5096.694198348111;5574.275344205227;5580.776768929279;5581.748821303051;5582.358028826737;998.8851282102651;1024.462157367388;1178.128185142988;1194.7729931313022;1222.7982382906898;777.8560966221268;816.2612835383987;833.5457175839061;858.862007839615;860.5740158621522;4157.605027522381;4596.47140578831;4996.800127994839;4997.750112494361;5041.899162016739;4537.410136682211;5120.795168193231;5303.602229832778;5320.31324181286;5373.474467000504;4423.6903416760515;4609.585137337589;4944.950550494521;5005.0240203160765;5032.874178145503;2798.468291024699;2802.5936239245193;2820.9294767630563;2828.0667379764627;2831.957520637192;5573.885222955425;5657.660540367591;5696.088625819849;5713.74288157034;5718.456746609066;4482.439695038121;4944.901101978012;5349.705766182819;5451.236826844245;5528.562573730778;5128.769205385692;5646.661200327993;5683.2490556233515;5701.743421546041;5827.475527202657;4273.252180868398;4602.7468489233415;4622.445306563234;4700.106979673851;5196.350456192976;4620.907581848412;4907.77914993819;5298.178782288752;5413.323334583177;5847.766089356378;5669.6314739541795;6155.6614386209185;6159.673776160823;6205.782797602063;6234.812955611339;3852.4799152114106;3861.9034524137123;3865.439511270906;3891.2606604745006;3899.1811719539214;5217.373915130057;5367.275417818701;5455.672659640434;5746.213086652886;5846.853828654318;4631.745254011062;5493.807716729895;5662.405447428126;5677.545796336366;5678.858028549319;5643.181738647955;5665.402469431858;5684.488396044376;5689.63017403884;5724.914126288026;5232.790688372575;5332.30680011596;5348.9465105348445;5542.944570554243;5574.581906356958;4689.812407503661;5427.592930530147;5593.356763972195;5606.327240731139;5678.403767604342;4847.757612119451;5521.723913804213;5554.2779438672715;5557;5584.246126772996 12:41:50 INFO - PROCESS | 1919 | |5;yui.html;681.536555142503;694.7915625312411;721.9783406497806;736.6648175080339;756.4024420707641;13950.372233249504;14814.185219812905;14887.702245955074;14892.329845156966;14913.5525934222;13147.934260328684;13216.73566528669;13229.272390018545;13269.20384776914;13311.201327920307;644.4747530762426;675.1358261425383;802.2539038694013;819.7704642700054;837.7193640130533;814.3647954595425;828.8549503836834;830.0951962361025;830.9792255193609;832.6586099699125;853.3216093205885;869.148234729965;871.6034204436997;874.5102742464209;876.7063033883657;800.8038030682462;804.1958041958071;871.9084496127911;872.7818045488627;876.4434584039117;571.711285800668;650.6584043377218;656.4321861589691;659.8911179655352;660.8380946668049;11312.660620181408;13224.801627975588;13427.060105792598;13490.797638035438;13540.322983850867;10811.891881081247;11167.944160279147;13298.468061277537;13461.394237259346;13546.593602191851;11067.169962252863;11237.719057023558;11259;11287.15346349019;11443.313401195956;3292.818894960776;3309.96690033099;3569.23261498779;3589.46158076288;3669.43123815809;89044.10955890488;94365.5281723587;94944.52527737386;95757.04242957481;96500.51749741206;8922.152395522415;9812.754681132887;10180.592776289013;10201.74495637615;10473.266871318934;20181.697274540893;20494.590108198052;20569.58860822775;20594.176232950515;20799.06404211814;7286.307800758919;7308.488405811649;7313.963430182815;7452.739154129579;7464.1416237132335;3789.981050094732;4031.677465802729;4059.106996460774;4063.1264278180333;4085.5301640311745;3302.8018318900945;3391.8304084796155;3437.05480992724;3470.6008808986785;3482.094655389617;578.4851482180862;624.2883113250898;631.7536160897254;644.577801539992;647.2685864972646;561.3824792728049;615.5229696984873;637.0603360043898;652.2238536142071;666.3303380102966;8045.235702608243;8189.126991242981;8452.957735211285;8515.935508061491;9078.591463384164;635.2572742408666;643.8583511627435;659.4032400677395;668.2482207516543;669.3087621765965;4344.869653910388;4402.889927751768;4443.066955939289;4448.399466072104;4467.195904737178;86371.56814215888;93104.67238318961;102449.48775256076;115560;124273;72551;85553;86776.56611716902;105230;106709;71982;75785;77301.2269877294;85773.71339429913;87350.12649873547;9147.58835852375;9539.284553658643;9577.856332155163;9648.951755241178;9751.853722194315;43097;48508.5149148504;51936.74031629818;52309.7384513075;54754.63113422037;62803.37196627975;71003.86988390356;75066;77975.2202477968;78762.21237787549;10261.640842570474;10599.417032063311;11971.16201865861;11986;12425.751484970431;11938.76122477563;13142.737145257233;14022.509212177525;14076.648083797987;14277.571672849832;12434.62696119118;12706.36468176606;12958;13021.869781302066;13166.144200626928;12351.197172184138;12820.807687884502;12979;13017.804732928824;13037.087403882017;12536;12628.684282893002;12729;12751;12768.553100641433;9679.806403872024;10108.797824043624;10423.061924426838;10685.984831441001;10948.56205751793;10658.307210031324;11014.118870490182;11211.887881121085;11216.66350009501;11291.887081129084 12:41:50 INFO - PROCESS | 1919 | __end_tp_report 12:41:50 INFO - PROCESS | 1919 | __start_cc_report 12:41:50 INFO - PROCESS | 1919 | _x_x_mozilla_cycle_collect,379 12:41:50 INFO - PROCESS | 1919 | __end_cc_report 12:41:50 INFO - PROCESS | 1919 | __startTimestamp1484858510455__endTimestamp 12:41:50 INFO - PROCESS | 1919 | 12:41:50 INFO - PROCESS | 1919 | ------- Summary: start ------- 12:41:50 INFO - PROCESS | 1919 | Number of tests: 6 12:41:50 INFO - PROCESS | 1919 | 12:41:50 INFO - PROCESS | 1919 | [#0] dojo.html Cycles:165 Average:5529.81 Median:5749.80 stddev:674.71 (11.7%) stddev-sans-first:653.93 12:41:50 INFO - PROCESS | 1919 | Values: 3310.6 3321.9 3365.5 3382.3 3384.5 4589.2 5521.5 5524.7 5557.5 5631.0 5564.4 5581.1 5596.2 5606.3 5629.8 4879.7 5086.5 5129.2 5161.9 5201.9 5052.7 5143.6 5157.8 5173.5 5205.1 4996.0 5030.8 5058.6 5079.4 5105.1 4997.4 5037.1 5059.2 5081.1 5106.4 4957.2 4962.7 5027.8 5053.3 5106.4 5002.3 5098.5 5482.3 5505.1 5513.3 4893.2 4989.1 5921.9 5972.1 6069.4 6068.8 6114.1 6120.3 6135.8 6152.0 5462.9 5512.6 5639.3 5657.7 5680.1 6114.0 6135.6 6203.9 6215.1 6229.6 5971.6 5974.6 5984.8 5992.6 6028.1 5908.6 6108.8 6130.2 6140.5 6145.5 5868.3 5901.8 5959.1 5968.9 6032.7 6040.6 6108.3 6111.7 6137.6 6144.2 4760.5 4762.8 4785.4 4815.2 4821.2 3452.4 3651.9 4227.2 4307.1 4307.8 4513.6 5714.8 5778.3 5832.0 5838.4 5978.7 6176.6 6183.4 6183.4 6204.9 4507.8 5648.4 5683.3 5749.8 5826.2 5228.7 5263.6 5266.4 5271.9 5277.4 5463.9 6232.8 6306.9 6311.1 6332.9 5980.0 6029.4 6097.5 6119.1 6149.1 6282.8 6295.4 6302.6 6312.2 6317.4 5999.7 6030.3 6080.1 6081.1 6102.2 5888.3 6002.6 6010.4 6170.0 6173.6 5705.1 5780.7 5785.3 5785.9 5796.6 5699.9 5731.6 5732.7 5768.3 5768.3 4153.2 4827.8 4876.8 5503.4 5595.3 5762.5 5799.6 5813.0 5823.3 5836.2 5751.5 5752.2 5758.4 5775.7 5780.4 12:41:50 INFO - PROCESS | 1919 | 12:41:50 INFO - PROCESS | 1919 | [#1] ext.html Cycles:180 Average:71117.31 Median:39441.40 stddev:157840.05 (400.2%) stddev-sans-first:158193.69 12:41:50 INFO - PROCESS | 1919 | Values: 462.4 472.9 497.2 529.3 529.9 38071.0 39646.0 39738.0 39821.4 40070.8 39958.4 40300.0 40457.8 40486.2 40552.2 9267.7 9376.3 9404.3 9414.5 9431.5 9210.8 9275.3 9289.4 9307.7 9326.0 7343.9 8015.3 8548.7 9034.3 9258.0 8634.1 8824.5 9320.7 9373.1 9378.0 9320.6 9410.1 9410.5 9421.7 9452.3 32852.0 38562.2 38945.4 38988.6 39236.8 3169.7 3319.3 3321.4 3326.3 3726.0 29210.6 29263.7 29424.6 29457.0 29678.7 3115.6 3115.7 3149.7 3157.7 3181.8 507119.0 510534.0 512242.0 592910.0 598110.0 59488.0 60002.7 60090.0 60262.0 60387.7 62035.7 62278.7 62348.7 62696.7 62715.4 15719.7 15868.8 15883.5 16120.2 16181.5 12275.9 12286.5 12303.5 12317.1 12318.0 18259.0 18289.3 18474.0 18494.0 18505.0 6728.5 7765.9 7827.6 7833.6 7975.4 872.4 921.6 934.9 953.9 1000.2 23518.2 28567.6 29643.7 29689.0 29696.9 984.4 1026.7 1049.1 1052.7 1081.8 13833.9 15058.3 15360.3 15374.0 15659.3 627929.0 637012.0 637500.0 638841.0 641238.0 56428.0 56620.0 56802.4 56893.4 56938.1 1241.3 1265.7 1269.2 1278.2 1318.2 28331.3 28758.6 29234.7 29350.3 29661.0 47928.8 48083.0 48142.5 48224.0 48244.0 580105.0 582318.0 584276.0 585451.0 586828.0 25481.2 25733.6 25959.6 25967.0 26017.5 44322.0 44824.8 44922.6 46081.8 46363.5 36294.0 37564.4 37586.4 38533.6 38700.0 30888.4 36981.4 37136.4 37228.4 37303.4 31106.5 32518.7 34523.5 37858.8 38394.2 32732.0 34112.3 34572.0 34582.0 34662.5 24714.3 24936.0 25374.6 25399.7 25456.1 12:41:50 INFO - PROCESS | 1919 | 12:41:50 INFO - PROCESS | 1919 | [#2] jquery.html Cycles:180 Average:34635.05 Median:372.84 stddev:99890.25 (26791.5%) stddev-sans-first:100137.33 12:41:50 INFO - PROCESS | 1919 | Values: 371.8 373.9 380.6 384.9 398.7 18400.9 19708.7 20177.0 20421.8 20583.2 19709.9 19751.4 19884.2 19991.3 20280.7 4791.9 5466.2 5701.9 6038.8 6128.7 5959.1 6078.0 6078.6 6082.8 6088.1 5732.1 5974.6 5990.4 5994.4 6002.3 5717.5 5953.7 5989.4 6006.2 6021.4 5175.6 5224.7 5243.2 5250.7 5250.8 24441.4 24584.0 24603.6 24650.0 25153.2 2034.8 2154.6 2208.8 2327.0 2915.1 5692.0 9897.2 9963.8 9988.1 10343.7 1555.6 1662.7 1916.4 1925.8 1929.0 116050.0 116970.0 119305.0 120943.0 122525.0 9878.3 11503.9 11669.3 11757.8 11761.5 12145.6 14674.7 14681.1 14692.9 14694.3 7585.4 8215.0 8473.3 8492.1 8613.3 8329.3 8393.2 8401.3 8515.5 8658.0 4444.7 4500.2 4606.6 4633.0 4712.6 1561.0 1790.7 1908.8 1955.1 1960.6 867.9 900.3 924.6 957.9 985.9 20150.6 20269.5 21878.7 22346.6 22562.8 932.8 943.0 967.0 1002.4 1018.8 10874.6 11106.4 11113.5 11397.7 11399.7 536573.0 605369.0 610119.0 614853.9 624290.0 82415.0 121800.0 129827.0 130416.0 134271.3 834.8 953.7 989.3 992.8 993.8 18820.8 19378.8 19890.1 19950.9 19959.7 38741.4 44786.0 51000.5 54066.0 54743.0 75669.0 79722.0 79739.6 80769.2 81559.0 5774.3 5875.4 6599.5 7074.7 7846.2 11563.8 12083.7 12378.3 13029.7 13250.3 10969.9 11107.9 11168.4 11410.5 11439.3 9449.2 10723.2 10815.6 11056.2 11104.1 11266.9 11277.1 11337.6 11715.8 11730.6 7215.9 7400.1 7547.4 7557.0 7675.8 10503.6 10937.1 11027.4 11276.6 11315.5 12:41:50 INFO - PROCESS | 1919 | 12:41:50 INFO - PROCESS | 1919 | [#3] mootools.html Cycles:180 Average:5530.09 Median:5148.91 stddev:3056.59 (59.4%) stddev-sans-first:3049.73 12:41:50 INFO - PROCESS | 1919 | Values: 1441.8 1462.3 1643.6 1785.6 1797.1 7585.2 7846.9 8194.3 8671.7 8678.2 8185.2 8478.0 8541.0 8593.8 8608.2 2328.3 2350.0 2353.9 2354.8 2361.4 2341.0 2371.9 2388.1 2395.9 2403.1 2337.4 2346.5 2356.5 2364.2 2367.8 2306.2 2308.8 2329.4 2330.7 2331.9 2333.3 2335.9 2365.8 2368.2 2372.8 8245.7 8358.9 8389.5 8538.9 8605.8 5131.2 5268.8 5298.8 5299.8 5309.0 3027.8 3487.8 3490.3 3548.9 3620.6 1058.9 1077.2 1095.2 1101.9 1106.8 11096.4 11165.1 11219.4 11241.1 11251.7 4501.1 4600.6 4781.6 4785.2 4796.7 9531.2 9592.7 9593.9 9596.9 9601.7 5242.6 5243.0 5248.5 5265.1 5266.4 4286.8 4307.6 4785.4 4815.6 4848.1 3227.4 3828.4 3901.9 3926.2 3929.7 1254.7 1265.3 1267.4 1275.1 1280.2 744.0 841.4 892.3 894.6 911.0 5499.6 5528.9 5553.0 5757.5 5758.8 832.2 877.9 943.5 947.5 976.6 3284.4 3347.2 3704.6 3757.1 3796.0 10144.5 10396.7 10424.8 10521.5 10535.5 10159.3 10182.0 10490.5 10528.3 10543.3 6766.4 7551.8 7657.9 7954.8 8138.8 5166.6 5507.0 5595.2 5683.3 5739.9 9012.6 9043.4 9696.8 9712.0 9913.5 9099.2 9474.6 9520.9 9691.5 9797.2 6182.7 6635.0 7133.6 7253.3 7410.0 7915.8 8198.6 8212.4 8231.3 8231.5 7235.5 7305.2 7367.9 7477.5 7513.7 7149.8 7227.5 7249.2 7273.7 7342.6 6713.4 6870.9 6891.7 6902.2 6903.4 4852.2 4867.5 4917.5 4975.5 5046.1 4966.6 5035.1 5048.8 5061.1 5120.7 12:41:50 INFO - PROCESS | 1919 | 12:41:50 INFO - PROCESS | 1919 | [#4] prototype.html Cycles:180 Average:4421.13 Median:4895.32 stddev:1369.19 (28.0%) stddev-sans-first:1337.68 12:41:50 INFO - PROCESS | 1919 | Values: 302.5 303.8 311.5 315.8 316.6 5107.9 5325.0 5339.0 5346.9 5494.3 4148.3 5220.9 5268.4 5301.7 5313.6 3438.6 3617.6 3907.8 4090.9 4120.8 3185.3 3902.7 4113.4 4120.0 4142.9 4035.4 4035.8 4037.5 4040.8 4080.7 3314.6 3956.0 4038.6 4060.3 4061.7 3313.9 3539.7 3554.5 3560.4 3561.3 4298.2 4701.2 4738.7 5108.7 5185.7 3886.0 4008.7 4059.0 4619.7 4648.0 4242.8 5206.6 5507.9 5559.4 5570.3 3544.5 3634.4 3893.2 3896.4 3900.3 4844.7 4849.7 4882.9 5062.5 5868.5 3721.0 3840.8 3846.3 3867.3 3881.8 3433.5 3924.2 3936.3 3945.7 3952.7 4237.7 4322.3 4477.8 4531.2 4532.8 5096.7 5574.3 5580.8 5581.7 5582.4 998.9 1024.5 1178.1 1194.8 1222.8 777.9 816.3 833.5 858.9 860.6 4157.6 4596.5 4996.8 4997.8 5041.9 4537.4 5120.8 5303.6 5320.3 5373.5 4423.7 4609.6 4945.0 5005.0 5032.9 2798.5 2802.6 2820.9 2828.1 2832.0 5573.9 5657.7 5696.1 5713.7 5718.5 4482.4 4944.9 5349.7 5451.2 5528.6 5128.8 5646.7 5683.2 5701.7 5827.5 4273.3 4602.7 4622.4 4700.1 5196.4 4620.9 4907.8 5298.2 5413.3 5847.8 5669.6 6155.7 6159.7 6205.8 6234.8 3852.5 3861.9 3865.4 3891.3 3899.2 5217.4 5367.3 5455.7 5746.2 5846.9 4631.7 5493.8 5662.4 5677.5 5678.9 5643.2 5665.4 5684.5 5689.6 5724.9 5232.8 5332.3 5348.9 5542.9 5574.6 4689.8 5427.6 5593.4 5606.3 5678.4 4847.8 5521.7 5554.3 5557.0 5584.2 12:41:50 INFO - PROCESS | 1919 | 12:41:50 INFO - PROCESS | 1919 | [#5] yui.html Cycles:180 Average:19995.13 Median:4445.73 stddev:29445.87 (662.3%) stddev-sans-first:29492.76 12:41:50 INFO - PROCESS | 1919 | Values: 681.5 694.8 722.0 736.7 756.4 13950.4 14814.2 14887.7 14892.3 14913.6 13147.9 13216.7 13229.3 13269.2 13311.2 644.5 675.1 802.3 819.8 837.7 814.4 828.9 830.1 831.0 832.7 853.3 869.1 871.6 874.5 876.7 800.8 804.2 871.9 872.8 876.4 571.7 650.7 656.4 659.9 660.8 11312.7 13224.8 13427.1 13490.8 13540.3 10811.9 11167.9 13298.5 13461.4 13546.6 11067.2 11237.7 11259.0 11287.2 11443.3 3292.8 3310.0 3569.2 3589.5 3669.4 89044.1 94365.5 94944.5 95757.0 96500.5 8922.2 9812.8 10180.6 10201.7 10473.3 20181.7 20494.6 20569.6 20594.2 20799.1 7286.3 7308.5 7314.0 7452.7 7464.1 3790.0 4031.7 4059.1 4063.1 4085.5 3302.8 3391.8 3437.1 3470.6 3482.1 578.5 624.3 631.8 644.6 647.3 561.4 615.5 637.1 652.2 666.3 8045.2 8189.1 8453.0 8515.9 9078.6 635.3 643.9 659.4 668.2 669.3 4344.9 4402.9 4443.1 4448.4 4467.2 86371.6 93104.7 102449.5 115560.0 124273.0 72551.0 85553.0 86776.6 105230.0 106709.0 71982.0 75785.0 77301.2 85773.7 87350.1 9147.6 9539.3 9577.9 9649.0 9751.9 43097.0 48508.5 51936.7 52309.7 54754.6 62803.4 71003.9 75066.0 77975.2 78762.2 10261.6 10599.4 11971.2 11986.0 12425.8 11938.8 13142.7 14022.5 14076.6 14277.6 12434.6 12706.4 12958.0 13021.9 13166.1 12351.2 12820.8 12979.0 13017.8 13037.1 12536.0 12628.7 12729.0 12751.0 12768.6 9679.8 10108.8 10423.1 10686.0 10948.6 10658.3 11014.1 11211.9 11216.7 11291.9 12:41:50 INFO - PROCESS | 1919 | -------- Summary: end -------- 12:41:50 INFO - PROCESS | 1919 | 12:41:51 INFO - TEST-INFO | 1919: exit 0 12:41:51 INFO - TEST-OK | dromaeo_css | took 1114328ms 12:41:51 INFO - TEST-START | kraken 12:41:51 INFO - operating with platform_type : mac_ 12:41:51 INFO - Initialising browser for kraken test... 12:41:51 INFO - TEST-INFO | started process 1925 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/tmpVcXJKE/profile http://localhost:56549/getInfo.html) 12:41:54 INFO - PROCESS | 1925 | 2017-01-19 12:41:54.107 plugin-container[1926:20529] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x453b, name = 'com.apple.tsm.portname' 12:41:54 INFO - PROCESS | 1925 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:41:54 INFO - PROCESS | 1925 | 2017-01-19 12:41:54.110 plugin-container[1926:20529] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0f, name = 'com.apple.CFPasteboardClient' 12:41:54 INFO - PROCESS | 1925 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:41:54 INFO - PROCESS | 1925 | 2017-01-19 12:41:54.110 plugin-container[1926:20529] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:41:54 INFO - PROCESS | 1925 | __metrics Screen width/height:1600/1200 12:41:54 INFO - PROCESS | 1925 | colorDepth:24 12:41:54 INFO - PROCESS | 1925 | Browser inner width/height: 1024/663 12:41:54 INFO - PROCESS | 1925 | __metrics 12:41:54 ERROR - PROCESS | 1925 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3181: TypeError: this.tabs is undefined 12:41:55 INFO - TEST-INFO | 1925: exit 0 12:41:55 INFO - Browser initialized. 12:41:55 INFO - Running cycle 1/1 for kraken test... 12:41:55 INFO - TEST-INFO | started process 1927 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/tmpVcXJKE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:41:57 INFO - PROCESS | 1927 | 2017-01-19 12:41:57.949 plugin-container[1928:20644] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4247, name = 'com.apple.tsm.portname' 12:41:57 INFO - PROCESS | 1927 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:41:57 INFO - PROCESS | 1927 | 2017-01-19 12:41:57.949 plugin-container[1928:20644] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 12:41:57 INFO - PROCESS | 1927 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:41:57 INFO - PROCESS | 1927 | 2017-01-19 12:41:57.949 plugin-container[1928:20644] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:42:07 INFO - PROCESS | 1927 | RSS: Main: 242536448 12:42:07 INFO - PROCESS | 1927 | 12:42:10 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56549/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56549/tests/kraken/driver.html?testName=audio-beat-detection) 12:42:11 INFO - PROCESS | 1927 | RSS: Main: 252592128 12:42:11 INFO - PROCESS | 1927 | 12:42:14 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56549/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56549/tests/kraken/driver.html?testName=audio-dft) 12:42:14 INFO - PROCESS | 1927 | RSS: Main: 260599808 12:42:14 INFO - PROCESS | 1927 | 12:42:18 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56549/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56549/tests/kraken/driver.html?testName=audio-fft) 12:42:18 INFO - PROCESS | 1927 | RSS: Main: 261320704 12:42:18 INFO - PROCESS | 1927 | 12:42:20 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56549/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56549/tests/kraken/driver.html?testName=audio-oscillator) 12:42:21 INFO - PROCESS | 1927 | RSS: Main: 261222400 12:42:21 INFO - PROCESS | 1927 | 12:42:23 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56549/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56549/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:42:24 INFO - PROCESS | 1927 | RSS: Main: 261148672 12:42:24 INFO - PROCESS | 1927 | 12:42:26 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56549/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56549/tests/kraken/driver.html?testName=imaging-darkroom) 12:42:27 INFO - PROCESS | 1927 | RSS: Main: 264994816 12:42:27 INFO - PROCESS | 1927 | 12:42:30 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56549/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56549/tests/kraken/driver.html?testName=imaging-desaturate) 12:42:31 INFO - PROCESS | 1927 | RSS: Main: 264556544 12:42:31 INFO - PROCESS | 1927 | 12:42:34 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56549/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56549/tests/kraken/driver.html?testName=json-parse-financial) 12:42:34 INFO - PROCESS | 1927 | RSS: Main: 264806400 12:42:34 INFO - PROCESS | 1927 | 12:42:36 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56549/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56549/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:42:36 INFO - PROCESS | 1927 | RSS: Main: 263675904 12:42:36 INFO - PROCESS | 1927 | 12:42:38 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56549/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56549/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:42:39 INFO - PROCESS | 1927 | RSS: Main: 263827456 12:42:39 INFO - PROCESS | 1927 | 12:42:41 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56549/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56549/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:42:42 INFO - PROCESS | 1927 | RSS: Main: 263897088 12:42:42 INFO - PROCESS | 1927 | 12:42:45 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56549/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56549/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:42:45 INFO - PROCESS | 1927 | RSS: Main: 263798784 12:42:45 INFO - PROCESS | 1927 | 12:42:48 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56549/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56549/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:42:49 INFO - PROCESS | 1927 | RSS: Main: 264228864 12:42:49 INFO - PROCESS | 1927 | 12:42:51 INFO - PROCESS | 1927 | Cycle 1(1): loaded http://localhost:56549/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56549/tests/kraken/driver.html?testName=ai-astar) 12:42:51 INFO - PROCESS | 1927 | RSS: Main: 266092544 12:42:51 INFO - PROCESS | 1927 | 12:42:51 INFO - PROCESS | 1927 | __start_tp_report 12:42:51 INFO - PROCESS | 1927 | _x_x_mozilla_page_load 12:42:51 INFO - PROCESS | 1927 | _x_x_mozilla_page_load_details 12:42:51 INFO - PROCESS | 1927 | |i|pagename|runs| 12:42:51 INFO - PROCESS | 1927 | |0;ai-astar;105;126;177;172;157;116;119;119;119;97 12:42:51 INFO - PROCESS | 1927 | |1;audio-beat-detection;175;141;140;146;116;111;119;114;142;110 12:42:51 INFO - PROCESS | 1927 | |2;audio-dft;201;207;198;197;199;199;195;198;218;199 12:42:51 INFO - PROCESS | 1927 | |3;audio-fft;86;58;61;87;59;62;74;52;93;79 12:42:51 INFO - PROCESS | 1927 | |4;audio-oscillator;89;98;120;100;99;58;104;64;75;119 12:42:51 INFO - PROCESS | 1927 | |5;imaging-gaussian-blur;88;88;92;102;89;85;90;85;88;85 12:42:51 INFO - PROCESS | 1927 | |6;imaging-darkroom;95;96;99;96;96;96;96;96;152;127 12:42:51 INFO - PROCESS | 1927 | |7;imaging-desaturate;74;133;135;91;87;88;89;90;89;89 12:42:51 INFO - PROCESS | 1927 | |8;json-parse-financial;57;57;58;59;60;60;61;59;58;65 12:42:51 INFO - PROCESS | 1927 | |9;json-stringify-tinderbox;69;69;77;80;77;68;71;95;64;70 12:42:51 INFO - PROCESS | 1927 | |10;stanford-crypto-aes;83;86;86;81;79;84;85;86;85;84 12:42:51 INFO - PROCESS | 1927 | |11;stanford-crypto-ccm;150;143;139;141;145;144;188;157;206;176 12:42:51 INFO - PROCESS | 1927 | |12;stanford-crypto-pbkdf2;170;172;202;230;197;164;170;167;168;169 12:42:51 INFO - PROCESS | 1927 | |13;stanford-crypto-sha256-iterative;67;80;87;68;70;70;66;67;66;69 12:42:51 INFO - PROCESS | 1927 | __end_tp_report 12:42:51 INFO - PROCESS | 1927 | __start_cc_report 12:42:51 INFO - PROCESS | 1927 | _x_x_mozilla_cycle_collect,975 12:42:51 INFO - PROCESS | 1927 | __end_cc_report 12:42:51 INFO - PROCESS | 1927 | __startTimestamp1484858571497__endTimestamp 12:42:51 INFO - PROCESS | 1927 | 12:42:51 INFO - PROCESS | 1927 | ------- Summary: start ------- 12:42:51 INFO - PROCESS | 1927 | Number of tests: 14 12:42:51 INFO - PROCESS | 1927 | 12:42:51 INFO - PROCESS | 1927 | [#0] ai-astar Cycles:10 Average:130.70 Median:141.50 stddev:27.86 (19.7%) stddev-sans-first:27.96 12:42:51 INFO - PROCESS | 1927 | Values: 105.0 126.0 177.0 172.0 157.0 116.0 119.0 119.0 119.0 97.0 12:42:51 INFO - PROCESS | 1927 | 12:42:51 INFO - PROCESS | 1927 | [#1] audio-beat-detection Cycles:10 Average:131.40 Median:140.50 stddev:20.98 (14.9%) stddev-sans-first:15.20 12:42:51 INFO - PROCESS | 1927 | Values: 175.0 141.0 140.0 146.0 116.0 111.0 119.0 114.0 142.0 110.0 12:42:51 INFO - PROCESS | 1927 | 12:42:51 INFO - PROCESS | 1927 | [#2] audio-dft Cycles:10 Average:201.10 Median:199.00 stddev:6.72 (3.4%) stddev-sans-first:7.13 12:42:51 INFO - PROCESS | 1927 | Values: 201.0 207.0 198.0 197.0 199.0 199.0 195.0 198.0 218.0 199.0 12:42:51 INFO - PROCESS | 1927 | 12:42:51 INFO - PROCESS | 1927 | [#3] audio-fft Cycles:10 Average:71.10 Median:76.50 stddev:14.50 (19.0%) stddev-sans-first:14.34 12:42:51 INFO - PROCESS | 1927 | Values: 86.0 58.0 61.0 87.0 59.0 62.0 74.0 52.0 93.0 79.0 12:42:51 INFO - PROCESS | 1927 | 12:42:51 INFO - PROCESS | 1927 | [#4] audio-oscillator Cycles:10 Average:92.60 Median:69.50 stddev:21.19 (30.5%) stddev-sans-first:22.43 12:42:51 INFO - PROCESS | 1927 | Values: 89.0 98.0 120.0 100.0 99.0 58.0 104.0 64.0 75.0 119.0 12:42:51 INFO - PROCESS | 1927 | 12:42:51 INFO - PROCESS | 1927 | [#5] imaging-gaussian-blur Cycles:10 Average:89.20 Median:88.00 stddev:5.05 (5.7%) stddev-sans-first:5.34 12:42:51 INFO - PROCESS | 1927 | Values: 88.0 88.0 92.0 102.0 89.0 85.0 90.0 85.0 88.0 85.0 12:42:51 INFO - PROCESS | 1927 | 12:42:51 INFO - PROCESS | 1927 | [#6] imaging-darkroom Cycles:10 Average:104.90 Median:96.00 stddev:19.19 (20.0%) stddev-sans-first:20.02 12:42:51 INFO - PROCESS | 1927 | Values: 95.0 96.0 99.0 96.0 96.0 96.0 96.0 96.0 152.0 127.0 12:42:51 INFO - PROCESS | 1927 | 12:42:51 INFO - PROCESS | 1927 | [#7] imaging-desaturate Cycles:10 Average:96.50 Median:89.00 stddev:20.34 (22.9%) stddev-sans-first:19.88 12:42:51 INFO - PROCESS | 1927 | Values: 74.0 133.0 135.0 91.0 87.0 88.0 89.0 90.0 89.0 89.0 12:42:51 INFO - PROCESS | 1927 | 12:42:51 INFO - PROCESS | 1927 | [#8] json-parse-financial Cycles:10 Average:59.40 Median:59.50 stddev:2.37 (4.0%) stddev-sans-first:2.35 12:42:51 INFO - PROCESS | 1927 | Values: 57.0 57.0 58.0 59.0 60.0 60.0 61.0 59.0 58.0 65.0 12:42:51 INFO - PROCESS | 1927 | 12:42:51 INFO - PROCESS | 1927 | [#9] json-stringify-tinderbox Cycles:10 Average:74.00 Median:74.00 stddev:8.86 (12.0%) stddev-sans-first:9.21 12:42:51 INFO - PROCESS | 1927 | Values: 69.0 69.0 77.0 80.0 77.0 68.0 71.0 95.0 64.0 70.0 12:42:51 INFO - PROCESS | 1927 | 12:42:51 INFO - PROCESS | 1927 | [#10] stanford-crypto-aes Cycles:10 Average:83.90 Median:85.00 stddev:2.33 (2.7%) stddev-sans-first:2.45 12:42:51 INFO - PROCESS | 1927 | Values: 83.0 86.0 86.0 81.0 79.0 84.0 85.0 86.0 85.0 84.0 12:42:51 INFO - PROCESS | 1927 | 12:42:51 INFO - PROCESS | 1927 | [#11] stanford-crypto-ccm Cycles:10 Average:158.90 Median:153.50 stddev:23.15 (15.1%) stddev-sans-first:24.33 12:42:51 INFO - PROCESS | 1927 | Values: 150.0 143.0 139.0 141.0 145.0 144.0 188.0 157.0 206.0 176.0 12:42:51 INFO - PROCESS | 1927 | 12:42:51 INFO - PROCESS | 1927 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.90 Median:171.00 stddev:21.65 (12.7%) stddev-sans-first:22.60 12:42:51 INFO - PROCESS | 1927 | Values: 170.0 172.0 202.0 230.0 197.0 164.0 170.0 167.0 168.0 169.0 12:42:51 INFO - PROCESS | 1927 | 12:42:51 INFO - PROCESS | 1927 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.00 Median:69.50 stddev:6.94 (10.0%) stddev-sans-first:7.21 12:42:51 INFO - PROCESS | 1927 | Values: 67.0 80.0 87.0 68.0 70.0 70.0 66.0 67.0 66.0 69.0 12:42:51 INFO - PROCESS | 1927 | -------- Summary: end -------- 12:42:51 INFO - PROCESS | 1927 | 12:42:52 INFO - TEST-INFO | 1927: exit 0 12:42:52 INFO - TEST-OK | kraken | took 61424ms 12:42:52 INFO - SUITE-END | took 1175s 12:42:52 INFO - Completed test suite (00:19:35) 12:42:52 INFO - javascript benchmark 12:42:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7089.233026909087, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3310.553075334829, 3321.8505167267476, 3365.4951757236413, 3382.3066271414364, 3384.458486642138, 4589.242774942139, 5521.475459831319, 5524.723763811813, 5557.527610153137, 5630.958272719549, 5564.443555644433, 5581.0512212923795, 5596.2165296858475, 5606.299212598426, 5629.774809007645, 4879.682820616648, 5086.491350864902, 5129.179331306992, 5161.9483805162035, 5201.947980520203, 5052.696838189702, 5143.588512918976, 5157.7936882524655, 5173.534381905646, 5205.115130427817, 4995.975020124913, 5030.84907452777, 5058.645894787366, 5079.3650793650795, 5105.055564720538, 4997.400311962551, 5037.143685573461, 5059.16523773577, 5081.085404627165, 5106.412762532319, 4957.231629097496, 4962.702237865739, 5027.798888044474, 5053.317802096743, 5106.412762532319, 5002.274670172805, 5098.464661210556, 5482.259894914176, 5505.146702261116, 5513.3108361454815, 4893.241547560134, 4989.126902791997, 5921.851953701149, 5972.134040564138, 6069.3627169147485, 6068.787592434288, 6114.144019837227, 6120.265568131808, 6135.754569817202, 6152.046432802923, 5462.863428414242, 5512.641678290898, 5639.323281206241, 5657.660540367591, 5680.062789639664, 6114.023656277693, 6135.630783975068, 6203.937960620336, 6215.12988181655, 6229.626222426669, 5971.552133589998, 5974.634670593982, 5984.820455386345, 5992.610480318765, 6028.125921741323, 5908.556858235649, 6108.786192483241, 6130.172426722335, 6140.539459540552, 6145.508359331243, 5868.295804503401, 5901.7639294427745, 5959.106134079922, 5968.850778730566, 6032.698365081728, 6040.607360521552, 6108.342071131513, 6111.724972376253, 6137.601055931439, 6144.2488890665945, 4760.523947605212, 4762.761861906891, 4785.377900872864, 4815.15734746418, 4821.228603423436, 3452.430348992388, 3651.9409371282563, 4227.175700738388, 4307.117041006586, 4307.784610769449, 4513.616342610915, 5714.828555143353, 5778.27771528559, 5831.970840145772, 5838.416158384128, 5978.671173085522, 6176.567640265138, 6183.350748171377, 6183.412575805291, 6204.875902481925, 4507.752073635916, 5648.406917273627, 5683.318001839806, 5749.798757043567, 5826.213461182686, 5228.712420816815, 5263.5525980292105, 5266.3943646480375, 5271.947280527223, 5277.445868183862, 5463.945360546502, 6232.75068997235, 6306.936930630635, 6311.084892690626, 6332.905006424908, 5980.0, 6029.366916473882, 6097.542684298606, 6119.07606531266, 6149.0776383542825, 6282.780102696383, 6295.370462953668, 6302.590331628613, 6312.242530896322, 6317.368263173646, 5999.670018149044, 6030.306514750684, 6080.0575910733905, 6081.087836824512, 6102.176206212104, 5888.263967004124, 6002.639841609517, 6010.429009244115, 6169.96915015422, 6173.59871608344, 5705.115707065412, 5780.739866705924, 5785.334686511105, 5785.884282314414, 5796.594238403271, 5699.886002279848, 5731.627444216113, 5732.684702341245, 5768.2501274834, 5768.278965129359, 4153.190127925026, 4827.831025914076, 4876.756162191805, 5503.394626591152, 5595.272614560081, 5762.5389968801555, 5799.594028417971, 5812.9709351452975, 5823.272090988627, 5836.212111364912, 5751.48236658686, 5752.223449834219, 5758.395368486249, 5775.7400916957995, 5780.3930587289415], "value": 5487.289627262543, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [462.4081176094598, 472.9101470720563, 497.1647631978276, 529.3118945371021, 529.9019681358949, 38071.0, 39646.0, 39738.00654983627, 39821.402678959836, 40070.79964600177, 39958.400623990594, 40300.0, 40457.79771101148, 40486.19027619446, 40552.18895622086, 9267.675631352904, 9376.343655944087, 9404.341696081277, 9414.52927353633, 9431.481268530228, 9210.815783684322, 9275.257979361635, 9289.396189247711, 9307.674231401903, 9326.020767819384, 7343.926560734378, 8015.278624923756, 8548.674955381895, 9034.322425818056, 9258.027907069762, 8634.136586341316, 8824.470531768115, 9320.673776417827, 9373.109554592336, 9378.0, 9320.627174913063, 9410.058994100604, 9410.482423466707, 9421.670241541513, 9452.338336316461, 32852.014439566614, 38562.228755425014, 38945.41581876274, 38988.63539776122, 39236.803815981024, 3169.6988786065285, 3319.319539494422, 3321.4021476134285, 3326.3347330533747, 3725.9627403725885, 29210.561841572395, 29263.707362926525, 29424.55863162033, 29457.0, 29678.703212968026, 3115.5638210650527, 3115.672854350306, 3149.700778426046, 3157.6526582075953, 3181.809091454497, 507119.0, 510534.0, 512242.0, 592910.0, 598110.0, 59488.0, 60002.699986499785, 60090.0, 60262.0, 60387.698061509414, 62035.6898215515, 62278.68860655668, 62348.68825655933, 62696.686516568036, 62715.372846271865, 15719.68560628781, 15868.761968570481, 15883.52349429519, 16120.193990300439, 16181.514554563382, 12275.877241227652, 12286.508539658493, 12303.507859685513, 12317.137800354069, 12318.0, 18259.0, 18289.26842926268, 18474.0, 18494.0, 18505.0, 6728.461723062242, 7765.883511747328, 7827.608619568999, 7833.5691536966015, 7975.441719079725, 872.4416373520952, 921.5714692667941, 934.8921527989219, 953.93322467428, 1000.2298230362726, 23518.163488737464, 28567.57148642772, 29643.703562964525, 29689.0, 29696.851515742714, 984.3552473130111, 1026.6612018034032, 1049.0977759127145, 1052.736815796051, 1081.848541204232, 13833.861661383458, 15058.32237549313, 15360.308786104652, 15374.0, 15659.295331709873, 627929.0, 637012.0, 637500.0, 638841.0, 641238.0, 56428.0, 56620.0, 56802.43197567971, 56893.43106568964, 56938.145927811114, 1241.3296819717432, 1265.7278685082752, 1269.2004037456343, 1278.1628033637983, 1318.202487495071, 28331.291717707223, 28758.568621470276, 29234.707652924048, 29350.266243344086, 29660.961866334575, 47928.76035619799, 48083.0, 48142.51857481521, 48224.0, 48244.0, 580105.0, 582318.0, 584276.0, 585451.0, 586828.0, 25481.235562933143, 25733.613995789703, 25959.61060584055, 25967.0, 26017.47965040651, 44322.0, 44824.77587612041, 44922.550774491836, 46081.76959115317, 46363.53636463592, 36294.0, 37564.43653345147, 37586.436203456426, 38533.614663853004, 38700.03249918774, 30888.38223235478, 36981.44527832031, 37136.442953356265, 37228.44157337588, 37303.44044839384, 31106.533401998535, 32518.674813251564, 34523.48214776831, 37858.81070594629, 38394.23211535809, 32732.0, 34112.317753645286, 34572.0, 34582.0, 34662.480062798575, 24714.258572242863, 24936.0, 25374.619380709675, 25399.746002539738, 25456.109036183643], "value": 19434.963450945346, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.754641936322, 373.9326921154193, 380.5642539292509, 384.89992601923524, 398.68703068091554, 18400.90799546002, 19708.70436943447, 20177.0, 20421.795782042194, 20583.176672933103, 19709.90145049276, 19751.40745777629, 19884.20463181471, 19991.300304489345, 20280.695789563168, 4791.928121078169, 5466.207399927008, 5701.916635839178, 6038.849028774293, 6128.693565321739, 5959.1359252908305, 6077.969610151965, 6078.604890682113, 6082.847928801793, 6088.1476593276975, 5732.0828667413225, 5974.641521508702, 5990.400959903996, 5994.370591087938, 6002.279726432834, 5717.485426311652, 5953.732082056296, 5989.431004054608, 6006.21919150512, 6021.367756385582, 5175.585953123741, 5224.660397074178, 5243.187305967581, 5250.65870718402, 5250.789968401259, 24441.388965275833, 24584.0, 24603.63094553583, 24650.0, 25153.24540263795, 2034.7761746207905, 2154.5906277807167, 2208.8012078912816, 2326.953460930772, 2915.1254623613045, 5691.971540142315, 9897.15874150698, 9963.754530683664, 9988.101070903653, 10343.689689309333, 1555.60332115311, 1662.6924019056514, 1916.367598692442, 1925.8149491261504, 1928.980710192894, 116049.99999999916, 116970.0, 119305.0, 120943.0, 122525.0, 9878.308518403643, 11503.942480287713, 11669.299842009506, 11757.76484470323, 11761.529538818522, 12145.574904878464, 14674.706505869823, 14681.119132852064, 14692.853071469148, 14694.265286735621, 7585.393168546503, 8215.0, 8473.279771219391, 8492.141429347652, 8613.26787223093, 8329.292010179202, 8393.160683931557, 8401.285890699359, 8515.489070655656, 8658.0, 4444.73331600105, 4500.234960056799, 4606.585407313358, 4633.0, 4712.552307530779, 1560.9853595163236, 1790.6955817511055, 1908.8091190881078, 1955.1397385150487, 1960.6372821028156, 867.8698195270633, 900.2572877376191, 924.5977999570208, 957.8658987741721, 985.8521221816785, 20150.59698806045, 20269.493262668257, 21878.671819922714, 22346.55306893853, 22562.774372256066, 932.7574830544107, 943.0, 966.9951650241844, 1002.4185972136144, 1018.8115198688266, 10874.619388321371, 11106.389148596743, 11113.499892504695, 11397.658070257905, 11399.715007124889, 536573.0, 605369.0, 610119.0, 614853.8514614886, 624290.0, 82415.0, 121800.0, 129827.0, 130416.0, 134271.3286433581, 834.7579202031465, 953.7091187187974, 989.2531138990016, 992.7766252593253, 993.7714325704984, 18820.811791881904, 19378.8062119377, 19890.104945277788, 19950.90024549926, 19959.700604491234, 38741.418878717406, 44786.0, 51000.489995099575, 54066.0, 54743.0, 75669.0, 79722.00694982671, 79739.60130199311, 80769.19230807616, 81559.0, 5774.307083150049, 5875.441833025856, 6599.505037122139, 7074.68163932614, 7846.17615150401, 11563.843615638705, 12083.662233342891, 12378.319192444142, 13029.739405212033, 13250.337483125997, 10969.945150274198, 11107.944460277648, 11168.441577920908, 11410.486528106421, 11439.256448330832, 9449.196818270524, 10723.19576031785, 10815.621453249414, 11056.226064175753, 11104.111671066134, 11266.94366528195, 11277.097832173571, 11337.603183888525, 11715.765684686088, 11730.648080557597, 7215.891761623475, 7400.111986561647, 7547.358474529727, 7557.0, 7675.808104797201, 10503.579856805947, 10937.12502999774, 11027.448627568428, 11276.605318813803, 11315.490802913724], "value": 9395.974147739687, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1441.8486058963813, 1462.3054049326568, 1643.6137507685692, 1785.6339450412668, 1797.1283927295267, 7585.203553626872, 7846.882296765553, 8194.344452443805, 8671.653133874644, 8678.218960293572, 8185.222403871638, 8478.0, 8541.017782954961, 8593.828123437543, 8608.225259726623, 2328.3131476214517, 2350.0247397330086, 2353.9293821185393, 2354.788069073783, 2361.3860396297, 2340.9531809363802, 2371.905123795046, 2388.10446082719, 2395.9041638334447, 2403.086827005744, 2337.415646088478, 2346.507233480971, 2356.4815740537056, 2364.219807463533, 2367.7868991790733, 2306.1582522379376, 2308.792208701225, 2329.3827135809024, 2330.6853574767442, 2331.883405829719, 2333.265021518216, 2335.9182428614918, 2365.7634236576378, 2368.1593034472685, 2372.8220383471134, 8245.711400101027, 8358.916410835875, 8389.454685445487, 8538.87191692125, 8605.784855378603, 5131.230315452674, 5268.841934741926, 5298.788048478056, 5299.761510732026, 5309.044372013036, 3027.8031927924612, 3487.773294735834, 3490.284491679225, 3548.946765798515, 3620.606066664327, 1058.915286777056, 1077.181342179941, 1095.1840878545534, 1101.8898110188998, 1106.80630889594, 11096.389698566576, 11165.106791456745, 11219.382933938712, 11241.100711943025, 11251.662450126509, 4501.144782491317, 4600.585947264763, 4781.569658730731, 4785.234362501983, 4796.688215265996, 9531.237500999903, 9592.664256751119, 9593.856092158623, 9596.856047159297, 9601.663941762004, 5242.606804489678, 5242.951409718072, 5248.501392367719, 5265.131253343232, 5266.420693723688, 4286.828526858953, 4307.590778876001, 4785.425748910153, 4815.6388270879825, 4848.0788650156355, 3227.370662720747, 3828.425736139601, 3901.941470877939, 3926.1951299983434, 3929.724919255682, 1254.667513109027, 1265.2977597433328, 1267.3726505379896, 1275.0628288208345, 1280.219066369514, 743.985120297591, 841.3857883744901, 892.2861710631469, 894.6466145872344, 910.9590068446936, 5499.615026948075, 5528.914526374593, 5552.972235138879, 5757.481826635623, 5758.848230353946, 832.1886160993105, 877.946464242911, 943.528235882059, 947.5215016416665, 976.5556671714353, 3284.408806414868, 3347.163209197701, 3704.592494825567, 3757.098296408842, 3796.0, 10144.492775361203, 10396.740081498023, 10424.791504169874, 10521.52653130611, 10535.473226338805, 10159.288849780445, 10182.0, 10490.527926243338, 10528.315659482108, 10543.31468454548, 6766.357196066366, 7551.773446796605, 7657.885131722917, 7954.8067789831985, 8138.83722325562, 5166.586673066221, 5506.972465137648, 5595.160725890986, 5683.346415162309, 5739.856503587277, 9012.648102784635, 9043.366964312436, 9696.806063878825, 9711.951440242754, 9913.45475998827, 9099.226565741987, 9474.621015159317, 9520.857187142337, 9691.5154242289, 9797.167240784613, 6182.690865456799, 6635.0, 7133.5719856808755, 7253.310935461122, 7410.036695229585, 7915.762527124196, 8198.59007049657, 8212.384071194803, 8231.259186673227, 8231.547264900488, 7235.529690570096, 7305.2329505403295, 7367.889481657672, 7477.476576639583, 7513.661885215069, 7149.82125446868, 7227.530210536298, 7249.238829922782, 7273.672684729306, 7342.559446433231, 6713.395794378529, 6870.862582748417, 6891.724331026903, 6902.24075351721, 6903.447724182154, 4852.2479015752615, 4867.5132486751045, 4917.483664215206, 4975.50244975514, 5046.142155833591, 4966.602671786176, 5035.144025515744, 5048.772805223847, 5061.089003979319, 5120.743962801919], "value": 4472.390802314995, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.51446428482274, 303.84352058689774, 311.50937273793767, 315.77579918258033, 316.6200559328806, 5107.923381149286, 5325.0, 5339.0, 5346.946530534694, 5494.2857428534335, 4148.315527937887, 5220.947790522094, 5268.420473747885, 5301.6818990860575, 5313.601479889014, 3438.638942910996, 3617.5839778425425, 3907.765534067965, 4090.8772736817796, 4120.814563344657, 3185.2514659055105, 3902.7268091233627, 4113.403556484308, 4120.0, 4142.875713728593, 4035.374516949863, 4035.778032208227, 4037.4953130858644, 4040.838366465338, 4080.6735461163184, 3314.585676790401, 3956.030772460737, 4038.6163314485075, 4060.2894493463527, 4061.675065994728, 3313.9015020955244, 3539.734519911017, 3554.537910071674, 3560.430331147004, 3561.3411518868834, 4298.229965709284, 4701.153792317382, 4738.696858363977, 5108.748356652643, 5185.662931909414, 3885.9805700971597, 4008.7394319369146, 4058.959410405888, 4619.69971951822, 4647.976760116211, 4242.766647834368, 5206.635535512516, 5507.924136139268, 5559.388467268597, 5570.275864137677, 3544.521489598897, 3634.418493041127, 3893.1629699614437, 3896.4350169225586, 3900.258950799339, 4844.685095468819, 4849.733264670442, 4882.853514394538, 5062.468440813698, 5868.530517558585, 3721.027855236729, 3840.8285472930465, 3846.288436639231, 3867.284552357823, 3881.84472621092, 3433.5021421893684, 3924.234774219, 3936.2717897189114, 3945.743526670757, 3952.664023557972, 4237.745735255895, 4322.286822674224, 4477.776111194427, 4531.207038768268, 4532.841350552715, 5096.694198348111, 5574.275344205227, 5580.776768929279, 5581.748821303051, 5582.358028826737, 998.8851282102651, 1024.462157367388, 1178.128185142988, 1194.7729931313022, 1222.7982382906898, 777.8560966221268, 816.2612835383987, 833.5457175839061, 858.862007839615, 860.5740158621522, 4157.605027522381, 4596.47140578831, 4996.800127994839, 4997.750112494361, 5041.899162016739, 4537.410136682211, 5120.795168193231, 5303.602229832778, 5320.31324181286, 5373.474467000504, 4423.6903416760515, 4609.585137337589, 4944.950550494521, 5005.0240203160765, 5032.874178145503, 2798.468291024699, 2802.5936239245193, 2820.9294767630563, 2828.0667379764627, 2831.957520637192, 5573.885222955425, 5657.660540367591, 5696.088625819849, 5713.74288157034, 5718.456746609066, 4482.439695038121, 4944.901101978012, 5349.705766182819, 5451.236826844245, 5528.562573730778, 5128.769205385692, 5646.661200327993, 5683.2490556233515, 5701.743421546041, 5827.475527202657, 4273.252180868398, 4602.7468489233415, 4622.445306563234, 4700.106979673851, 5196.350456192976, 4620.907581848412, 4907.77914993819, 5298.178782288752, 5413.323334583177, 5847.766089356378, 5669.6314739541795, 6155.6614386209185, 6159.673776160823, 6205.782797602063, 6234.812955611339, 3852.4799152114106, 3861.9034524137123, 3865.439511270906, 3891.2606604745006, 3899.1811719539214, 5217.373915130057, 5367.275417818701, 5455.672659640434, 5746.213086652886, 5846.853828654318, 4631.745254011062, 5493.807716729895, 5662.405447428126, 5677.545796336366, 5678.858028549319, 5643.181738647955, 5665.402469431858, 5684.488396044376, 5689.63017403884, 5724.914126288026, 5232.790688372575, 5332.30680011596, 5348.9465105348445, 5542.944570554243, 5574.581906356958, 4689.812407503661, 5427.592930530147, 5593.356763972195, 5606.327240731139, 5678.403767604342, 4847.757612119451, 5521.723913804213, 5554.2779438672715, 5557.0, 5584.246126772996], "value": 3979.480267382879, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [681.536555142503, 694.7915625312411, 721.9783406497806, 736.6648175080339, 756.4024420707641, 13950.372233249504, 14814.185219812905, 14887.702245955074, 14892.329845156966, 14913.5525934222, 13147.934260328684, 13216.73566528669, 13229.272390018545, 13269.20384776914, 13311.201327920307, 644.4747530762426, 675.1358261425383, 802.2539038694013, 819.7704642700054, 837.7193640130533, 814.3647954595425, 828.8549503836834, 830.0951962361025, 830.9792255193609, 832.6586099699125, 853.3216093205885, 869.148234729965, 871.6034204436997, 874.5102742464209, 876.7063033883657, 800.8038030682462, 804.1958041958071, 871.9084496127911, 872.7818045488627, 876.4434584039117, 571.711285800668, 650.6584043377218, 656.4321861589691, 659.8911179655352, 660.8380946668049, 11312.660620181408, 13224.801627975588, 13427.060105792598, 13490.797638035438, 13540.322983850867, 10811.891881081247, 11167.944160279147, 13298.468061277537, 13461.394237259346, 13546.593602191851, 11067.169962252863, 11237.719057023558, 11259.0, 11287.15346349019, 11443.313401195956, 3292.818894960776, 3309.96690033099, 3569.23261498779, 3589.46158076288, 3669.43123815809, 89044.10955890488, 94365.5281723587, 94944.52527737386, 95757.04242957481, 96500.51749741206, 8922.152395522415, 9812.754681132887, 10180.592776289013, 10201.74495637615, 10473.266871318934, 20181.697274540893, 20494.590108198052, 20569.58860822775, 20594.176232950515, 20799.06404211814, 7286.307800758919, 7308.488405811649, 7313.963430182815, 7452.739154129579, 7464.1416237132335, 3789.981050094732, 4031.677465802729, 4059.106996460774, 4063.1264278180333, 4085.5301640311745, 3302.8018318900945, 3391.8304084796155, 3437.05480992724, 3470.6008808986785, 3482.094655389617, 578.4851482180862, 624.2883113250898, 631.7536160897254, 644.577801539992, 647.2685864972646, 561.3824792728049, 615.5229696984873, 637.0603360043898, 652.2238536142071, 666.3303380102966, 8045.235702608243, 8189.126991242981, 8452.957735211285, 8515.935508061491, 9078.591463384164, 635.2572742408666, 643.8583511627435, 659.4032400677395, 668.2482207516543, 669.3087621765965, 4344.869653910388, 4402.889927751768, 4443.066955939289, 4448.399466072104, 4467.195904737178, 86371.56814215888, 93104.67238318961, 102449.48775256076, 115560.0, 124273.0, 72551.0, 85553.0, 86776.56611716902, 105230.0, 106709.0, 71982.0, 75785.0, 77301.2269877294, 85773.71339429913, 87350.12649873547, 9147.58835852375, 9539.284553658643, 9577.856332155163, 9648.951755241178, 9751.853722194315, 43097.0, 48508.5149148504, 51936.74031629818, 52309.7384513075, 54754.63113422037, 62803.37196627975, 71003.86988390356, 75066.0, 77975.2202477968, 78762.21237787549, 10261.640842570474, 10599.417032063311, 11971.16201865861, 11986.0, 12425.751484970431, 11938.76122477563, 13142.737145257233, 14022.509212177525, 14076.648083797987, 14277.571672849832, 12434.62696119118, 12706.36468176606, 12958.0, 13021.869781302066, 13166.144200626928, 12351.197172184138, 12820.807687884502, 12979.0, 13017.804732928824, 13037.087403882017, 12536.0, 12628.684282893002, 12729.0, 12751.0, 12768.553100641433, 9679.806403872024, 10108.797824043624, 10423.061924426838, 10685.984831441001, 10948.56205751793, 10658.307210031324, 11014.118870490182, 11211.887881121085, 11216.66350009501, 11291.887081129084], "value": 7117.026655506684, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1545.6000000000004, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 126.0, 177.0, 172.0, 157.0, 116.0, 119.0, 119.0, 119.0, 97.0], "value": 130.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 141.0, 140.0, 146.0, 116.0, 111.0, 119.0, 114.0, 142.0, 110.0], "value": 131.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 207.0, 198.0, 197.0, 199.0, 199.0, 195.0, 198.0, 218.0, 199.0], "value": 201.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 58.0, 61.0, 87.0, 59.0, 62.0, 74.0, 52.0, 93.0, 79.0], "value": 71.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 98.0, 120.0, 100.0, 99.0, 58.0, 104.0, 64.0, 75.0, 119.0], "value": 92.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 92.0, 102.0, 89.0, 85.0, 90.0, 85.0, 88.0, 85.0], "value": 89.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 99.0, 96.0, 96.0, 96.0, 96.0, 96.0, 152.0, 127.0], "value": 104.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 133.0, 135.0, 91.0, 87.0, 88.0, 89.0, 90.0, 89.0, 89.0], "value": 96.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 58.0, 59.0, 60.0, 60.0, 61.0, 59.0, 58.0, 65.0], "value": 59.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 77.0, 80.0, 77.0, 68.0, 71.0, 95.0, 64.0, 70.0], "value": 74.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 86.0, 81.0, 79.0, 84.0, 85.0, 86.0, 85.0, 84.0], "value": 83.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 143.0, 139.0, 141.0, 145.0, 144.0, 188.0, 157.0, 206.0, 176.0], "value": 158.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 172.0, 202.0, 230.0, 197.0, 164.0, 170.0, 167.0, 168.0, 169.0], "value": 180.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 80.0, 87.0, 68.0, 70.0, 70.0, 66.0, 67.0, 66.0, 69.0], "value": 71.0, "unit": "score"}]}]} 12:42:52 INFO - Return code: 0 12:42:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:42:52 ERROR - # TBPL SUCCESS # 12:42:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:42:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:42:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:42:52 INFO - 2017-01-19 12:42:52.835 screenresolution[1932:21036] starting screenresolution argv=screenresolution get 12:42:52 INFO - 2017-01-19 12:42:52.840 screenresolution[1932:21036] Display 0: 1600x1200x32@60 12:42:52 INFO - 2017-01-19 12:42:52.846 screenresolution[1933:21039] starting screenresolution argv=screenresolution list 12:42:52 INFO - Available Modes on Display 0 12:42:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:42:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:42:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:42:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:42:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:42:53 INFO - Intel Iris: 12:42:53 INFO - Chipset Model: Intel Iris 12:42:53 INFO - Type: GPU 12:42:53 INFO - Bus: Built-In 12:42:53 INFO - VRAM (Dynamic, Max): 1536 MB 12:42:53 INFO - Vendor: Intel (0x8086) 12:42:53 INFO - Device ID: 0x0a2e 12:42:53 INFO - Revision ID: 0x0009 12:42:53 INFO - Displays: 12:42:53 INFO - Display: 12:42:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:42:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:42:53 INFO - Display Serial Number: 1600x1200 60 12:42:53 INFO - Main Display: Yes 12:42:53 INFO - Mirror: Off 12:42:53 INFO - Online: Yes 12:42:53 INFO - Rotation: Supported 12:42:53 INFO - Return code: 0 12:42:53 INFO - Running post-action listener: _resource_record_post_action 12:42:53 INFO - [mozharness: 2017-01-19 20:42:53.097062Z] Finished run-tests step (success) 12:42:53 INFO - Running post-run listener: _resource_record_post_run 12:42:53 INFO - Total resource usage - Wall time: 1208s; CPU: 54.0%; Read bytes: 1189376; Write bytes: 658791424; Read time: 13; Write time: 1918 12:42:53 INFO - TinderboxPrint: CPU usage
54.4% 12:42:53 INFO - TinderboxPrint: I/O read bytes / time
1,189,376 / 13 12:42:53 INFO - TinderboxPrint: I/O write bytes / time
658,791,424 / 1,918 12:42:53 INFO - TinderboxPrint: CPU idle
2,204.7 (45.6%) 12:42:53 INFO - TinderboxPrint: CPU system
978.1 (20.2%) 12:42:53 INFO - TinderboxPrint: CPU user
1,650.9 (34.2%) 12:42:53 INFO - TinderboxPrint: Swap in / out
350,433,280 / 0 12:42:53 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 340873728; Write bytes: 335803904; Read time: 26656; Write time: 697 12:42:53 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 1189376; Write bytes: 306259456; Read time: 13; Write time: 1166 12:42:53 INFO - Running post-run listener: _upload_blobber_files 12:42:53 INFO - Blob upload gear active. 12:42:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:42:53 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 12:42:53 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:42:53 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:42:53 INFO - (blobuploader) - INFO - Open directory for files ... 12:42:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:42:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:42:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:42:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:42:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:42:55 INFO - (blobuploader) - INFO - Done attempting. 12:42:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:42:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:42:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:42:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:42:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:42:56 INFO - (blobuploader) - INFO - Done attempting. 12:42:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:42:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:42:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:42:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:42:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:42:59 INFO - (blobuploader) - INFO - Done attempting. 12:42:59 INFO - (blobuploader) - INFO - Iteration through files over. 12:42:59 INFO - Return code: 0 12:42:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:42:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:42:59 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/46733be8d33cc80aef74ac09afe759056002c30cde7c134c9e6121b0c365b05bf5ef89e7eca2702da4a64c0f7cf5a93899bbe4850c1f6fcd9e2e110629eb4563", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3cfce4ee50016c70cd7e49b604b50423205f58e6a75e2ea1cf2f8b3daed575846adf6384741708d36dbab0ac424ca13e1a6926302d6d4e7c0264decde7b710dd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/17a32f852c7b427f6533f16d90c08af33fd443dfbb2e8feca6056034ebfee4c684674025e0db984d0e6ccfebdaf616e833e78f350b66c715bc44713e46031a86"} 12:42:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:42:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:42:59 INFO - Contents: 12:42:59 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/46733be8d33cc80aef74ac09afe759056002c30cde7c134c9e6121b0c365b05bf5ef89e7eca2702da4a64c0f7cf5a93899bbe4850c1f6fcd9e2e110629eb4563", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3cfce4ee50016c70cd7e49b604b50423205f58e6a75e2ea1cf2f8b3daed575846adf6384741708d36dbab0ac424ca13e1a6926302d6d4e7c0264decde7b710dd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/17a32f852c7b427f6533f16d90c08af33fd443dfbb2e8feca6056034ebfee4c684674025e0db984d0e6ccfebdaf616e833e78f350b66c715bc44713e46031a86"} 12:42:59 INFO - Running post-run listener: copy_logs_to_upload_dir 12:42:59 INFO - Copying logs to upload dir... 12:42:59 INFO - mkdir: /builds/slave/test/build/upload/logs 12:42:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.202618 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-01-19 12:42:59.657251) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-19 12:42:59.660765) ========= 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.yaw0vmisAo/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.FQaCmcBLi7/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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/Mozilla-Aurora/sha512/46733be8d33cc80aef74ac09afe759056002c30cde7c134c9e6121b0c365b05bf5ef89e7eca2702da4a64c0f7cf5a93899bbe4850c1f6fcd9e2e110629eb4563", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3cfce4ee50016c70cd7e49b604b50423205f58e6a75e2ea1cf2f8b3daed575846adf6384741708d36dbab0ac424ca13e1a6926302d6d4e7c0264decde7b710dd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/17a32f852c7b427f6533f16d90c08af33fd443dfbb2e8feca6056034ebfee4c684674025e0db984d0e6ccfebdaf616e833e78f350b66c715bc44713e46031a86"} build_url:https://queue.taskcluster.net/v1/task/cdA6A_rMQJKEQZlAaaL9rQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011164 build_url: 'https://queue.taskcluster.net/v1/task/cdA6A_rMQJKEQZlAaaL9rQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/46733be8d33cc80aef74ac09afe759056002c30cde7c134c9e6121b0c365b05bf5ef89e7eca2702da4a64c0f7cf5a93899bbe4850c1f6fcd9e2e110629eb4563", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3cfce4ee50016c70cd7e49b604b50423205f58e6a75e2ea1cf2f8b3daed575846adf6384741708d36dbab0ac424ca13e1a6926302d6d4e7c0264decde7b710dd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/17a32f852c7b427f6533f16d90c08af33fd443dfbb2e8feca6056034ebfee4c684674025e0db984d0e6ccfebdaf616e833e78f350b66c715bc44713e46031a86"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-19 12:42:59.694773) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 12:42:59.695103) ========= 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.yaw0vmisAo/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.FQaCmcBLi7/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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.005128 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 12:42:59.743273) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-19 12:42:59.743601) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-19 12:42:59.743942) ========= ========= Total master_lag: 3.32 =========