builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0263 starttime: 1482059802.96 results: success (0) buildid: 20161218004028 builduid: b3b67421cfc6407aab438558f7ea5c95 revision: eb41245ead336aeba73bc628e413e7b55f40eddd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-18 03:16:42.964212) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-18 03:16:42.964613) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-18 03:16:42.964918) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-18 03:16:43.004715) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 03:16:43.005083) ========= 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.kgoaSsVP8a/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.HsDhBySUJ9/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-18 03:16:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 179M=0s 2016-12-18 03:16:43 (179 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.139925 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 03:16:43.179134) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 03:16:43.179443) ========= 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.kgoaSsVP8a/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.HsDhBySUJ9/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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.027785 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 03:16:43.244308) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-18 03:16:43.244618) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev eb41245ead336aeba73bc628e413e7b55f40eddd --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 eb41245ead336aeba73bc628e413e7b55f40eddd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kgoaSsVP8a/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.HsDhBySUJ9/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-18 03:16:43,367 truncating revision to first 12 chars 2016-12-18 03:16:43,367 Setting DEBUG logging. 2016-12-18 03:16:43,367 attempt 1/10 2016-12-18 03:16:43,367 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/eb41245ead33?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-18 03:16:44,542 unpacking tar archive at: mozilla-aurora-eb41245ead33/testing/mozharness/ program finished with exit code 0 elapsedTime=1.453253 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-18 03:16:44.724011) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-18 03:16:44.724298) ========= script_repo_revision: eb41245ead336aeba73bc628e413e7b55f40eddd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-18 03:16:44.724677) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-18 03:16:44.724964) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-18 03:16:44.744709) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-12-18 03:16:44.745019) ========= /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.kgoaSsVP8a/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.HsDhBySUJ9/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 03:16:44 INFO - MultiFileLogger online at 20161218 03:16:44 in /builds/slave/test 03:16:44 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 03:16:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:16:44 INFO - {'append_to_log': False, 03:16:44 INFO - 'base_work_dir': '/builds/slave/test', 03:16:44 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:16:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:16:44 INFO - 'branch': 'Mozilla-Aurora', 03:16:44 INFO - 'buildbot_json_path': 'buildprops.json', 03:16:44 INFO - 'config_files': ('talos/mac_config.py',), 03:16:44 INFO - 'default_actions': ('clobber', 03:16:44 INFO - 'read-buildbot-config', 03:16:44 INFO - 'download-and-extract', 03:16:44 INFO - 'populate-webroot', 03:16:44 INFO - 'create-virtualenv', 03:16:44 INFO - 'install', 03:16:44 INFO - 'run-tests'), 03:16:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:16:44 INFO - 'download_minidump_stackwalk': True, 03:16:44 INFO - 'download_symbols': 'ondemand', 03:16:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:16:44 INFO - 'tooltool.py': '/tools/tooltool.py', 03:16:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:16:44 INFO - '/tools/misc-python/virtualenv.py')}, 03:16:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:16:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:16:44 INFO - 'installer_path': 'installer.exe', 03:16:44 INFO - 'log_level': 'info', 03:16:44 INFO - 'log_name': 'talos', 03:16:44 INFO - 'log_to_console': True, 03:16:44 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:16:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:16:44 INFO - 'opt_config_files': (), 03:16:44 INFO - 'pip_index': False, 03:16:44 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:16:44 INFO - 'cmd': ('bash', 03:16:44 INFO - '-c', 03:16:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:16:44 INFO - 'enabled': True, 03:16:44 INFO - 'halt_on_failure': False, 03:16:44 INFO - 'name': 'check_screen_resolution'},), 03:16:44 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:16:44 INFO - 'cmd': ('bash', 03:16:44 INFO - '-c', 03:16:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:16:44 INFO - 'enabled': True, 03:16:44 INFO - 'halt_on_failure': False, 03:16:44 INFO - 'name': 'check_screen_resolution'},), 03:16:44 INFO - 'run_cmd_checks_enabled': True, 03:16:44 INFO - 'sps_profile': False, 03:16:44 INFO - 'sps_profile_interval': 0, 03:16:44 INFO - 'suite': 'dromaeojs-e10s', 03:16:44 INFO - 'system_bits': '32', 03:16:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:16:44 INFO - 'title': 't-yosemite-r7-0263', 03:16:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:16:44 INFO - 'use_talos_json': True, 03:16:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:16:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:16:44 INFO - 'work_dir': 'build'} 03:16:44 INFO - [mozharness: 2016-12-18 11:16:44.969892Z] Running clobber step. 03:16:44 INFO - Running pre-action listener: _resource_record_pre_action 03:16:44 INFO - Running main action method: clobber 03:16:44 INFO - rmtree: /builds/slave/test/build 03:16:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:16:47 INFO - Running post-action listener: _resource_record_post_action 03:16:47 INFO - [mozharness: 2016-12-18 11:16:47.138283Z] Finished clobber step (success) 03:16:47 INFO - [mozharness: 2016-12-18 11:16:47.138407Z] Running read-buildbot-config step. 03:16:47 INFO - Running pre-action listener: _resource_record_pre_action 03:16:47 INFO - Running main action method: read_buildbot_config 03:16:47 INFO - Using buildbot properties: 03:16:47 INFO - { 03:16:47 INFO - "project": "", 03:16:47 INFO - "product": "firefox", 03:16:47 INFO - "script_repo_revision": "production", 03:16:47 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 03:16:47 INFO - "repository": "", 03:16:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 03:16:47 INFO - "buildid": "20161218004028", 03:16:47 INFO - "stage_platform": "macosx64", 03:16:47 INFO - "basedir": "/builds/slave/test", 03:16:47 INFO - "nightly_build": "True", 03:16:47 INFO - "buildnumber": 10, 03:16:47 INFO - "slavename": "t-yosemite-r7-0263", 03:16:47 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 03:16:47 INFO - "platform": "yosemite_r7", 03:16:47 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:16:47 INFO - "branch": "mozilla-aurora", 03:16:47 INFO - "repo_path": "releases/mozilla-aurora", 03:16:47 INFO - "revision": "eb41245ead336aeba73bc628e413e7b55f40eddd", 03:16:47 INFO - "pgo_build": "False", 03:16:47 INFO - "builduid": "b3b67421cfc6407aab438558f7ea5c95", 03:16:47 INFO - "slavebuilddir": "test" 03:16:47 INFO - } 03:16:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/Yoy68BNfRWWS2uRXTvaZsg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 03:16:47 INFO - Running post-action listener: _resource_record_post_action 03:16:47 INFO - [mozharness: 2016-12-18 11:16:47.141823Z] Finished read-buildbot-config step (success) 03:16:47 INFO - [mozharness: 2016-12-18 11:16:47.141916Z] Running download-and-extract step. 03:16:47 INFO - Running pre-action listener: _resource_record_pre_action 03:16:47 INFO - Running main action method: download_and_extract 03:16:47 INFO - mkdir: /builds/slave/test/build/tests 03:16:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:16:47 INFO - trying https://queue.taskcluster.net/v1/task/Yoy68BNfRWWS2uRXTvaZsg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 03:16:47 INFO - Downloading https://queue.taskcluster.net/v1/task/Yoy68BNfRWWS2uRXTvaZsg/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 03:16:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yoy68BNfRWWS2uRXTvaZsg/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 03:16:48 INFO - Downloaded 1199 bytes. 03:16:48 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 03:16:48 INFO - Using the following test package requirements: 03:16:48 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:16:48 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:16:48 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 03:16:48 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:16:48 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 03:16:48 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:16:48 INFO - u'jsshell-mac.zip'], 03:16:48 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:16:48 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 03:16:48 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:16:48 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:16:48 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 03:16:48 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:16:48 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 03:16:48 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:16:48 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 03:16:48 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:16:48 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 03:16:48 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:16:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yoy68BNfRWWS2uRXTvaZsg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:16:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yoy68BNfRWWS2uRXTvaZsg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:16:48 INFO - Fetch https://queue.taskcluster.net/v1/task/Yoy68BNfRWWS2uRXTvaZsg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:16:49 INFO - Http code: 200 03:16:49 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 03:16:49 INFO - x-amz-cf-id: hGS1S_G3MDfxOJoecxHADDPRpWw6tl9ORKXNkIICZd0yXWLP2u05Cg== 03:16:49 INFO - x-amz-version-id: IwbKce8mmTvWks43b.5kkdu_HORNbZMj 03:16:49 INFO - Expected file size: 16280566 03:16:49 INFO - Obtained file size: 16280566 03:16:55 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 03:16:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yoy68BNfRWWS2uRXTvaZsg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:16:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yoy68BNfRWWS2uRXTvaZsg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:16:55 INFO - Fetch https://queue.taskcluster.net/v1/task/Yoy68BNfRWWS2uRXTvaZsg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:16:56 INFO - Http code: 200 03:16:56 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront) 03:16:56 INFO - x-amz-cf-id: YpcwXl2PLx4aGidl8OwSCgoBh_xO1c72bEPx2LJo1r_yJxCXiQpp2Q== 03:16:56 INFO - x-amz-version-id: IwbKce8mmTvWks43b.5kkdu_HORNbZMj 03:16:56 INFO - Expected file size: 16280566 03:16:56 INFO - Obtained file size: 16280566 03:17:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yoy68BNfRWWS2uRXTvaZsg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 03:17:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yoy68BNfRWWS2uRXTvaZsg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:17:03 INFO - Fetch https://queue.taskcluster.net/v1/task/Yoy68BNfRWWS2uRXTvaZsg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 03:17:03 INFO - Http code: 200 03:17:03 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 03:17:03 INFO - x-amz-cf-id: r0L9k6y4fxECqw0JlycpxfNaMpRhl2hQCWHX9EiqJrnv69fIkz3yVg== 03:17:03 INFO - x-amz-version-id: KWt1voqKzi0CkV6AGWZVKcSF.U7nbhzU 03:17:04 INFO - Expected file size: 14409038 03:17:04 INFO - Obtained file size: 14409038 03:17:04 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')]} 03:17:04 INFO - trying https://queue.taskcluster.net/v1/task/Yoy68BNfRWWS2uRXTvaZsg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:17:04 INFO - Downloading https://queue.taskcluster.net/v1/task/Yoy68BNfRWWS2uRXTvaZsg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 03:17:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yoy68BNfRWWS2uRXTvaZsg/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 03:17:07 INFO - Downloaded 109833521 bytes. 03:17:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Yoy68BNfRWWS2uRXTvaZsg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:17:07 INFO - mkdir: /builds/slave/test/properties 03:17:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:17:07 INFO - Writing to file /builds/slave/test/properties/build_url 03:17:07 INFO - Contents: 03:17:07 INFO - build_url:https://queue.taskcluster.net/v1/task/Yoy68BNfRWWS2uRXTvaZsg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:17:08 INFO - Running post-action listener: _resource_record_post_action 03:17:08 INFO - Running post-action listener: set_extra_try_arguments 03:17:08 INFO - [mozharness: 2016-12-18 11:17:08.037493Z] Finished download-and-extract step (success) 03:17:08 INFO - [mozharness: 2016-12-18 11:17:08.037617Z] Running populate-webroot step. 03:17:08 INFO - Running pre-action listener: _resource_record_pre_action 03:17:08 INFO - Running main action method: populate_webroot 03:17:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:17:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:17:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:17:08 INFO - '--tppagecycles', 03:17:08 INFO - '7'], 03:17:08 INFO - 'tests': ['tsvgm']}}, 03:17:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:17:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:17:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:17:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:17:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:17:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:17:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:17:08 INFO - 'talos_options': ['--disable-e10s'], 03:17:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:17:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:17:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:17:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:17:08 INFO - 'talos_options': ['--disable-e10s'], 03:17:08 INFO - 'tests': ['damp', 'tps']}, 03:17:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:17:08 INFO - 'tests': ['damp', 'tps']}, 03:17:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:17:08 INFO - 'tests': ['dromaeo_dom']}, 03:17:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:17:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:17:08 INFO - 'tests': ['basic_compositor_video']}, 03:17:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:17:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:17:08 INFO - 'tests': ['a11yr', 03:17:08 INFO - 'ts_paint', 03:17:08 INFO - 'tpaint', 03:17:08 INFO - 'sessionrestore', 03:17:08 INFO - 'sessionrestore_no_auto_restore', 03:17:08 INFO - 'tabpaint']}, 03:17:08 INFO - 'other-e10s': {'tests': ['a11yr', 03:17:08 INFO - 'ts_paint', 03:17:08 INFO - 'tpaint', 03:17:08 INFO - 'sessionrestore', 03:17:08 INFO - 'sessionrestore_no_auto_restore', 03:17:08 INFO - 'tabpaint']}, 03:17:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:17:08 INFO - 'ts_paint', 03:17:08 INFO - 'tpaint', 03:17:08 INFO - 'sessionrestore', 03:17:08 INFO - 'sessionrestore_no_auto_restore', 03:17:08 INFO - 'tabpaint']}, 03:17:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:17:08 INFO - 'ts_paint', 03:17:08 INFO - 'tpaint', 03:17:08 INFO - 'sessionrestore', 03:17:08 INFO - 'sessionrestore_no_auto_restore', 03:17:08 INFO - 'tabpaint']}, 03:17:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:17:08 INFO - 'tests': ['a11yr', 03:17:08 INFO - 'ts_paint', 03:17:08 INFO - 'tpaint', 03:17:08 INFO - 'sessionrestore', 03:17:08 INFO - 'sessionrestore_no_auto_restore', 03:17:08 INFO - 'tabpaint']}, 03:17:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:17:08 INFO - 'tests': ['a11yr', 03:17:08 INFO - 'ts_paint', 03:17:08 INFO - 'tpaint', 03:17:08 INFO - 'sessionrestore', 03:17:08 INFO - 'sessionrestore_no_auto_restore', 03:17:08 INFO - 'tabpaint']}, 03:17:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:17:08 INFO - 'tests': ['tsvgx', 03:17:08 INFO - 'tsvgr_opacity', 03:17:08 INFO - 'tart', 03:17:08 INFO - 'tscrollx', 03:17:08 INFO - 'cart']}, 03:17:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:17:08 INFO - 'tsvgr_opacity', 03:17:08 INFO - 'tart', 03:17:08 INFO - 'tscrollx', 03:17:08 INFO - 'cart']}, 03:17:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:17:08 INFO - 'talos_options': ['--disable-e10s'], 03:17:08 INFO - 'tests': ['tp5o']}, 03:17:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:17:08 INFO - 'tests': ['tp5o']}, 03:17:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:17:08 INFO - 'talos_options': ['--disable-e10s', 03:17:08 INFO - '--xperf_path', 03:17:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:17:08 INFO - 'tests': ['tp5n']}, 03:17:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:17:08 INFO - 'talos_options': ['--xperf_path', 03:17:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:17:08 INFO - 'tests': ['tp5n']}}, 03:17:08 INFO - 'talos.zip': {'path': '', 03:17:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:17:08 INFO - Running post-action listener: _resource_record_post_action 03:17:08 INFO - [mozharness: 2016-12-18 11:17:08.056155Z] Finished populate-webroot step (success) 03:17:08 INFO - [mozharness: 2016-12-18 11:17:08.056268Z] Running create-virtualenv step. 03:17:08 INFO - Running pre-action listener: _resource_record_pre_action 03:17:08 INFO - Running main action method: create_virtualenv 03:17:08 INFO - Creating virtualenv /builds/slave/test/build/venv 03:17:08 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 03:17:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:17:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:17:08 INFO - Using real prefix '/tools/python27' 03:17:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:17:08 INFO - Installing distribute.............................................................................................................................................................................................done. 03:17:11 INFO - Installing pip.................done. 03:17:11 INFO - Return code: 0 03:17:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:17:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:17:12 INFO - Reading from file tmpfile_stdout 03:17:12 INFO - Output received: 03:17:12 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) 03:17:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:17:12 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')]} 03:17:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:12 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')]} 03:17:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:12 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 0x11037f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102f5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbef94793f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110193d30>, '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 0x10fff9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11018bb20>, '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.kgoaSsVP8a/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.HsDhBySUJ9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 03:17:12 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 03:17:12 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 03:17:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kgoaSsVP8a/Render', 03:17:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:17:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:17:12 INFO - 'HOME': '/Users/cltbld', 03:17:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:17:12 INFO - 'LOGNAME': 'cltbld', 03:17:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:12 INFO - 'MOZ_NO_REMOTE': '1', 03:17:12 INFO - 'NO_EM_RESTART': '1', 03:17:12 INFO - 'PAGER': '/bin/cat', 03:17:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:17:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:12 INFO - 'PWD': '/builds/slave/test', 03:17:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:17:12 INFO - 'SHELL': '/bin/bash', 03:17:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HsDhBySUJ9/Listeners', 03:17:12 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 03:17:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:17:12 INFO - 'USER': 'cltbld', 03:17:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:17:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:17:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:12 INFO - 'XPC_FLAGS': '0x0', 03:17:12 INFO - 'XPC_SERVICE_NAME': '0', 03:17:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:17:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:12 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 03:17:12 INFO - Cleaning up... 03:17:12 INFO - Return code: 0 03:17:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:17:12 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')]} 03:17:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:12 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')]} 03:17:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:12 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 0x11037f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102f5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbef94793f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110193d30>, '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 0x10fff9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11018bb20>, '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.kgoaSsVP8a/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.HsDhBySUJ9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 03:17:12 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 03:17:12 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 03:17:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kgoaSsVP8a/Render', 03:17:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:17:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:17:12 INFO - 'HOME': '/Users/cltbld', 03:17:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:17:12 INFO - 'LOGNAME': 'cltbld', 03:17:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:12 INFO - 'MOZ_NO_REMOTE': '1', 03:17:12 INFO - 'NO_EM_RESTART': '1', 03:17:12 INFO - 'PAGER': '/bin/cat', 03:17:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:17:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:12 INFO - 'PWD': '/builds/slave/test', 03:17:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:17:12 INFO - 'SHELL': '/bin/bash', 03:17:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HsDhBySUJ9/Listeners', 03:17:12 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 03:17:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:17:12 INFO - 'USER': 'cltbld', 03:17:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:17:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:17:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:12 INFO - 'XPC_FLAGS': '0x0', 03:17:12 INFO - 'XPC_SERVICE_NAME': '0', 03:17:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:17:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:12 INFO - Downloading/unpacking psutil>=3.1.1 03:17:12 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 03:17:12 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 03:17:12 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 03:17:12 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 03:17:12 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 03:17:12 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 03:17:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:17:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:17:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:17:16 INFO - Installing collected packages: psutil 03:17:16 INFO - Running setup.py install for psutil 03:17:16 INFO - building 'psutil._psutil_osx' extension 03:17:16 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 03:17:17 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 03:17:17 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 03:17:17 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 03:17:17 INFO - building 'psutil._psutil_posix' extension 03:17:17 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 03:17:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:17:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:17:17 INFO - ^ 03:17:17 INFO - 1 warning generated. 03:17:17 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 03:17:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:17:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:17:17 INFO - Successfully installed psutil 03:17:17 INFO - Cleaning up... 03:17:17 INFO - Return code: 0 03:17:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11037f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102f5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbef94793f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110193d30>, '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 0x10fff9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11018bb20>, '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.kgoaSsVP8a/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.HsDhBySUJ9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 03:17:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:17:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:17:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kgoaSsVP8a/Render', 03:17:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:17:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:17:17 INFO - 'HOME': '/Users/cltbld', 03:17:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:17:17 INFO - 'LOGNAME': 'cltbld', 03:17:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:17 INFO - 'MOZ_NO_REMOTE': '1', 03:17:17 INFO - 'NO_EM_RESTART': '1', 03:17:17 INFO - 'PAGER': '/bin/cat', 03:17:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:17:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:17 INFO - 'PWD': '/builds/slave/test', 03:17:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:17:17 INFO - 'SHELL': '/bin/bash', 03:17:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HsDhBySUJ9/Listeners', 03:17:17 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 03:17:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:17:17 INFO - 'USER': 'cltbld', 03:17:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:17:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:17:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:17 INFO - 'XPC_FLAGS': '0x0', 03:17:17 INFO - 'XPC_SERVICE_NAME': '0', 03:17:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:17:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:17:18 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 03:17:18 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 03:17:18 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 03:17:18 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 03:17:18 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 03:17:18 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 03:17:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:17:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:17:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:17:20 INFO - Installing collected packages: mozsystemmonitor 03:17:20 INFO - Running setup.py install for mozsystemmonitor 03:17:20 INFO - Successfully installed mozsystemmonitor 03:17:20 INFO - Cleaning up... 03:17:20 INFO - Return code: 0 03:17:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:17: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')]} 03:17:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17: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')]} 03:17:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17: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', '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 0x11037f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102f5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbef94793f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110193d30>, '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 0x10fff9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11018bb20>, '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.kgoaSsVP8a/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.HsDhBySUJ9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 03:17: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:17: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 jsonschema==2.5.1 03:17:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kgoaSsVP8a/Render', 03:17:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:17:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:17:20 INFO - 'HOME': '/Users/cltbld', 03:17:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:17:20 INFO - 'LOGNAME': 'cltbld', 03:17:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:20 INFO - 'MOZ_NO_REMOTE': '1', 03:17:20 INFO - 'NO_EM_RESTART': '1', 03:17:20 INFO - 'PAGER': '/bin/cat', 03:17:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:17:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:20 INFO - 'PWD': '/builds/slave/test', 03:17:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:17:20 INFO - 'SHELL': '/bin/bash', 03:17:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HsDhBySUJ9/Listeners', 03:17:20 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 03:17:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:17:20 INFO - 'USER': 'cltbld', 03:17:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:17:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:17:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:20 INFO - 'XPC_FLAGS': '0x0', 03:17:20 INFO - 'XPC_SERVICE_NAME': '0', 03:17:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:17:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:21 INFO - Downloading/unpacking jsonschema==2.5.1 03:17: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 03:17: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 03:17: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 03:17: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 03:17: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 03:17: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 03:17:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:17:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:17:23 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 03:17:23 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 03:17:23 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 03:17:23 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 03:17:23 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 03:17:23 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 03:17:24 INFO - Downloading functools32-3.2.3-2.tar.gz 03:17:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:17:24 INFO - warning: no files found matching '*.txt' 03:17:24 INFO - no previously-included directories found matching 'build' 03:17:24 INFO - no previously-included directories found matching 'dist' 03:17:24 INFO - no previously-included directories found matching '.git*' 03:17:24 INFO - Installing collected packages: jsonschema, functools32 03:17:24 INFO - Running setup.py install for functools32 03:17:24 INFO - warning: no files found matching '*.txt' 03:17:24 INFO - no previously-included directories found matching 'build' 03:17:24 INFO - no previously-included directories found matching 'dist' 03:17:24 INFO - no previously-included directories found matching '.git*' 03:17:24 INFO - Successfully installed jsonschema functools32 03:17:24 INFO - Cleaning up... 03:17:24 INFO - Return code: 0 03:17:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:17: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')]} 03:17:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17: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')]} 03:17:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17: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', '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 0x11037f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102f5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbef94793f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110193d30>, '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 0x10fff9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11018bb20>, '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.kgoaSsVP8a/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.HsDhBySUJ9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 03:17: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:17: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 functools32==3.2.3-2 03:17:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kgoaSsVP8a/Render', 03:17:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:17:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:17:24 INFO - 'HOME': '/Users/cltbld', 03:17:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:17:24 INFO - 'LOGNAME': 'cltbld', 03:17:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:24 INFO - 'MOZ_NO_REMOTE': '1', 03:17:24 INFO - 'NO_EM_RESTART': '1', 03:17:24 INFO - 'PAGER': '/bin/cat', 03:17:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:17:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:24 INFO - 'PWD': '/builds/slave/test', 03:17:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:17:24 INFO - 'SHELL': '/bin/bash', 03:17:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HsDhBySUJ9/Listeners', 03:17:24 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 03:17:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:17:24 INFO - 'USER': 'cltbld', 03:17:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:17:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:17:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:24 INFO - 'XPC_FLAGS': '0x0', 03:17:24 INFO - 'XPC_SERVICE_NAME': '0', 03:17:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:17:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:17:24 INFO - Cleaning up... 03:17:24 INFO - Return code: 0 03:17:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:17: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')]} 03:17:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17: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')]} 03:17:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17: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', '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 0x11037f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102f5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbef94793f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110193d30>, '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 0x10fff9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11018bb20>, '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.kgoaSsVP8a/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.HsDhBySUJ9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 03:17: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:17: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 blobuploader==1.2.4 03:17:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kgoaSsVP8a/Render', 03:17:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:17:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:17:24 INFO - 'HOME': '/Users/cltbld', 03:17:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:17:24 INFO - 'LOGNAME': 'cltbld', 03:17:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:24 INFO - 'MOZ_NO_REMOTE': '1', 03:17:24 INFO - 'NO_EM_RESTART': '1', 03:17:24 INFO - 'PAGER': '/bin/cat', 03:17:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:17:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:24 INFO - 'PWD': '/builds/slave/test', 03:17:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:17:24 INFO - 'SHELL': '/bin/bash', 03:17:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HsDhBySUJ9/Listeners', 03:17:24 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 03:17:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:17:24 INFO - 'USER': 'cltbld', 03:17:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:17:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:17:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:24 INFO - 'XPC_FLAGS': '0x0', 03:17:24 INFO - 'XPC_SERVICE_NAME': '0', 03:17:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:17:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:25 INFO - Downloading/unpacking blobuploader==1.2.4 03:17:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:17:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:17:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:27 INFO - Downloading blobuploader-1.2.4.tar.gz 03:17:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:17:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:17:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:17:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:17:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:17:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:17:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:17:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:17: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 03:17: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 03:17: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 03:17: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 03:17: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 03:17: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 03:17:28 INFO - Downloading docopt-0.6.1.tar.gz 03:17:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:17:28 INFO - Installing collected packages: blobuploader, requests, docopt 03:17:28 INFO - Running setup.py install for blobuploader 03:17:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:17:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:17:29 INFO - Running setup.py install for requests 03:17:29 INFO - Running setup.py install for docopt 03:17:29 INFO - Successfully installed blobuploader requests docopt 03:17:29 INFO - Cleaning up... 03:17:29 INFO - Return code: 0 03:17:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:17:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:29 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 0x11037f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102f5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbef94793f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110193d30>, '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 0x10fff9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11018bb20>, '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.kgoaSsVP8a/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.HsDhBySUJ9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 03:17:29 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 03:17:29 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 03:17:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kgoaSsVP8a/Render', 03:17:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:17:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:17:29 INFO - 'HOME': '/Users/cltbld', 03:17:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:17:29 INFO - 'LOGNAME': 'cltbld', 03:17:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:29 INFO - 'MOZ_NO_REMOTE': '1', 03:17:29 INFO - 'NO_EM_RESTART': '1', 03:17:29 INFO - 'PAGER': '/bin/cat', 03:17:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:17:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:29 INFO - 'PWD': '/builds/slave/test', 03:17:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:17:29 INFO - 'SHELL': '/bin/bash', 03:17:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HsDhBySUJ9/Listeners', 03:17:29 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 03:17:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:17:29 INFO - 'USER': 'cltbld', 03:17:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:17:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:17:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:29 INFO - 'XPC_FLAGS': '0x0', 03:17:29 INFO - 'XPC_SERVICE_NAME': '0', 03:17:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:17:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:17:30 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-NwQvIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:17:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:17:30 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-VY_IyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:17:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:17:30 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-x7gJ5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:17:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:17:30 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-kiPAel-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:17:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:17:30 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-lIGR7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:17:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:17:30 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-HaZsSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:17:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:17:30 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-jbf8PZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:17:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:17:30 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-raHahu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:17:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:17:30 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-Ro0zsm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:17:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:17:30 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-QcIos3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:17:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:17:31 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-tX2BLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:17:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:17:31 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-SHE1Cc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:17:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:17:31 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-GPNFIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:17:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:17:31 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-3xcT6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:17:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:17:31 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-1oZIAD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:17:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:17:31 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-PxeW8r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:17:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:17:31 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-w2lT_M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:17:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:17:31 INFO - Running setup.py install for manifestparser 03:17:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:17:31 INFO - Running setup.py install for mozcrash 03:17:32 INFO - Running setup.py install for mozdebug 03:17:32 INFO - Running setup.py install for mozdevice 03:17:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:17:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:17:32 INFO - Running setup.py install for mozfile 03:17:32 INFO - Running setup.py install for mozhttpd 03:17:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:17:32 INFO - Running setup.py install for mozinfo 03:17:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:17:32 INFO - Running setup.py install for mozInstall 03:17:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:17:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:17:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:17:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:17:32 INFO - Running setup.py install for mozleak 03:17:33 INFO - Running setup.py install for mozlog 03:17:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:17:33 INFO - Running setup.py install for moznetwork 03:17:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:17:33 INFO - Running setup.py install for mozprocess 03:17:33 INFO - Running setup.py install for mozprofile 03:17:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:17:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:17:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:17:33 INFO - Running setup.py install for mozrunner 03:17:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:17:33 INFO - Running setup.py install for mozscreenshot 03:17:34 INFO - Running setup.py install for moztest 03:17:34 INFO - Running setup.py install for mozversion 03:17:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:17:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:17:34 INFO - Cleaning up... 03:17:34 INFO - Return code: 0 03:17:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:17:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:34 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 0x11037f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102f5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbef94793f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110193d30>, '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 0x10fff9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11018bb20>, '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.kgoaSsVP8a/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.HsDhBySUJ9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 03:17:34 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 03:17:34 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 03:17:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kgoaSsVP8a/Render', 03:17:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:17:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:17:34 INFO - 'HOME': '/Users/cltbld', 03:17:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:17:34 INFO - 'LOGNAME': 'cltbld', 03:17:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:34 INFO - 'MOZ_NO_REMOTE': '1', 03:17:34 INFO - 'NO_EM_RESTART': '1', 03:17:34 INFO - 'PAGER': '/bin/cat', 03:17:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:17:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:34 INFO - 'PWD': '/builds/slave/test', 03:17:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:17:34 INFO - 'SHELL': '/bin/bash', 03:17:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HsDhBySUJ9/Listeners', 03:17:34 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 03:17:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:17:34 INFO - 'USER': 'cltbld', 03:17:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:17:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:17:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:34 INFO - 'XPC_FLAGS': '0x0', 03:17:34 INFO - 'XPC_SERVICE_NAME': '0', 03:17:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:17:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:17:34 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-ZXbH0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:17:34 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)) 03:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:17:34 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-HYRIaY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:17:34 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)) 03:17:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:17:34 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-CxZ4ZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:17:35 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)) 03:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:17:35 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-WDZNQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:17:35 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)) 03:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:17:35 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-MYYcHv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:17:35 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)) 03:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:17:35 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-LXx_0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:17:35 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)) 03:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:17:35 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-kkVdln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:17:35 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)) 03:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:17:35 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-z1IHhj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:17:35 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)) 03:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:17:35 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-2MAj_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:17:35 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)) 03:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:17:35 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-0mr1A_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:17:35 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)) 03:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:17:35 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-gPrKyc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:17:35 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)) 03:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:17:35 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-yTwCaH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:17:35 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)) 03:17:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:17:35 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-QnPBZ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:17:36 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)) 03:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:17:36 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-F4mqeD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:17:36 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)) 03:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:17:36 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-c1lLyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:17:36 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)) 03:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:17:36 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-nWROqk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:17:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:17:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:17:36 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-j4i9VV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:17:36 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)) 03:17:36 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)) 03:17:36 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)) 03:17:36 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)) 03:17:36 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)) 03:17:36 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)) 03:17:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:17:36 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 03:17:36 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 03:17:36 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 03:17:36 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 03:17:36 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 03:17:36 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 03:17:39 INFO - Downloading blessings-1.6.tar.gz 03:17:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:17:39 INFO - Installing collected packages: blessings 03:17:39 INFO - Running setup.py install for blessings 03:17:39 INFO - Successfully installed blessings 03:17:39 INFO - Cleaning up... 03:17:39 INFO - Return code: 0 03:17:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:17:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:17:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:17:39 INFO - Reading from file tmpfile_stdout 03:17:39 INFO - Current package versions: 03:17:39 INFO - blessings == 1.6 03:17:39 INFO - blobuploader == 1.2.4 03:17:39 INFO - docopt == 0.6.1 03:17:39 INFO - functools32 == 3.2.3-2 03:17:39 INFO - jsonschema == 2.5.1 03:17:39 INFO - manifestparser == 1.1 03:17:39 INFO - mozInstall == 1.12 03:17:39 INFO - mozcrash == 1.0 03:17:39 INFO - mozdebug == 0.1 03:17:39 INFO - mozdevice == 0.48 03:17:39 INFO - mozfile == 1.2 03:17:39 INFO - mozhttpd == 0.7 03:17:39 INFO - mozinfo == 0.9 03:17:39 INFO - mozleak == 0.1 03:17:39 INFO - mozlog == 3.4 03:17:39 INFO - moznetwork == 0.27 03:17:39 INFO - mozprocess == 0.23 03:17:39 INFO - mozprofile == 0.28 03:17:39 INFO - mozrunner == 6.13 03:17:39 INFO - mozscreenshot == 0.1 03:17:39 INFO - mozsystemmonitor == 0.3 03:17:39 INFO - moztest == 0.7 03:17:39 INFO - mozversion == 1.4 03:17:39 INFO - psutil == 3.1.1 03:17:39 INFO - requests == 1.2.3 03:17:39 INFO - wsgiref == 0.1.2 03:17:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:17:39 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')]} 03:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:39 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')]} 03:17:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:39 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 0x11037f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102f5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbef94793f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110193d30>, '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 0x10fff9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11018bb20>, '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.kgoaSsVP8a/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.HsDhBySUJ9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 03:17:39 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 03:17:39 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 03:17:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kgoaSsVP8a/Render', 03:17:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:17:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:17:39 INFO - 'HOME': '/Users/cltbld', 03:17:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:17:39 INFO - 'LOGNAME': 'cltbld', 03:17:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:39 INFO - 'MOZ_NO_REMOTE': '1', 03:17:39 INFO - 'NO_EM_RESTART': '1', 03:17:39 INFO - 'PAGER': '/bin/cat', 03:17:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:17:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:39 INFO - 'PWD': '/builds/slave/test', 03:17:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:17:39 INFO - 'SHELL': '/bin/bash', 03:17:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HsDhBySUJ9/Listeners', 03:17:39 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 03:17:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:17:39 INFO - 'USER': 'cltbld', 03:17:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:17:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:17:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:39 INFO - 'XPC_FLAGS': '0x0', 03:17:39 INFO - 'XPC_SERVICE_NAME': '0', 03:17:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:17:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:39 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)) 03:17:39 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)) 03:17:39 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)) 03:17:39 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)) 03:17:39 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)) 03:17:39 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)) 03:17:39 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)) 03:17:39 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)) 03:17:39 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)) 03:17:39 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)) 03:17:39 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)) 03:17:39 INFO - Cleaning up... 03:17:39 INFO - Return code: 0 03:17:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:17:39 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')]} 03:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:17:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:39 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')]} 03:17:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:17:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:17:39 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 0x11037f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1102f5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbef94793f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110193d30>, '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 0x10fff9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11018bb20>, '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.kgoaSsVP8a/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.HsDhBySUJ9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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 03:17:39 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 03:17:39 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 03:17:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kgoaSsVP8a/Render', 03:17:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:17:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:17:40 INFO - 'HOME': '/Users/cltbld', 03:17:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:17:40 INFO - 'LOGNAME': 'cltbld', 03:17:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:17:40 INFO - 'MOZ_NO_REMOTE': '1', 03:17:40 INFO - 'NO_EM_RESTART': '1', 03:17:40 INFO - 'PAGER': '/bin/cat', 03:17:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:17:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:17:40 INFO - 'PWD': '/builds/slave/test', 03:17:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:17:40 INFO - 'SHELL': '/bin/bash', 03:17:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HsDhBySUJ9/Listeners', 03:17:40 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 03:17:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:17:40 INFO - 'USER': 'cltbld', 03:17:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:17:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:17:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:17:40 INFO - 'XPC_FLAGS': '0x0', 03:17:40 INFO - 'XPC_SERVICE_NAME': '0', 03:17:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:17:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:17:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:17:40 INFO - Cleaning up... 03:17:40 INFO - Return code: 0 03:17:40 INFO - Running post-action listener: _resource_record_post_action 03:17:40 INFO - Running post-action listener: _start_resource_monitoring 03:17:40 INFO - Starting resource monitoring. 03:17:40 INFO - [mozharness: 2016-12-18 11:17:40.288322Z] Finished create-virtualenv step (success) 03:17:40 INFO - [mozharness: 2016-12-18 11:17:40.289315Z] Running install step. 03:17:40 INFO - Running pre-action listener: _resource_record_pre_action 03:17:40 INFO - Running main action method: install 03:17:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:17:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:17:40 INFO - Reading from file tmpfile_stdout 03:17:40 INFO - Detecting whether we're running mozinstall >=1.0... 03:17:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:17:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:17:40 INFO - Reading from file tmpfile_stdout 03:17:40 INFO - Output received: 03:17:40 INFO - Usage: mozinstall [options] installer 03:17:40 INFO - Options: 03:17:40 INFO - -h, --help show this help message and exit 03:17:40 INFO - -d DEST, --destination=DEST 03:17:40 INFO - Directory to install application into. [default: 03:17:40 INFO - "/builds/slave/test"] 03:17:40 INFO - --app=APP Application being installed. [default: firefox] 03:17:40 INFO - mkdir: /builds/slave/test/build/application 03:17:40 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'] 03:17:40 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 03:18:12 INFO - Reading from file tmpfile_stdout 03:18:12 INFO - Output received: 03:18:12 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:18:12 INFO - Running post-action listener: _resource_record_post_action 03:18:12 INFO - [mozharness: 2016-12-18 11:18:12.684233Z] Finished install step (success) 03:18:12 INFO - [mozharness: 2016-12-18 11:18:12.684359Z] Running run-tests step. 03:18:12 INFO - Running pre-action listener: _resource_record_pre_action 03:18:12 INFO - Running main action method: run_tests 03:18:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:18:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:18:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:18:12 INFO - 2016-12-18 03:18:12.699 screenresolution[1646:16052] starting screenresolution argv=screenresolution get 03:18:12 INFO - 2016-12-18 03:18:12.701 screenresolution[1646:16052] Display 0: 1600x1200x32@60 03:18:12 INFO - 2016-12-18 03:18:12.707 screenresolution[1647:16055] starting screenresolution argv=screenresolution list 03:18:12 INFO - Available Modes on Display 0 03:18:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:18:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:18:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:18:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:18:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:18:12 INFO - Intel Iris: 03:18:12 INFO - Chipset Model: Intel Iris 03:18:12 INFO - Type: GPU 03:18:12 INFO - Bus: Built-In 03:18:12 INFO - VRAM (Dynamic, Max): 1536 MB 03:18:12 INFO - Vendor: Intel (0x8086) 03:18:12 INFO - Device ID: 0x0a2e 03:18:12 INFO - Revision ID: 0x0009 03:18:12 INFO - Displays: 03:18:12 INFO - Display: 03:18:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:18:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:18:12 INFO - Display Serial Number: 1600x1200 60 03:18:12 INFO - Main Display: Yes 03:18:12 INFO - Mirror: Off 03:18:12 INFO - Online: Yes 03:18:12 INFO - Rotation: Supported 03:18:12 INFO - Return code: 0 03:18:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:18:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:18:12 INFO - Python 2.7.3 03:18:12 INFO - Return code: 0 03:18:12 INFO - grabbing minidump binary from tooltool 03:18:12 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')]} 03:18:12 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 0x110193d30>, '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 0x10fff9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11018bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:18:12 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 03:18:12 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 03:18:12 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 03:18:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:18:13 INFO - Return code: 0 03:18:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:18:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:18:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:18:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:18:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:18:13 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/Yoy68BNfRWWS2uRXTvaZsg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0263', '--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 03:18:13 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/Yoy68BNfRWWS2uRXTvaZsg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0263 --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 03:18:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kgoaSsVP8a/Render', 03:18:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:18:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:18:13 INFO - 'HOME': '/Users/cltbld', 03:18:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:18:13 INFO - 'LOGNAME': 'cltbld', 03:18:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:18:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:18:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:18:13 INFO - 'MOZ_NO_REMOTE': '1', 03:18:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:18:13 INFO - 'NO_EM_RESTART': '1', 03:18:13 INFO - 'PAGER': '/bin/cat', 03:18:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:18:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:18:13 INFO - 'PWD': '/builds/slave/test', 03:18:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:18:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:18:13 INFO - 'SHELL': '/bin/bash', 03:18:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HsDhBySUJ9/Listeners', 03:18:13 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 03:18:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:18:13 INFO - 'USER': 'cltbld', 03:18:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:18:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:18:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:18:13 INFO - 'XPC_FLAGS': '0x0', 03:18:13 INFO - 'XPC_SERVICE_NAME': '0', 03:18:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:18:13 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/Yoy68BNfRWWS2uRXTvaZsg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0263', '--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 03:18:13 INFO - mozversion application_buildid: 20161218004028 03:18:13 INFO - mozversion application_changeset: eb41245ead336aeba73bc628e413e7b55f40eddd 03:18:13 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:18:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:18:13 INFO - mozversion application_name: Firefox 03:18:13 INFO - mozversion application_remotingname: firefox-dev 03:18:13 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:18:13 INFO - mozversion application_vendor: Mozilla 03:18:13 INFO - mozversion application_version: 52.0a2 03:18:13 INFO - mozversion platform_buildid: 20161218004028 03:18:13 INFO - mozversion platform_changeset: eb41245ead336aeba73bc628e413e7b55f40eddd 03:18:13 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:18:13 INFO - mozversion platform_version: 52.0a2 03:18:13 INFO - using testdate: 1482059893 03:18:13 INFO - actual date: 1482059893 03:18:13 INFO - starting webserver on 'localhost:49241' 03:18:13 INFO - SUITE-START | Running 2 tests 03:18:13 INFO - TEST-START | dromaeo_css 03:18:13 INFO - operating with platform_type : mac_ 03:18:13 INFO - Initialising browser for dromaeo_css test... 03:18:13 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/tmp_Anv0t/profile http://localhost:49241/getInfo.html) 03:18:16 INFO - PROCESS | 1657 | 2016-12-18 03:18:16.860 plugin-container[1661:16255] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3b, name = 'com.apple.tsm.portname' 03:18:16 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:18:16 INFO - PROCESS | 1657 | 2016-12-18 03:18:16.861 plugin-container[1661:16255] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c4f, name = 'com.apple.CFPasteboardClient' 03:18:16 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:18:16 INFO - PROCESS | 1657 | 2016-12-18 03:18:16.861 plugin-container[1661:16255] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:18:16 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 03:18:16 INFO - PROCESS | 1657 | colorDepth:24 03:18:16 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 03:18:16 INFO - PROCESS | 1657 | __metrics 03:18:17 ERROR - PROCESS | 1657 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 03:18:17 INFO - TEST-INFO | 1657: exit 0 03:18:17 INFO - Browser initialized. 03:18:17 INFO - Running cycle 1/1 for dromaeo_css test... 03:18:17 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/tmp_Anv0t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:18:20 INFO - PROCESS | 1662 | 2016-12-18 03:18:20.549 plugin-container[1663:16380] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4343, name = 'com.apple.tsm.portname' 03:18:20 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:18:20 INFO - PROCESS | 1662 | 2016-12-18 03:18:20.549 plugin-container[1663:16380] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 03:18:20 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:18:20 INFO - PROCESS | 1662 | 2016-12-18 03:18:20.549 plugin-container[1663:16380] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:18:30 INFO - PROCESS | 1662 | RSS: Main: 229502976 03:18:30 INFO - PROCESS | 1662 | 03:19:19 INFO - PROCESS | 1662 | 1482059959779 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:21:34 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 03:21:35 INFO - PROCESS | 1662 | RSS: Main: 233828352 03:21:35 INFO - PROCESS | 1662 | 03:24:38 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 03:24:38 INFO - PROCESS | 1662 | RSS: Main: 243404800 03:24:38 INFO - PROCESS | 1662 | 03:27:40 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 03:27:41 INFO - PROCESS | 1662 | RSS: Main: 246210560 03:27:41 INFO - PROCESS | 1662 | 03:30:43 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 03:30:43 INFO - PROCESS | 1662 | RSS: Main: 245563392 03:30:43 INFO - PROCESS | 1662 | 03:33:46 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 03:33:47 INFO - PROCESS | 1662 | RSS: Main: 249925632 03:33:47 INFO - PROCESS | 1662 | 03:36:49 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 03:36:49 INFO - PROCESS | 1662 | RSS: Main: 251387904 03:36:49 INFO - PROCESS | 1662 | 03:36:49 INFO - PROCESS | 1662 | __start_tp_report 03:36:49 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 03:36:49 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 03:36:49 INFO - PROCESS | 1662 | |i|pagename|runs| 03:36:49 INFO - PROCESS | 1662 | |0;dojo.html;2701.783857291417;3339.632640409556;3382.560267165268;3389.525466434699;3397.473391624296;5652.745626446809;5666.235058267132;5698.259226300585;5715.9997000524945;5748.511376533;5746.310442746866;5790.942090579093;5801.854953626162;5811.099279611656;5876.882462350751;4319.265724826787;5165.664231824938;5182.611304152185;5193.922091168635;5275.261463395128;3830.138218900739;4338.17574660815;4538.863834084983;4641.280601506755;5192.468221874556;5100.770465329032;5163.948360516384;5200.3759548854005;5251.789928402859;5257;5041.369828771403;5104.846854594368;5146.845594632129;5185.0148471790235;5217.373915130209;5090.720010399426;5157.174852023658;5171.3277273954145;5207.713575753332;5226.817061402832;4553.4535855697195;5591.133374326945;5654.886902261932;5754.1944127822135;5824.24284842972;6154.569180157391;6181.196444462236;6201.937980620181;6207.4723648489935;6227.875442491125;6228.626282423023;6231.376862313778;6245.718942647546;6247.125402443661;6256.217972753406;5717.828465146053;5782.335031471351;5806.65160090396;5921.822345329647;5958.910616340801;6207.689615519296;6246.718897649616;6272.372762723782;6295.905561416583;6359.523035772336;6203;6204.13142160098;6205.944989351821;6221.129041934042;6222.657753823492;6135.754569817157;6236.314005459396;6243.344448832898;6250.3749625037135;6256.374362563707;6127.325994140641;6131.651036771903;6165.784197553064;6170.475509581646;6209.844753881189;6194.907076393858;6202.689865506797;6205.410486003822;6208.1308616793685;6223.533235007392;4910.754462276872;4964.503549645006;4977.701337919664;5005.924911126336;5017.698938063655;4401.823927042947;4402.71382360152;4428.3800267962515;4436.3567282743825;4484.013517026249;5758.222639943554;5903.793367232126;5923.822285331447;5926.436988486087;5935.881282374328;6125.662799505599;6170.228721409824;6177.536684748662;6247.531435142382;6254.781082662085;5889.705514724246;5918.822435326947;5920.851978700481;5935.7625694972585;5991.700414979321;5316.202569614588;5393.568514518829;5393.703346315911;5399.83800485986;5426.457354264621;6354.3963323484195;6358.650274234961;6378.904316435164;6392.232932048184;6392.680365981775;6232.189815323979;6258.280297765633;6263.404976527223;6264.185655864709;6287.811365659038;6150.384961503813;6249.406306400884;6254.968725156527;6293.276273228637;6365.363463653597;5292.02097611943;6191.102290167991;6196.256449226122;6197.349278325711;6197.535184861244;6213.5339849512375;6214;6225.595336303126;6241.531885108544;6249.218847644044;5975.551833612583;5983.341832398346;6063.848403789764;6120.17377654011;6121.326654068138;6103.206583144163;6122.184701511036;6138.600990935575;6182.041783523561;6207.96896015517;5046.520580544842;5811.215485909348;5929.229200203946;6034.125051867367;6104.969475152596;5683.2895888014;6136.509079273558;6153.169322141633;6158.815235542941;6214.943459611786;4786.593139583175;5296.179092240709;5943.257092863392;5956.493698035542;5991.550633702403 03:36:49 INFO - PROCESS | 1662 | |1;ext.html;435.0754646376449;452.8664044106988;482.43073173655074;504.5005444609835;537.8735997040694;33048.17379565512;37672.81163594181;39604.00989975245;39679.603203967985;41066.79466602663;40444.98887527813;40983.38524922128;41082.7945860271;41150.17699646005;41153;9339.859902101474;9445.05549445057;9511.286653501014;9512.143907048365;9534.523273836281;9383.530823458834;9386;9411.341206115574;9460.668876589321;9512.191463725592;9465.716028519155;9485.004074572174;9511.667091651794;9531.523423828816;9550.904490955105;8152.592370381457;9401.858972115424;9428.764280892998;9503;9522.571484283224;9272.165505104505;9324.393914395543;9329.160375566164;9341.019192984706;9443.291753118476;38026.049348766224;38314.80842595797;38376.42435363472;38488.80755596232;38491.03772405684;3125.87496500142;3128.562001319817;3148.354587309596;3248.8213148276836;3281.9825853985103;28712.425751484854;28907.855460722563;29216.707832921613;29266;29523.852380738175;3159.921001974969;3244.4971029490466;3247.7401807855313;3266;3266.0528446750413;498363;500371;500683;503632;504456.47771761275;54977.45022549803;55028.449715503135;55332;55407;55598.44401556013;55770;56556.71721641366;57426.13860792091;57610.71194644084;57707;15230.771538426932;15317.846821531864;15365.078095314317;15516.767248491282;15521.767173492406;12197.756044879052;12325.69185770345;12398.318092504818;12401.441935112942;12410.193337433038;13231.53689620878;13599.388027538784;13648.727025459435;13719.245441500614;13786.172829630254;3172.191850645156;3173.0480855743185;3256.739460843127;5270.7101109439345;5913.615614985012;822.268181318627;953.4517652349788;1003.6738060130341;1018.2363227579316;1087.2660953856273;28613.57079643807;29457.55813662797;29516;30092.59259259254;30506.38987220243;952.3143336797494;1024.5850430575629;1032.6334151376154;1085.2891356161726;1204.1029433072417;11890.821637675443;14220.21788801626;14253.714925701635;14550.41798328055;14740.041897276857;359490;362048;362602;362632;679431;51560.96878062493;51700.48299517032;51897.740511297205;51968.480315196364;52068.69828254248;1341.677997280665;1386.729587730403;1425.6720954180596;1554.8542569534154;1600.3358606178538;26279.868600656875;27217.911283548437;27774.722252777214;27904.720952790212;27949;46596.30105548352;46915.530844691115;46970.53029469662;47129.293060603435;47260.7636961813;581984;587950;591127;591794;592096.1185582133;21845;24779.628305575072;24986;25099;25159.87420062888;42715.5728442724;44076;45299.32051019171;45612.771936141435;45645.54354456546;28969.130926072252;34745.13137171591;37309.81345093257;37410.064748381505;37502.812260958126;36306.63693363138;36738.448923266704;36880.81559592292;37534.8123259382;37744.62255377521;36237;37006.629933700315;37104.443433349064;37304.626953731204;38479.61520384761;30284.09147725572;33776.15559610931;33975.150621234665;34132.82933585316;34296.82851585726;21053.521182775607;21711.565768684854;24817.25548233556;25352.74647253504;25424.618630720925 03:36:49 INFO - PROCESS | 1662 | |2;jquery.html;372.36883482497177;376.60080314866235;382.4359070371202;388.4639197906888;402.92545879012386;14435.494757683484;15347.539573812775;15977.92011039946;17743.93536387816;20187.798122018776;18259.72610410845;19442.1251043703;19471.4158575243;19991.900040499815;20135.899320503377;5695.857603559903;7401.444891633122;7770.4560680752365;7851.960740196319;7865.252800983926;6007.249093863267;6540.9672951635175;7693.961530192341;7759.495632783879;7798.220177982184;7543.924560754377;7724.845503089935;7853.764387068397;7858.68565257389;7866.567338796364;7843.450958432912;7881.921180788233;7955.960220198891;7967.083785364699;7984.560849153294;7367;7651.234876512304;7661.65522551481;7664.425168112358;7686.192949740309;23479;26593;27274.772635231464;27670.861645691843;28132.859335703397;2253.4704344479032;2361.3624321433117;2444.1201167579657;2516.811239157052;2674.291312802109;16154.030758154431;16652.58368540784;16894.40869569559;16952.3219071237;17546.210420531108;1974.4866334753067;1998.5103649605794;2031.2078289466972;2078.8752674839407;2083.447886310129;97821;140301;148886.25556872148;150316.2484187572;152721.9999999989;21700;21745.67381489279;21937.890310548664;22095.563788353706;22148.88925555362;30908.072757817303;35105.29789404198;35226;35282.647173527934;35997.28005439877;10294.639687610897;10572.36565806054;10689.518971646294;10731.83902241467;10741.24811263204;8669.176428239307;8714.869276960851;8749.081346458657;8799.956000220085;8872.048149407121;9618.374805637612;9800.705978820646;9943.45311007887;9955.55200016001;9969.252306077073;3832.3676593362343;3860.82626281818;3864.613538646113;3879.3599056155417;3896.4414234306505;797.421869144877;833.2708879730236;891.8751374807532;902.5622573052065;941.3833938770116;19430.028498575015;20226.190952362034;20241;20302.796972030384;20526.486837828874;817.3093735793245;888.2849306308354;933.407286373158;947.3794664494767;979.8285300072458;10456.790864182674;10651.30766500175;10714.142868570496;10749.462526873625;10771.892281077246;561469;577469.112654434;617166;626073;638364.8081759561;70278.94581581281;74628;96341.51829240809;120848;133093;927.4759760735163;956.4213650741201;967.777411195429;978.6770365779275;978.8629591857145;17069.48791536256;17687.91156044263;18118.91286522813;18303.359382422084;18501.629967400306;54128.72935635297;54428.639284018216;54687.78734846659;55101.428699973454;55996.44003560075;78118.60940695487;78220;78323.21676783159;78758;79002.8398864039;6806.65966701653;6942.965285173744;6979.987901754319;7590.20302868213;7978.640961156878;9904.158146557624;10113.24150688716;12406.821351971548;12438.564650237198;12475.93762031184;10293.53679084428;10732.839007415052;10923.071538918963;11074.501647425725;11077.72606150303;10766.192535559707;10896.400697961688;10922.617708380169;10923.453827308762;11034;8998.68504602362;11006.174536909924;11146.275492092987;11197.888021119685;11209.327440353605;7953.443258971816;8132.227438393344;8187.058488273925;8343.874841877256;8356.832863342577;9996.550155243178;10743.516541755484;10838;10841.078508326565;11199.440027998404 03:36:49 INFO - PROCESS | 1662 | |3;mootools.html;1451;1457.0237940579811;1661.3188592677004;1765.4023109086281;1806.9277228910844;7515.962420187891;7774.53352798831;8052.5973701314915;8471.957640211798;8488.702895398663;7957.283844453998;8252.711155109573;8283.710070147547;8360.581970901461;8365.498070115782;2308.411355104446;2343.6015877300815;2350.7061617297836;2351.2946116165167;2351.7413084560685;2325.2559181061984;2372.0393240737444;2374.833761636686;2380.8928598213033;2386.9761302387014;2308.68832707584;2329.184785325131;2330.5571941331177;2353.5057637895975;2357.092519380034;2298;2299.850509716863;2331.4754180309296;2332.043862016582;2332.4518738096617;2327.674125622414;2350.4593943392942;2357.7288611809518;2362.4448254660315;2368.301351101412;8310.501369917763;8335.458195217292;8370.079291277954;8386.161383861627;8398.412111152222;4301.849435269751;5040.092783299005;5104.285400043997;5160.92258616121;5219.269302297681;3362.2434952135573;3499.8075105869166;3608.4046132388125;3620.167361506868;3622.9275414491563;1030.814453398388;1034;1036.8185567525652;1037.725002874239;1080.1790639114251;10830.72923176919;10837.83743243852;10916.454177291103;10920.836187457195;10923.726906827313;4518.118966801442;4597.42532183477;4707.81168753246;4726.550977657186;4728.621710263171;9217.769555761026;9348.57931393089;9364.110409511086;9366.157045865768;9372.34393592442;5179.7928082877015;5180.8963820723375;5190.844274671766;5192.221166825007;5204.531592156724;4244.978775106105;4252.659787217015;4702.670813043054;4767.213409787416;4777.259524773665;3280.6555311692405;3366.3267346530793;3684.5578530576504;3699.4647221403357;3798.3163030654746;1279.2836011833404;1297.4096785962365;1300.8504022037398;1313.4632479998425;1321.4383886848243;822.3873214455269;908.2779190543533;912.2291663544295;920.4293338130402;930.7905721212672;5659.207710920475;5704;5751.338596061507;5804.970975145182;5805.01314776489;894.5124906925741;900.0909081827401;907.4509921497531;927.188709878856;931.6459745296879;3701.148735790783;3758.1168425420005;3821.121142137324;3822.4266360046217;3872.2642697887873;9705.5147242639;9959.203263738884;10018;10103.595856165817;10104.545295461721;9943.055409736064;9968.651097211708;10035.297529173034;10041.849372259421;10068.44623545712;7381.37258333037;7663.386929045663;7818.257265559762;7837.49056311338;7869.606519673994;4185.811638476337;4658.254679251304;4796.712197268035;5304.9124929390155;5627.7467513961155;8494.787630309293;8589.14108589136;9206.861897071416;9338.626454941726;9339.486328252011;9028.322875784212;9294.302927280616;9323.533823308942;9361.906380936376;9383.953080234554;6626.237982632059;6627.801165965029;6676.465882729467;6743.022261771917;6902.930970690229;7762.8447431049935;7919.406044546567;7929.801754956172;7945.245201705829;7975.521468711896;6232.781852635136;6941.895871562032;7040.542364746275;7082.716691332289;7104.786856394317;6964.442844572523;7068.187158476841;7082.291770822876;7097.7160913562875;7135.714571417086;6447.419732223935;6604.240512341143;6691.330866913269;6741;6774.153230846145;4851.781669824937;4860.78126484302;4863.562279394871;4896.608271338356;4912.533309335608;4847.975760121177;4879.804807807648;4899.387576552931;4986.875328116681;4999.77501012448 03:36:49 INFO - PROCESS | 1662 | |4;prototype.html;299.99850000750007;301.52660323292434;302.455579956079;307.9168624471393;311.19711145245253;4345.3699213614045;5262.947410517892;5270.235815806705;5386.596005299603;5414.40441551429;4719.917271560102;4909.7545122743895;4939.035959297391;5226.660267082637;5423.457654234585;3467.8209408801004;4118.691098167649;4140.523839758436;4140.958590414102;4173.290540608089;3953.8220780064967;4118;4144.171165766844;4146.108586653869;4154.178156096398;3731.645493678096;3801.74542401007;3812.8856134315874;3940.6059394060508;4133.5945778435325;3840.5391353037676;4083.4487344208746;4083.8162282697203;4104.994276402271;4113.609207125318;3108.1918701137643;3589.766665166782;3603.963960360389;3667.936298473466;3684.0237337105686;4291.699581029329;4604.585587297126;4696.906061878777;4999.350084488993;5253.789848406059;4394.516603173648;4403.735775853459;4494.966157783728;4715.8210447388155;4766.451858036336;4853;5364.812231571916;5401.216823560561;5504.4770746778995;5714.657120572779;3623.927521449556;3717.70258379329;3724.7206459515646;3983.342748446503;3988.840446382141;5585.966596179679;5605.607607467479;5746.764445644207;5985.25184351956;6011.458968692838;3499.807510586891;3511.877084302037;3930.6046353544652;4082.5305089914445;4118.094019315746;3954.683625309968;4175.770332631734;4186.895327616834;4202.789860507023;4215.557366476476;4056.4320995060716;4726.007538416902;4753.405824271966;4773.570378665937;4802.711837289774;5520.025581581255;5753.424657534297;5782.335031471351;5793.391693872167;5823.912641310384;1241.2428418664608;1270.3838638260436;1277.9424925878172;1304.315234501894;1353.88491978181;905.9139381758722;919.544825311475;925.7130289610243;929.9628014879464;930.8743319651895;4224.429701990253;4585.592563903977;4855.484399508491;5073.568746656576;5099.24640694021;5256.579473642099;5330.653507521999;5388.164834450667;5550.611457198038;5562.715420350683;4088.8364465421646;4999.8000479885295;5025.547700706954;5045.873853153627;5105.7191854448365;2459.2659131172422;2561.9615705764427;2563.1541591274836;2674.9331266718486;2697;4609.562091601292;4681.208221340725;5157.813702848366;5637.351704553947;5674.432556744293;4843.854684359475;4932.975335123301;5381.37646159764;5673.177389278614;5696.784379559096;4874.317595536628;4969.3849498913;5166.354205724285;5520.91718624221;5717;4886.731229782254;5088.720120393414;5170.621514103555;5532.91700624499;5572;5125.948740512547;5285.735713214401;5415.106507426231;5474.507294343528;5484.972575137258;4692.718436893797;5098.2862399263395;5321.467853214647;5640.774369025193;5731.484166425042;3613.608760627125;3930.803459827054;3961.801909904551;3978.801059946933;3985.940210896781;4992.425871024879;5231.895362092813;5897.970510147422;5925.792597259076;5940.138679891478;5160.793568257228;5394.730263486888;5424.83725488236;5726.484616384545;5767.069155871837;4882.80468781259;5110.054639891632;5186.299849520266;5463.4536546346635;5510.311211098613;4449.51055383914;4978.726170060681;5364.973175134255;5629.8592535186945;5718.428157184415;5001.874578219871;5287.365516137934;5422.295101636762;5616.8876622466505;5782.5254560086905;5301.5493683037375;5604.635698679573;5611.270534830609;5631.549476041989;5697.547125482975 03:36:49 INFO - PROCESS | 1662 | |5;yui.html;708.4367927497639;729.8723472235399;743.4572761883821;786.5791801386258;792.1721800718249;14011.509597164102;14784.6303842404;14791.186484743335;14828.961972661891;14847.925760371196;14401.63995900103;14863.7027259455;14902.925485372558;15347.616309592266;15368.923155384207;645.961242325462;671.4661843834139;793.5873345860149;799.8720204767238;820.5692011693849;789.6209819286746;802.3741481644327;820.0405525535108;820.1880138662741;823.2467292427422;867.8741582470537;871.3116637856118;872.6073267029831;873.0004145253681;874.4578361415931;838.1660248053165;862.3144600042949;868.3660927522881;868.7654333329997;868.9261412779921;618.4619381138393;634.2895956528718;639.66097968077;640.324457697134;641.7368878759686;14621.26893655313;14791.112533248039;14824.851751482454;14971.176585287805;15072.698546029018;10064.597416103346;11052.723681907935;13197.340132993408;13201.40593673287;13635.250061246628;9370.765730856714;11205.103591712725;11351.943240283746;11800.763984720257;11827;3533.9823300883586;3631.1829838286176;3672.687821535146;3779.998300450383;3805.4482100095606;78413;92393.15213695755;95785.52107239488;96691;96882.54676179863;10256.846147307797;10404.531796069195;10418.322809017389;10432.426216558162;10489.947550262199;18623.255069797327;18743.531411714815;20239.190432382835;20404.48988775263;20554.58890822175;7366.337029667356;7493;7513.436492263102;7578.355839753573;7672.61636918163;4194.8112334945;4264.850730224427;4289.935650965175;4293.947982744247;4300.827966881289;2983.254186453387;3436.9312613748084;3453.3438646657055;3466.2201004773724;3636.1091532574687;654.9869002619921;657.6251536624078;694.7915625312386;697.6442014572503;718.4755128756037;574.7241324164341;583.7314835175866;584.5557376393971;625.967154195581;663.6582160187507;8846.292296616255;9083.50040747752;9163.58763855628;9366.718998430058;9417.246620270362;581.9330776960619;599.2000679093459;641.4195153386192;649.6914955779341;685.2017399729259;4248.532661407243;4463.107378524308;4471;4554.726716397026;4605.171069207508;66999;88128.55935720408;98453.50773246231;98596;105980;33823.661763382544;80901;85131.57434212789;86953;88164;71878.64060679768;73875.52248954991;74982.25017749752;81613.59193203996;84254;9270.202283315763;9772.267079969173;9826.459544725109;9828.31201815866;9844.212463003087;47491.762541187076;50224;50689.239661405845;51581.226281606556;53238.46761532335;69744;70468;74197.6290118546;74246;78635;11795.587154449553;12166.330851803235;12283.201591896499;12290.446929888356;12315.167511472233;13669.38487768033;13933.372998214902;13954;14070.78893816614;14218.573442796733;11240.15698822608;12751.553695620609;12783.360831958551;12937.935310323388;12945.611631651065;12822.487100516248;12957.287349195885;13009.934950325187;13102.803457948328;13249.205047697169;12663.683407914506;12678.115087453974;12721.745565088831;12759.681007974876;12903.290319032543;10936.945315273691;11092.500837462496;11121.110311175249;11404.543818247177;11581.305121692725;9936.20510359184;10479.371237725762;11093.445327733742;11170.385628790495;11254.60608878718 03:36:49 INFO - PROCESS | 1662 | __end_tp_report 03:36:49 INFO - PROCESS | 1662 | __start_cc_report 03:36:49 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,357 03:36:49 INFO - PROCESS | 1662 | __end_cc_report 03:36:49 INFO - PROCESS | 1662 | __startTimestamp1482061009966__endTimestamp 03:36:49 INFO - PROCESS | 1662 | 03:36:49 INFO - PROCESS | 1662 | ------- Summary: start ------- 03:36:49 INFO - PROCESS | 1662 | Number of tests: 6 03:36:49 INFO - PROCESS | 1662 | 03:36:49 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5703.57 Median:5958.91 stddev:708.14 (11.9%) stddev-sans-first:670.01 03:36:49 INFO - PROCESS | 1662 | Values: 2701.8 3339.6 3382.6 3389.5 3397.5 5652.7 5666.2 5698.3 5716.0 5748.5 5746.3 5790.9 5801.9 5811.1 5876.9 4319.3 5165.7 5182.6 5193.9 5275.3 3830.1 4338.2 4538.9 4641.3 5192.5 5100.8 5163.9 5200.4 5251.8 5257.0 5041.4 5104.8 5146.8 5185.0 5217.4 5090.7 5157.2 5171.3 5207.7 5226.8 4553.5 5591.1 5654.9 5754.2 5824.2 6154.6 6181.2 6201.9 6207.5 6227.9 6228.6 6231.4 6245.7 6247.1 6256.2 5717.8 5782.3 5806.7 5921.8 5958.9 6207.7 6246.7 6272.4 6295.9 6359.5 6203.0 6204.1 6205.9 6221.1 6222.7 6135.8 6236.3 6243.3 6250.4 6256.4 6127.3 6131.7 6165.8 6170.5 6209.8 6194.9 6202.7 6205.4 6208.1 6223.5 4910.8 4964.5 4977.7 5005.9 5017.7 4401.8 4402.7 4428.4 4436.4 4484.0 5758.2 5903.8 5923.8 5926.4 5935.9 6125.7 6170.2 6177.5 6247.5 6254.8 5889.7 5918.8 5920.9 5935.8 5991.7 5316.2 5393.6 5393.7 5399.8 5426.5 6354.4 6358.7 6378.9 6392.2 6392.7 6232.2 6258.3 6263.4 6264.2 6287.8 6150.4 6249.4 6255.0 6293.3 6365.4 5292.0 6191.1 6196.3 6197.3 6197.5 6213.5 6214.0 6225.6 6241.5 6249.2 5975.6 5983.3 6063.8 6120.2 6121.3 6103.2 6122.2 6138.6 6182.0 6208.0 5046.5 5811.2 5929.2 6034.1 6105.0 5683.3 6136.5 6153.2 6158.8 6214.9 4786.6 5296.2 5943.3 5956.5 5991.6 03:36:49 INFO - PROCESS | 1662 | 03:36:49 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:63410.44 Median:37456.44 stddev:137667.04 (367.5%) stddev-sans-first:137972.04 03:36:49 INFO - PROCESS | 1662 | Values: 435.1 452.9 482.4 504.5 537.9 33048.2 37672.8 39604.0 39679.6 41066.8 40445.0 40983.4 41082.8 41150.2 41153.0 9339.9 9445.1 9511.3 9512.1 9534.5 9383.5 9386.0 9411.3 9460.7 9512.2 9465.7 9485.0 9511.7 9531.5 9550.9 8152.6 9401.9 9428.8 9503.0 9522.6 9272.2 9324.4 9329.2 9341.0 9443.3 38026.0 38314.8 38376.4 38488.8 38491.0 3125.9 3128.6 3148.4 3248.8 3282.0 28712.4 28907.9 29216.7 29266.0 29523.9 3159.9 3244.5 3247.7 3266.0 3266.1 498363.0 500371.0 500683.0 503632.0 504456.5 54977.5 55028.4 55332.0 55407.0 55598.4 55770.0 56556.7 57426.1 57610.7 57707.0 15230.8 15317.8 15365.1 15516.8 15521.8 12197.8 12325.7 12398.3 12401.4 12410.2 13231.5 13599.4 13648.7 13719.2 13786.2 3172.2 3173.0 3256.7 5270.7 5913.6 822.3 953.5 1003.7 1018.2 1087.3 28613.6 29457.6 29516.0 30092.6 30506.4 952.3 1024.6 1032.6 1085.3 1204.1 11890.8 14220.2 14253.7 14550.4 14740.0 359490.0 362048.0 362602.0 362632.0 679431.0 51561.0 51700.5 51897.7 51968.5 52068.7 1341.7 1386.7 1425.7 1554.9 1600.3 26279.9 27217.9 27774.7 27904.7 27949.0 46596.3 46915.5 46970.5 47129.3 47260.8 581984.0 587950.0 591127.0 591794.0 592096.1 21845.0 24779.6 24986.0 25099.0 25159.9 42715.6 44076.0 45299.3 45612.8 45645.5 28969.1 34745.1 37309.8 37410.1 37502.8 36306.6 36738.4 36880.8 37534.8 37744.6 36237.0 37006.6 37104.4 37304.6 38479.6 30284.1 33776.2 33975.2 34132.8 34296.8 21053.5 21711.6 24817.3 25352.7 25424.6 03:36:49 INFO - PROCESS | 1662 | 03:36:49 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:36331.40 Median:2107.40 stddev:100675.93 (4777.3%) stddev-sans-first:100922.15 03:36:49 INFO - PROCESS | 1662 | Values: 372.4 376.6 382.4 388.5 402.9 14435.5 15347.5 15977.9 17743.9 20187.8 18259.7 19442.1 19471.4 19991.9 20135.9 5695.9 7401.4 7770.5 7852.0 7865.3 6007.2 6541.0 7694.0 7759.5 7798.2 7543.9 7724.8 7853.8 7858.7 7866.6 7843.5 7881.9 7956.0 7967.1 7984.6 7367.0 7651.2 7661.7 7664.4 7686.2 23479.0 26593.0 27274.8 27670.9 28132.9 2253.5 2361.4 2444.1 2516.8 2674.3 16154.0 16652.6 16894.4 16952.3 17546.2 1974.5 1998.5 2031.2 2078.9 2083.4 97821.0 140301.0 148886.3 150316.2 152722.0 21700.0 21745.7 21937.9 22095.6 22148.9 30908.1 35105.3 35226.0 35282.6 35997.3 10294.6 10572.4 10689.5 10731.8 10741.2 8669.2 8714.9 8749.1 8800.0 8872.0 9618.4 9800.7 9943.5 9955.6 9969.3 3832.4 3860.8 3864.6 3879.4 3896.4 797.4 833.3 891.9 902.6 941.4 19430.0 20226.2 20241.0 20302.8 20526.5 817.3 888.3 933.4 947.4 979.8 10456.8 10651.3 10714.1 10749.5 10771.9 561469.0 577469.1 617166.0 626073.0 638364.8 70278.9 74628.0 96341.5 120848.0 133093.0 927.5 956.4 967.8 978.7 978.9 17069.5 17687.9 18118.9 18303.4 18501.6 54128.7 54428.6 54687.8 55101.4 55996.4 78118.6 78220.0 78323.2 78758.0 79002.8 6806.7 6943.0 6980.0 7590.2 7978.6 9904.2 10113.2 12406.8 12438.6 12475.9 10293.5 10732.8 10923.1 11074.5 11077.7 10766.2 10896.4 10922.6 10923.5 11034.0 8998.7 11006.2 11146.3 11197.9 11209.3 7953.4 8132.2 8187.1 8343.9 8356.8 9996.6 10743.5 10838.0 10841.1 11199.4 03:36:49 INFO - PROCESS | 1662 | 03:36:49 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5397.48 Median:5072.19 stddev:2940.34 (58.0%) stddev-sans-first:2933.63 03:36:49 INFO - PROCESS | 1662 | Values: 1451.0 1457.0 1661.3 1765.4 1806.9 7516.0 7774.5 8052.6 8472.0 8488.7 7957.3 8252.7 8283.7 8360.6 8365.5 2308.4 2343.6 2350.7 2351.3 2351.7 2325.3 2372.0 2374.8 2380.9 2387.0 2308.7 2329.2 2330.6 2353.5 2357.1 2298.0 2299.9 2331.5 2332.0 2332.5 2327.7 2350.5 2357.7 2362.4 2368.3 8310.5 8335.5 8370.1 8386.2 8398.4 4301.8 5040.1 5104.3 5160.9 5219.3 3362.2 3499.8 3608.4 3620.2 3622.9 1030.8 1034.0 1036.8 1037.7 1080.2 10830.7 10837.8 10916.5 10920.8 10923.7 4518.1 4597.4 4707.8 4726.6 4728.6 9217.8 9348.6 9364.1 9366.2 9372.3 5179.8 5180.9 5190.8 5192.2 5204.5 4245.0 4252.7 4702.7 4767.2 4777.3 3280.7 3366.3 3684.6 3699.5 3798.3 1279.3 1297.4 1300.9 1313.5 1321.4 822.4 908.3 912.2 920.4 930.8 5659.2 5704.0 5751.3 5805.0 5805.0 894.5 900.1 907.5 927.2 931.6 3701.1 3758.1 3821.1 3822.4 3872.3 9705.5 9959.2 10018.0 10103.6 10104.5 9943.1 9968.7 10035.3 10041.8 10068.4 7381.4 7663.4 7818.3 7837.5 7869.6 4185.8 4658.3 4796.7 5304.9 5627.7 8494.8 8589.1 9206.9 9338.6 9339.5 9028.3 9294.3 9323.5 9361.9 9384.0 6626.2 6627.8 6676.5 6743.0 6902.9 7762.8 7919.4 7929.8 7945.2 7975.5 6232.8 6941.9 7040.5 7082.7 7104.8 6964.4 7068.2 7082.3 7097.7 7135.7 6447.4 6604.2 6691.3 6741.0 6774.2 4851.8 4860.8 4863.6 4896.6 4912.5 4848.0 4879.8 4899.4 4986.9 4999.8 03:36:49 INFO - PROCESS | 1662 | 03:36:49 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:4460.16 Median:4954.21 stddev:1343.22 (27.1%) stddev-sans-first:1310.20 03:36:49 INFO - PROCESS | 1662 | Values: 300.0 301.5 302.5 307.9 311.2 4345.4 5262.9 5270.2 5386.6 5414.4 4719.9 4909.8 4939.0 5226.7 5423.5 3467.8 4118.7 4140.5 4141.0 4173.3 3953.8 4118.0 4144.2 4146.1 4154.2 3731.6 3801.7 3812.9 3940.6 4133.6 3840.5 4083.4 4083.8 4105.0 4113.6 3108.2 3589.8 3604.0 3667.9 3684.0 4291.7 4604.6 4696.9 4999.4 5253.8 4394.5 4403.7 4495.0 4715.8 4766.5 4853.0 5364.8 5401.2 5504.5 5714.7 3623.9 3717.7 3724.7 3983.3 3988.8 5586.0 5605.6 5746.8 5985.3 6011.5 3499.8 3511.9 3930.6 4082.5 4118.1 3954.7 4175.8 4186.9 4202.8 4215.6 4056.4 4726.0 4753.4 4773.6 4802.7 5520.0 5753.4 5782.3 5793.4 5823.9 1241.2 1270.4 1277.9 1304.3 1353.9 905.9 919.5 925.7 930.0 930.9 4224.4 4585.6 4855.5 5073.6 5099.2 5256.6 5330.7 5388.2 5550.6 5562.7 4088.8 4999.8 5025.5 5045.9 5105.7 2459.3 2562.0 2563.2 2674.9 2697.0 4609.6 4681.2 5157.8 5637.4 5674.4 4843.9 4933.0 5381.4 5673.2 5696.8 4874.3 4969.4 5166.4 5520.9 5717.0 4886.7 5088.7 5170.6 5532.9 5572.0 5125.9 5285.7 5415.1 5474.5 5485.0 4692.7 5098.3 5321.5 5640.8 5731.5 3613.6 3930.8 3961.8 3978.8 3985.9 4992.4 5231.9 5898.0 5925.8 5940.1 5160.8 5394.7 5424.8 5726.5 5767.1 4882.8 5110.1 5186.3 5463.5 5510.3 4449.5 4978.7 5365.0 5629.9 5718.4 5001.9 5287.4 5422.3 5616.9 5782.5 5301.5 5604.6 5611.3 5631.5 5697.5 03:36:49 INFO - PROCESS | 1662 | 03:36:49 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:19258.57 Median:48857.88 stddev:27253.03 (55.8%) stddev-sans-first:27293.88 03:36:49 INFO - PROCESS | 1662 | Values: 708.4 729.9 743.5 786.6 792.2 14011.5 14784.6 14791.2 14829.0 14847.9 14401.6 14863.7 14902.9 15347.6 15368.9 646.0 671.5 793.6 799.9 820.6 789.6 802.4 820.0 820.2 823.2 867.9 871.3 872.6 873.0 874.5 838.2 862.3 868.4 868.8 868.9 618.5 634.3 639.7 640.3 641.7 14621.3 14791.1 14824.9 14971.2 15072.7 10064.6 11052.7 13197.3 13201.4 13635.3 9370.8 11205.1 11351.9 11800.8 11827.0 3534.0 3631.2 3672.7 3780.0 3805.4 78413.0 92393.2 95785.5 96691.0 96882.5 10256.8 10404.5 10418.3 10432.4 10489.9 18623.3 18743.5 20239.2 20404.5 20554.6 7366.3 7493.0 7513.4 7578.4 7672.6 4194.8 4264.9 4289.9 4293.9 4300.8 2983.3 3436.9 3453.3 3466.2 3636.1 655.0 657.6 694.8 697.6 718.5 574.7 583.7 584.6 626.0 663.7 8846.3 9083.5 9163.6 9366.7 9417.2 581.9 599.2 641.4 649.7 685.2 4248.5 4463.1 4471.0 4554.7 4605.2 66999.0 88128.6 98453.5 98596.0 105980.0 33823.7 80901.0 85131.6 86953.0 88164.0 71878.6 73875.5 74982.3 81613.6 84254.0 9270.2 9772.3 9826.5 9828.3 9844.2 47491.8 50224.0 50689.2 51581.2 53238.5 69744.0 70468.0 74197.6 74246.0 78635.0 11795.6 12166.3 12283.2 12290.4 12315.2 13669.4 13933.4 13954.0 14070.8 14218.6 11240.2 12751.6 12783.4 12937.9 12945.6 12822.5 12957.3 13009.9 13102.8 13249.2 12663.7 12678.1 12721.7 12759.7 12903.3 10936.9 11092.5 11121.1 11404.5 11581.3 9936.2 10479.4 11093.4 11170.4 11254.6 03:36:49 INFO - PROCESS | 1662 | -------- Summary: end -------- 03:36:49 INFO - PROCESS | 1662 | 03:36:50 INFO - TEST-INFO | 1662: exit 0 03:36:50 INFO - TEST-OK | dromaeo_css | took 1117411ms 03:36:50 INFO - TEST-START | kraken 03:36:50 INFO - operating with platform_type : mac_ 03:36:50 INFO - Initialising browser for kraken test... 03:36:50 INFO - TEST-INFO | started process 1691 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/tmpVXmfFw/profile http://localhost:49241/getInfo.html) 03:36:53 INFO - PROCESS | 1691 | 2016-12-18 03:36:53.841 plugin-container[1693:18717] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8737, name = 'com.apple.tsm.portname' 03:36:53 INFO - PROCESS | 1691 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:36:53 INFO - PROCESS | 1691 | 2016-12-18 03:36:53.842 plugin-container[1693:18717] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4317, name = 'com.apple.CFPasteboardClient' 03:36:53 INFO - PROCESS | 1691 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:36:53 INFO - PROCESS | 1691 | 2016-12-18 03:36:53.842 plugin-container[1693:18717] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:36:53 INFO - PROCESS | 1691 | __metrics Screen width/height:1600/1200 03:36:53 INFO - PROCESS | 1691 | colorDepth:24 03:36:53 INFO - PROCESS | 1691 | Browser inner width/height: 1024/689 03:36:53 INFO - PROCESS | 1691 | __metrics 03:36:53 ERROR - PROCESS | 1691 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 03:36:54 INFO - TEST-INFO | 1691: exit 0 03:36:54 INFO - Browser initialized. 03:36:54 INFO - Running cycle 1/1 for kraken test... 03:36:54 INFO - TEST-INFO | started process 1694 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/tmpVXmfFw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:36:57 INFO - PROCESS | 1694 | 2016-12-18 03:36:57.280 plugin-container[1695:18842] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x874f, name = 'com.apple.tsm.portname' 03:36:57 INFO - PROCESS | 1694 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:36:57 INFO - PROCESS | 1694 | 2016-12-18 03:36:57.281 plugin-container[1695:18842] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8753, name = 'com.apple.CFPasteboardClient' 03:36:57 INFO - PROCESS | 1694 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:36:57 INFO - PROCESS | 1694 | 2016-12-18 03:36:57.281 plugin-container[1695:18842] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:37:06 INFO - PROCESS | 1694 | RSS: Main: 229400576 03:37:06 INFO - PROCESS | 1694 | 03:37:09 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 03:37:10 INFO - PROCESS | 1694 | RSS: Main: 241491968 03:37:10 INFO - PROCESS | 1694 | 03:37:13 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 03:37:14 INFO - PROCESS | 1694 | RSS: Main: 249229312 03:37:14 INFO - PROCESS | 1694 | 03:37:17 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 03:37:18 INFO - PROCESS | 1694 | RSS: Main: 252768256 03:37:18 INFO - PROCESS | 1694 | 03:37:19 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 03:37:20 INFO - PROCESS | 1694 | RSS: Main: 253210624 03:37:20 INFO - PROCESS | 1694 | 03:37:22 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:37:23 INFO - PROCESS | 1694 | RSS: Main: 254517248 03:37:23 INFO - PROCESS | 1694 | 03:37:25 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 03:37:26 INFO - PROCESS | 1694 | RSS: Main: 258473984 03:37:26 INFO - PROCESS | 1694 | 03:37:29 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 03:37:30 INFO - PROCESS | 1694 | RSS: Main: 258908160 03:37:30 INFO - PROCESS | 1694 | 03:37:33 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 03:37:33 INFO - PROCESS | 1694 | RSS: Main: 258158592 03:37:33 INFO - PROCESS | 1694 | 03:37:35 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:37:36 INFO - PROCESS | 1694 | RSS: Main: 257130496 03:37:36 INFO - PROCESS | 1694 | 03:37:37 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:37:38 INFO - PROCESS | 1694 | RSS: Main: 257175552 03:37:38 INFO - PROCESS | 1694 | 03:37:40 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:37:41 INFO - PROCESS | 1694 | RSS: Main: 257146880 03:37:41 INFO - PROCESS | 1694 | 03:37:44 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:37:45 INFO - PROCESS | 1694 | RSS: Main: 257236992 03:37:45 INFO - PROCESS | 1694 | 03:37:48 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:37:48 INFO - PROCESS | 1694 | RSS: Main: 257527808 03:37:48 INFO - PROCESS | 1694 | 03:37:50 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 03:37:50 INFO - PROCESS | 1694 | RSS: Main: 259026944 03:37:50 INFO - PROCESS | 1694 | 03:37:50 INFO - PROCESS | 1694 | __start_tp_report 03:37:50 INFO - PROCESS | 1694 | _x_x_mozilla_page_load 03:37:50 INFO - PROCESS | 1694 | _x_x_mozilla_page_load_details 03:37:50 INFO - PROCESS | 1694 | |i|pagename|runs| 03:37:50 INFO - PROCESS | 1694 | |0;ai-astar;107;119;118;121;120;122;124;121;121;120 03:37:50 INFO - PROCESS | 1694 | |1;audio-beat-detection;145;113;119;145;116;114;129;142;147;141 03:37:50 INFO - PROCESS | 1694 | |2;audio-dft;194;199;195;200;271;247;195;200;197;194 03:37:50 INFO - PROCESS | 1694 | |3;audio-fft;85;59;60;87;61;62;60;60;76;62 03:37:50 INFO - PROCESS | 1694 | |4;audio-oscillator;87;72;92;73;70;71;85;67;70;87 03:37:50 INFO - PROCESS | 1694 | |5;imaging-gaussian-blur;94;81;124;89;94;90;93;93;93;90 03:37:50 INFO - PROCESS | 1694 | |6;imaging-darkroom;143;97;98;98;103;98;98;99;102;143 03:37:50 INFO - PROCESS | 1694 | |7;imaging-desaturate;91;89;118;108;91;91;91;99;75;132 03:37:50 INFO - PROCESS | 1694 | |8;json-parse-financial;57;57;57;67;76;83;87;87;82;59 03:37:50 INFO - PROCESS | 1694 | |9;json-stringify-tinderbox;69;72;73;84;77;70;71;70;72;72 03:37:50 INFO - PROCESS | 1694 | |10;stanford-crypto-aes;89;92;85;84;87;88;86;84;85;85 03:37:50 INFO - PROCESS | 1694 | |11;stanford-crypto-ccm;141;164;179;134;157;140;164;146;187;140 03:37:50 INFO - PROCESS | 1694 | |12;stanford-crypto-pbkdf2;171;170;170;197;222;186;170;167;169;172 03:37:50 INFO - PROCESS | 1694 | |13;stanford-crypto-sha256-iterative;69;71;70;68;71;70;67;83;74;68 03:37:50 INFO - PROCESS | 1694 | __end_tp_report 03:37:50 INFO - PROCESS | 1694 | __start_cc_report 03:37:50 INFO - PROCESS | 1694 | _x_x_mozilla_cycle_collect,973 03:37:50 INFO - PROCESS | 1694 | __end_cc_report 03:37:50 INFO - PROCESS | 1694 | __startTimestamp1482061070657__endTimestamp 03:37:50 INFO - PROCESS | 1694 | 03:37:50 INFO - PROCESS | 1694 | ------- Summary: start ------- 03:37:50 INFO - PROCESS | 1694 | Number of tests: 14 03:37:50 INFO - PROCESS | 1694 | 03:37:50 INFO - PROCESS | 1694 | [#0] ai-astar Cycles:10 Average:119.30 Median:121.00 stddev:4.62 (3.8%) stddev-sans-first:1.73 03:37:50 INFO - PROCESS | 1694 | Values: 107.0 119.0 118.0 121.0 120.0 122.0 124.0 121.0 121.0 120.0 03:37:50 INFO - PROCESS | 1694 | 03:37:50 INFO - PROCESS | 1694 | [#1] audio-beat-detection Cycles:10 Average:131.10 Median:141.50 stddev:14.36 (10.1%) stddev-sans-first:14.32 03:37:50 INFO - PROCESS | 1694 | Values: 145.0 113.0 119.0 145.0 116.0 114.0 129.0 142.0 147.0 141.0 03:37:50 INFO - PROCESS | 1694 | 03:37:50 INFO - PROCESS | 1694 | [#2] audio-dft Cycles:10 Average:209.20 Median:199.50 stddev:26.95 (13.5%) stddev-sans-first:28.02 03:37:50 INFO - PROCESS | 1694 | Values: 194.0 199.0 195.0 200.0 271.0 247.0 195.0 200.0 197.0 194.0 03:37:50 INFO - PROCESS | 1694 | 03:37:50 INFO - PROCESS | 1694 | [#3] audio-fft Cycles:10 Average:67.20 Median:62.00 stddev:11.06 (17.8%) stddev-sans-first:9.68 03:37:50 INFO - PROCESS | 1694 | Values: 85.0 59.0 60.0 87.0 61.0 62.0 60.0 60.0 76.0 62.0 03:37:50 INFO - PROCESS | 1694 | 03:37:50 INFO - PROCESS | 1694 | [#4] audio-oscillator Cycles:10 Average:77.40 Median:79.00 stddev:9.20 (11.7%) stddev-sans-first:9.08 03:37:50 INFO - PROCESS | 1694 | Values: 87.0 72.0 92.0 73.0 70.0 71.0 85.0 67.0 70.0 87.0 03:37:50 INFO - PROCESS | 1694 | 03:37:50 INFO - PROCESS | 1694 | [#5] imaging-gaussian-blur Cycles:10 Average:94.10 Median:93.00 stddev:11.20 (12.0%) stddev-sans-first:11.88 03:37:50 INFO - PROCESS | 1694 | Values: 94.0 81.0 124.0 89.0 94.0 90.0 93.0 93.0 93.0 90.0 03:37:50 INFO - PROCESS | 1694 | 03:37:50 INFO - PROCESS | 1694 | [#6] imaging-darkroom Cycles:10 Average:107.90 Median:98.00 stddev:18.60 (19.0%) stddev-sans-first:14.76 03:37:50 INFO - PROCESS | 1694 | Values: 143.0 97.0 98.0 98.0 103.0 98.0 98.0 99.0 102.0 143.0 03:37:50 INFO - PROCESS | 1694 | 03:37:50 INFO - PROCESS | 1694 | [#7] imaging-desaturate Cycles:10 Average:98.50 Median:91.00 stddev:16.53 (18.2%) stddev-sans-first:17.31 03:37:50 INFO - PROCESS | 1694 | Values: 91.0 89.0 118.0 108.0 91.0 91.0 91.0 99.0 75.0 132.0 03:37:50 INFO - PROCESS | 1694 | 03:37:50 INFO - PROCESS | 1694 | [#8] json-parse-financial Cycles:10 Average:71.20 Median:79.00 stddev:13.12 (16.6%) stddev-sans-first:12.87 03:37:50 INFO - PROCESS | 1694 | Values: 57.0 57.0 57.0 67.0 76.0 83.0 87.0 87.0 82.0 59.0 03:37:50 INFO - PROCESS | 1694 | 03:37:50 INFO - PROCESS | 1694 | [#9] json-stringify-tinderbox Cycles:10 Average:73.00 Median:72.00 stddev:4.45 (6.2%) stddev-sans-first:4.48 03:37:50 INFO - PROCESS | 1694 | Values: 69.0 72.0 73.0 84.0 77.0 70.0 71.0 70.0 72.0 72.0 03:37:50 INFO - PROCESS | 1694 | 03:37:50 INFO - PROCESS | 1694 | [#10] stanford-crypto-aes Cycles:10 Average:86.50 Median:86.50 stddev:2.55 (2.9%) stddev-sans-first:2.54 03:37:50 INFO - PROCESS | 1694 | Values: 89.0 92.0 85.0 84.0 87.0 88.0 86.0 84.0 85.0 85.0 03:37:50 INFO - PROCESS | 1694 | 03:37:50 INFO - PROCESS | 1694 | [#11] stanford-crypto-ccm Cycles:10 Average:155.20 Median:160.50 stddev:18.05 (11.2%) stddev-sans-first:18.40 03:37:50 INFO - PROCESS | 1694 | Values: 141.0 164.0 179.0 134.0 157.0 140.0 164.0 146.0 187.0 140.0 03:37:50 INFO - PROCESS | 1694 | 03:37:50 INFO - PROCESS | 1694 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.40 Median:171.50 stddev:17.70 (10.3%) stddev-sans-first:18.51 03:37:50 INFO - PROCESS | 1694 | Values: 171.0 170.0 170.0 197.0 222.0 186.0 170.0 167.0 169.0 172.0 03:37:50 INFO - PROCESS | 1694 | 03:37:50 INFO - PROCESS | 1694 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.10 Median:70.50 stddev:4.63 (6.6%) stddev-sans-first:4.85 03:37:50 INFO - PROCESS | 1694 | Values: 69.0 71.0 70.0 68.0 71.0 70.0 67.0 83.0 74.0 68.0 03:37:50 INFO - PROCESS | 1694 | -------- Summary: end -------- 03:37:50 INFO - PROCESS | 1694 | 03:37:51 INFO - TEST-INFO | 1694: exit 0 03:37:51 INFO - TEST-OK | kraken | took 61005ms 03:37:51 INFO - SUITE-END | took 1178s 03:37:51 INFO - Completed test suite (00:19:38) 03:37:51 INFO - javascript benchmark 03:37:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7246.6977496066365, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2701.783857291417, 3339.632640409556, 3382.560267165268, 3389.525466434699, 3397.473391624296, 5652.745626446809, 5666.235058267132, 5698.259226300585, 5715.9997000524945, 5748.511376533, 5746.310442746866, 5790.942090579093, 5801.854953626162, 5811.099279611656, 5876.882462350751, 4319.265724826787, 5165.664231824938, 5182.611304152185, 5193.922091168635, 5275.261463395128, 3830.138218900739, 4338.17574660815, 4538.863834084983, 4641.280601506755, 5192.468221874556, 5100.770465329032, 5163.948360516384, 5200.3759548854005, 5251.789928402859, 5257.0, 5041.369828771403, 5104.846854594368, 5146.845594632129, 5185.0148471790235, 5217.373915130209, 5090.720010399426, 5157.174852023658, 5171.3277273954145, 5207.713575753332, 5226.817061402832, 4553.4535855697195, 5591.133374326945, 5654.886902261932, 5754.1944127822135, 5824.24284842972, 6154.569180157391, 6181.196444462236, 6201.937980620181, 6207.4723648489935, 6227.875442491125, 6228.626282423023, 6231.376862313778, 6245.718942647546, 6247.125402443661, 6256.217972753406, 5717.828465146053, 5782.335031471351, 5806.65160090396, 5921.822345329647, 5958.910616340801, 6207.689615519296, 6246.718897649616, 6272.372762723782, 6295.905561416583, 6359.523035772336, 6203.0, 6204.13142160098, 6205.944989351821, 6221.129041934042, 6222.657753823492, 6135.754569817157, 6236.314005459396, 6243.344448832898, 6250.3749625037135, 6256.374362563707, 6127.325994140641, 6131.651036771903, 6165.784197553064, 6170.475509581646, 6209.844753881189, 6194.907076393858, 6202.689865506797, 6205.410486003822, 6208.1308616793685, 6223.533235007392, 4910.754462276872, 4964.503549645006, 4977.701337919664, 5005.924911126336, 5017.698938063655, 4401.823927042947, 4402.71382360152, 4428.3800267962515, 4436.3567282743825, 4484.013517026249, 5758.222639943554, 5903.793367232126, 5923.822285331447, 5926.436988486087, 5935.881282374328, 6125.662799505599, 6170.228721409824, 6177.536684748662, 6247.531435142382, 6254.781082662085, 5889.705514724246, 5918.822435326947, 5920.851978700481, 5935.7625694972585, 5991.700414979321, 5316.202569614588, 5393.568514518829, 5393.703346315911, 5399.83800485986, 5426.457354264621, 6354.3963323484195, 6358.650274234961, 6378.904316435164, 6392.232932048184, 6392.680365981775, 6232.189815323979, 6258.280297765633, 6263.404976527223, 6264.185655864709, 6287.811365659038, 6150.384961503813, 6249.406306400884, 6254.968725156527, 6293.276273228637, 6365.363463653597, 5292.02097611943, 6191.102290167991, 6196.256449226122, 6197.349278325711, 6197.535184861244, 6213.5339849512375, 6214.0, 6225.595336303126, 6241.531885108544, 6249.218847644044, 5975.551833612583, 5983.341832398346, 6063.848403789764, 6120.17377654011, 6121.326654068138, 6103.206583144163, 6122.184701511036, 6138.600990935575, 6182.041783523561, 6207.96896015517, 5046.520580544842, 5811.215485909348, 5929.229200203946, 6034.125051867367, 6104.969475152596, 5683.2895888014, 6136.509079273558, 6153.169322141633, 6158.815235542941, 6214.943459611786, 4786.593139583175, 5296.179092240709, 5943.257092863392, 5956.493698035542, 5991.550633702403], "value": 5655.1633446773585, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [435.0754646376449, 452.8664044106988, 482.43073173655074, 504.5005444609835, 537.8735997040694, 33048.17379565512, 37672.81163594181, 39604.00989975245, 39679.603203967985, 41066.79466602663, 40444.98887527813, 40983.38524922128, 41082.7945860271, 41150.17699646005, 41153.0, 9339.859902101474, 9445.05549445057, 9511.286653501014, 9512.143907048365, 9534.523273836281, 9383.530823458834, 9386.0, 9411.341206115574, 9460.668876589321, 9512.191463725592, 9465.716028519155, 9485.004074572174, 9511.667091651794, 9531.523423828816, 9550.904490955105, 8152.592370381457, 9401.858972115424, 9428.764280892998, 9503.0, 9522.571484283224, 9272.165505104505, 9324.393914395543, 9329.160375566164, 9341.019192984706, 9443.291753118476, 38026.049348766224, 38314.80842595797, 38376.42435363472, 38488.80755596232, 38491.03772405684, 3125.87496500142, 3128.562001319817, 3148.354587309596, 3248.8213148276836, 3281.9825853985103, 28712.425751484854, 28907.855460722563, 29216.707832921613, 29266.0, 29523.852380738175, 3159.921001974969, 3244.4971029490466, 3247.7401807855313, 3266.0, 3266.0528446750413, 498363.0, 500371.0, 500683.0, 503632.0, 504456.47771761275, 54977.45022549803, 55028.449715503135, 55332.0, 55407.0, 55598.44401556013, 55770.0, 56556.71721641366, 57426.13860792091, 57610.71194644084, 57707.0, 15230.771538426932, 15317.846821531864, 15365.078095314317, 15516.767248491282, 15521.767173492406, 12197.756044879052, 12325.69185770345, 12398.318092504818, 12401.441935112942, 12410.193337433038, 13231.53689620878, 13599.388027538784, 13648.727025459435, 13719.245441500614, 13786.172829630254, 3172.191850645156, 3173.0480855743185, 3256.739460843127, 5270.7101109439345, 5913.615614985012, 822.268181318627, 953.4517652349788, 1003.6738060130341, 1018.2363227579316, 1087.2660953856273, 28613.57079643807, 29457.55813662797, 29516.0, 30092.59259259254, 30506.38987220243, 952.3143336797494, 1024.5850430575629, 1032.6334151376154, 1085.2891356161726, 1204.1029433072417, 11890.821637675443, 14220.21788801626, 14253.714925701635, 14550.41798328055, 14740.041897276857, 359490.0, 362048.0, 362602.0, 362632.0, 679431.0, 51560.96878062493, 51700.48299517032, 51897.740511297205, 51968.480315196364, 52068.69828254248, 1341.677997280665, 1386.729587730403, 1425.6720954180596, 1554.8542569534154, 1600.3358606178538, 26279.868600656875, 27217.911283548437, 27774.722252777214, 27904.720952790212, 27949.0, 46596.30105548352, 46915.530844691115, 46970.53029469662, 47129.293060603435, 47260.7636961813, 581984.0, 587950.0, 591127.0, 591794.0, 592096.1185582133, 21845.0, 24779.628305575072, 24986.0, 25099.0, 25159.87420062888, 42715.5728442724, 44076.0, 45299.32051019171, 45612.771936141435, 45645.54354456546, 28969.130926072252, 34745.13137171591, 37309.81345093257, 37410.064748381505, 37502.812260958126, 36306.63693363138, 36738.448923266704, 36880.81559592292, 37534.8123259382, 37744.62255377521, 36237.0, 37006.629933700315, 37104.443433349064, 37304.626953731204, 38479.61520384761, 30284.09147725572, 33776.15559610931, 33975.150621234665, 34132.82933585316, 34296.82851585726, 21053.521182775607, 21711.565768684854, 24817.25548233556, 25352.74647253504, 25424.618630720925], "value": 18565.50201241809, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.36883482497177, 376.60080314866235, 382.4359070371202, 388.4639197906888, 402.92545879012386, 14435.494757683484, 15347.539573812775, 15977.92011039946, 17743.93536387816, 20187.798122018776, 18259.72610410845, 19442.1251043703, 19471.4158575243, 19991.900040499815, 20135.899320503377, 5695.857603559903, 7401.444891633122, 7770.4560680752365, 7851.960740196319, 7865.252800983926, 6007.249093863267, 6540.9672951635175, 7693.961530192341, 7759.495632783879, 7798.220177982184, 7543.924560754377, 7724.845503089935, 7853.764387068397, 7858.68565257389, 7866.567338796364, 7843.450958432912, 7881.921180788233, 7955.960220198891, 7967.083785364699, 7984.560849153294, 7367.0, 7651.234876512304, 7661.65522551481, 7664.425168112358, 7686.192949740309, 23479.0, 26593.0, 27274.772635231464, 27670.861645691843, 28132.859335703397, 2253.4704344479032, 2361.3624321433117, 2444.1201167579657, 2516.811239157052, 2674.291312802109, 16154.030758154431, 16652.58368540784, 16894.40869569559, 16952.3219071237, 17546.210420531108, 1974.4866334753067, 1998.5103649605794, 2031.2078289466972, 2078.8752674839407, 2083.447886310129, 97821.0, 140301.0, 148886.25556872148, 150316.2484187572, 152721.9999999989, 21700.0, 21745.67381489279, 21937.890310548664, 22095.563788353706, 22148.88925555362, 30908.072757817303, 35105.29789404198, 35226.0, 35282.647173527934, 35997.28005439877, 10294.639687610897, 10572.36565806054, 10689.518971646294, 10731.83902241467, 10741.24811263204, 8669.176428239307, 8714.869276960851, 8749.081346458657, 8799.956000220085, 8872.048149407121, 9618.374805637612, 9800.705978820646, 9943.45311007887, 9955.55200016001, 9969.252306077073, 3832.3676593362343, 3860.82626281818, 3864.613538646113, 3879.3599056155417, 3896.4414234306505, 797.421869144877, 833.2708879730236, 891.8751374807532, 902.5622573052065, 941.3833938770116, 19430.028498575015, 20226.190952362034, 20241.0, 20302.796972030384, 20526.486837828874, 817.3093735793245, 888.2849306308354, 933.407286373158, 947.3794664494767, 979.8285300072458, 10456.790864182674, 10651.30766500175, 10714.142868570496, 10749.462526873625, 10771.892281077246, 561469.0, 577469.112654434, 617166.0, 626073.0, 638364.8081759561, 70278.94581581281, 74628.0, 96341.51829240809, 120848.0, 133093.0, 927.4759760735163, 956.4213650741201, 967.777411195429, 978.6770365779275, 978.8629591857145, 17069.48791536256, 17687.91156044263, 18118.91286522813, 18303.359382422084, 18501.629967400306, 54128.72935635297, 54428.639284018216, 54687.78734846659, 55101.428699973454, 55996.44003560075, 78118.60940695487, 78220.0, 78323.21676783159, 78758.0, 79002.8398864039, 6806.65966701653, 6942.965285173744, 6979.987901754319, 7590.20302868213, 7978.640961156878, 9904.158146557624, 10113.24150688716, 12406.821351971548, 12438.564650237198, 12475.93762031184, 10293.53679084428, 10732.839007415052, 10923.071538918963, 11074.501647425725, 11077.72606150303, 10766.192535559707, 10896.400697961688, 10922.617708380169, 10923.453827308762, 11034.0, 8998.68504602362, 11006.174536909924, 11146.275492092987, 11197.888021119685, 11209.327440353605, 7953.443258971816, 8132.227438393344, 8187.058488273925, 8343.874841877256, 8356.832863342577, 9996.550155243178, 10743.516541755484, 10838.0, 10841.078508326565, 11199.440027998404], "value": 10836.047258452227, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1451.0, 1457.0237940579811, 1661.3188592677004, 1765.4023109086281, 1806.9277228910844, 7515.962420187891, 7774.53352798831, 8052.5973701314915, 8471.957640211798, 8488.702895398663, 7957.283844453998, 8252.711155109573, 8283.710070147547, 8360.581970901461, 8365.498070115782, 2308.411355104446, 2343.6015877300815, 2350.7061617297836, 2351.2946116165167, 2351.7413084560685, 2325.2559181061984, 2372.0393240737444, 2374.833761636686, 2380.8928598213033, 2386.9761302387014, 2308.68832707584, 2329.184785325131, 2330.5571941331177, 2353.5057637895975, 2357.092519380034, 2298.0, 2299.850509716863, 2331.4754180309296, 2332.043862016582, 2332.4518738096617, 2327.674125622414, 2350.4593943392942, 2357.7288611809518, 2362.4448254660315, 2368.301351101412, 8310.501369917763, 8335.458195217292, 8370.079291277954, 8386.161383861627, 8398.412111152222, 4301.849435269751, 5040.092783299005, 5104.285400043997, 5160.92258616121, 5219.269302297681, 3362.2434952135573, 3499.8075105869166, 3608.4046132388125, 3620.167361506868, 3622.9275414491563, 1030.814453398388, 1034.0, 1036.8185567525652, 1037.725002874239, 1080.1790639114251, 10830.72923176919, 10837.83743243852, 10916.454177291103, 10920.836187457195, 10923.726906827313, 4518.118966801442, 4597.42532183477, 4707.81168753246, 4726.550977657186, 4728.621710263171, 9217.769555761026, 9348.57931393089, 9364.110409511086, 9366.157045865768, 9372.34393592442, 5179.7928082877015, 5180.8963820723375, 5190.844274671766, 5192.221166825007, 5204.531592156724, 4244.978775106105, 4252.659787217015, 4702.670813043054, 4767.213409787416, 4777.259524773665, 3280.6555311692405, 3366.3267346530793, 3684.5578530576504, 3699.4647221403357, 3798.3163030654746, 1279.2836011833404, 1297.4096785962365, 1300.8504022037398, 1313.4632479998425, 1321.4383886848243, 822.3873214455269, 908.2779190543533, 912.2291663544295, 920.4293338130402, 930.7905721212672, 5659.207710920475, 5704.0, 5751.338596061507, 5804.970975145182, 5805.01314776489, 894.5124906925741, 900.0909081827401, 907.4509921497531, 927.188709878856, 931.6459745296879, 3701.148735790783, 3758.1168425420005, 3821.121142137324, 3822.4266360046217, 3872.2642697887873, 9705.5147242639, 9959.203263738884, 10018.0, 10103.595856165817, 10104.545295461721, 9943.055409736064, 9968.651097211708, 10035.297529173034, 10041.849372259421, 10068.44623545712, 7381.37258333037, 7663.386929045663, 7818.257265559762, 7837.49056311338, 7869.606519673994, 4185.811638476337, 4658.254679251304, 4796.712197268035, 5304.9124929390155, 5627.7467513961155, 8494.787630309293, 8589.14108589136, 9206.861897071416, 9338.626454941726, 9339.486328252011, 9028.322875784212, 9294.302927280616, 9323.533823308942, 9361.906380936376, 9383.953080234554, 6626.237982632059, 6627.801165965029, 6676.465882729467, 6743.022261771917, 6902.930970690229, 7762.8447431049935, 7919.406044546567, 7929.801754956172, 7945.245201705829, 7975.521468711896, 6232.781852635136, 6941.895871562032, 7040.542364746275, 7082.716691332289, 7104.786856394317, 6964.442844572523, 7068.187158476841, 7082.291770822876, 7097.7160913562875, 7135.714571417086, 6447.419732223935, 6604.240512341143, 6691.330866913269, 6741.0, 6774.153230846145, 4851.781669824937, 4860.78126484302, 4863.562279394871, 4896.608271338356, 4912.533309335608, 4847.975760121177, 4879.804807807648, 4899.387576552931, 4986.875328116681, 4999.77501012448], "value": 4396.8363605849745, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.99850000750007, 301.52660323292434, 302.455579956079, 307.9168624471393, 311.19711145245253, 4345.3699213614045, 5262.947410517892, 5270.235815806705, 5386.596005299603, 5414.40441551429, 4719.917271560102, 4909.7545122743895, 4939.035959297391, 5226.660267082637, 5423.457654234585, 3467.8209408801004, 4118.691098167649, 4140.523839758436, 4140.958590414102, 4173.290540608089, 3953.8220780064967, 4118.0, 4144.171165766844, 4146.108586653869, 4154.178156096398, 3731.645493678096, 3801.74542401007, 3812.8856134315874, 3940.6059394060508, 4133.5945778435325, 3840.5391353037676, 4083.4487344208746, 4083.8162282697203, 4104.994276402271, 4113.609207125318, 3108.1918701137643, 3589.766665166782, 3603.963960360389, 3667.936298473466, 3684.0237337105686, 4291.699581029329, 4604.585587297126, 4696.906061878777, 4999.350084488993, 5253.789848406059, 4394.516603173648, 4403.735775853459, 4494.966157783728, 4715.8210447388155, 4766.451858036336, 4853.0, 5364.812231571916, 5401.216823560561, 5504.4770746778995, 5714.657120572779, 3623.927521449556, 3717.70258379329, 3724.7206459515646, 3983.342748446503, 3988.840446382141, 5585.966596179679, 5605.607607467479, 5746.764445644207, 5985.25184351956, 6011.458968692838, 3499.807510586891, 3511.877084302037, 3930.6046353544652, 4082.5305089914445, 4118.094019315746, 3954.683625309968, 4175.770332631734, 4186.895327616834, 4202.789860507023, 4215.557366476476, 4056.4320995060716, 4726.007538416902, 4753.405824271966, 4773.570378665937, 4802.711837289774, 5520.025581581255, 5753.424657534297, 5782.335031471351, 5793.391693872167, 5823.912641310384, 1241.2428418664608, 1270.3838638260436, 1277.9424925878172, 1304.315234501894, 1353.88491978181, 905.9139381758722, 919.544825311475, 925.7130289610243, 929.9628014879464, 930.8743319651895, 4224.429701990253, 4585.592563903977, 4855.484399508491, 5073.568746656576, 5099.24640694021, 5256.579473642099, 5330.653507521999, 5388.164834450667, 5550.611457198038, 5562.715420350683, 4088.8364465421646, 4999.8000479885295, 5025.547700706954, 5045.873853153627, 5105.7191854448365, 2459.2659131172422, 2561.9615705764427, 2563.1541591274836, 2674.9331266718486, 2697.0, 4609.562091601292, 4681.208221340725, 5157.813702848366, 5637.351704553947, 5674.432556744293, 4843.854684359475, 4932.975335123301, 5381.37646159764, 5673.177389278614, 5696.784379559096, 4874.317595536628, 4969.3849498913, 5166.354205724285, 5520.91718624221, 5717.0, 4886.731229782254, 5088.720120393414, 5170.621514103555, 5532.91700624499, 5572.0, 5125.948740512547, 5285.735713214401, 5415.106507426231, 5474.507294343528, 5484.972575137258, 4692.718436893797, 5098.2862399263395, 5321.467853214647, 5640.774369025193, 5731.484166425042, 3613.608760627125, 3930.803459827054, 3961.801909904551, 3978.801059946933, 3985.940210896781, 4992.425871024879, 5231.895362092813, 5897.970510147422, 5925.792597259076, 5940.138679891478, 5160.793568257228, 5394.730263486888, 5424.83725488236, 5726.484616384545, 5767.069155871837, 4882.80468781259, 5110.054639891632, 5186.299849520266, 5463.4536546346635, 5510.311211098613, 4449.51055383914, 4978.726170060681, 5364.973175134255, 5629.8592535186945, 5718.428157184415, 5001.874578219871, 5287.365516137934, 5422.295101636762, 5616.8876622466505, 5782.5254560086905, 5301.5493683037375, 5604.635698679573, 5611.270534830609, 5631.549476041989, 5697.547125482975], "value": 4033.625976536414, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [708.4367927497639, 729.8723472235399, 743.4572761883821, 786.5791801386258, 792.1721800718249, 14011.509597164102, 14784.6303842404, 14791.186484743335, 14828.961972661891, 14847.925760371196, 14401.63995900103, 14863.7027259455, 14902.925485372558, 15347.616309592266, 15368.923155384207, 645.961242325462, 671.4661843834139, 793.5873345860149, 799.8720204767238, 820.5692011693849, 789.6209819286746, 802.3741481644327, 820.0405525535108, 820.1880138662741, 823.2467292427422, 867.8741582470537, 871.3116637856118, 872.6073267029831, 873.0004145253681, 874.4578361415931, 838.1660248053165, 862.3144600042949, 868.3660927522881, 868.7654333329997, 868.9261412779921, 618.4619381138393, 634.2895956528718, 639.66097968077, 640.324457697134, 641.7368878759686, 14621.26893655313, 14791.112533248039, 14824.851751482454, 14971.176585287805, 15072.698546029018, 10064.597416103346, 11052.723681907935, 13197.340132993408, 13201.40593673287, 13635.250061246628, 9370.765730856714, 11205.103591712725, 11351.943240283746, 11800.763984720257, 11827.0, 3533.9823300883586, 3631.1829838286176, 3672.687821535146, 3779.998300450383, 3805.4482100095606, 78413.0, 92393.15213695755, 95785.52107239488, 96691.0, 96882.54676179863, 10256.846147307797, 10404.531796069195, 10418.322809017389, 10432.426216558162, 10489.947550262199, 18623.255069797327, 18743.531411714815, 20239.190432382835, 20404.48988775263, 20554.58890822175, 7366.337029667356, 7493.0, 7513.436492263102, 7578.355839753573, 7672.61636918163, 4194.8112334945, 4264.850730224427, 4289.935650965175, 4293.947982744247, 4300.827966881289, 2983.254186453387, 3436.9312613748084, 3453.3438646657055, 3466.2201004773724, 3636.1091532574687, 654.9869002619921, 657.6251536624078, 694.7915625312386, 697.6442014572503, 718.4755128756037, 574.7241324164341, 583.7314835175866, 584.5557376393971, 625.967154195581, 663.6582160187507, 8846.292296616255, 9083.50040747752, 9163.58763855628, 9366.718998430058, 9417.246620270362, 581.9330776960619, 599.2000679093459, 641.4195153386192, 649.6914955779341, 685.2017399729259, 4248.532661407243, 4463.107378524308, 4471.0, 4554.726716397026, 4605.171069207508, 66999.0, 88128.55935720408, 98453.50773246231, 98596.0, 105980.0, 33823.661763382544, 80901.0, 85131.57434212789, 86953.0, 88164.0, 71878.64060679768, 73875.52248954991, 74982.25017749752, 81613.59193203996, 84254.0, 9270.202283315763, 9772.267079969173, 9826.459544725109, 9828.31201815866, 9844.212463003087, 47491.762541187076, 50224.0, 50689.239661405845, 51581.226281606556, 53238.46761532335, 69744.0, 70468.0, 74197.6290118546, 74246.0, 78635.0, 11795.587154449553, 12166.330851803235, 12283.201591896499, 12290.446929888356, 12315.167511472233, 13669.38487768033, 13933.372998214902, 13954.0, 14070.78893816614, 14218.573442796733, 11240.15698822608, 12751.553695620609, 12783.360831958551, 12937.935310323388, 12945.611631651065, 12822.487100516248, 12957.287349195885, 13009.934950325187, 13102.803457948328, 13249.205047697169, 12663.683407914506, 12678.115087453974, 12721.745565088831, 12759.681007974876, 12903.290319032543, 10936.945315273691, 11092.500837462496, 11121.110311175249, 11404.543818247177, 11581.305121692725, 9936.20510359184, 10479.371237725762, 11093.445327733742, 11170.385628790495, 11254.60608878718], "value": 7176.8618753121245, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1541.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 119.0, 118.0, 121.0, 120.0, 122.0, 124.0, 121.0, 121.0, 120.0], "value": 119.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 113.0, 119.0, 145.0, 116.0, 114.0, 129.0, 142.0, 147.0, 141.0], "value": 131.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 199.0, 195.0, 200.0, 271.0, 247.0, 195.0, 200.0, 197.0, 194.0], "value": 209.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 59.0, 60.0, 87.0, 61.0, 62.0, 60.0, 60.0, 76.0, 62.0], "value": 67.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 72.0, 92.0, 73.0, 70.0, 71.0, 85.0, 67.0, 70.0, 87.0], "value": 77.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 81.0, 124.0, 89.0, 94.0, 90.0, 93.0, 93.0, 93.0, 90.0], "value": 94.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 97.0, 98.0, 98.0, 103.0, 98.0, 98.0, 99.0, 102.0, 143.0], "value": 107.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 118.0, 108.0, 91.0, 91.0, 91.0, 99.0, 75.0, 132.0], "value": 98.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 57.0, 67.0, 76.0, 83.0, 87.0, 87.0, 82.0, 59.0], "value": 71.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 72.0, 73.0, 84.0, 77.0, 70.0, 71.0, 70.0, 72.0, 72.0], "value": 73.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 92.0, 85.0, 84.0, 87.0, 88.0, 86.0, 84.0, 85.0, 85.0], "value": 86.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 164.0, 179.0, 134.0, 157.0, 140.0, 164.0, 146.0, 187.0, 140.0], "value": 155.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 170.0, 170.0, 197.0, 222.0, 186.0, 170.0, 167.0, 169.0, 172.0], "value": 179.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 70.0, 68.0, 71.0, 70.0, 67.0, 83.0, 74.0, 68.0], "value": 71.1, "unit": "score"}]}]} 03:37:51 INFO - Return code: 0 03:37:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:37:51 ERROR - # TBPL SUCCESS # 03:37:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:37:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:37:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:37:51 INFO - 2016-12-18 03:37:51.991 screenresolution[1699:19224] starting screenresolution argv=screenresolution get 03:37:51 INFO - 2016-12-18 03:37:51.994 screenresolution[1699:19224] Display 0: 1600x1200x32@60 03:37:52 INFO - 2016-12-18 03:37:52.000 screenresolution[1700:19227] starting screenresolution argv=screenresolution list 03:37:52 INFO - Available Modes on Display 0 03:37:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:37:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:37:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:37:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:37:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:37:52 INFO - Intel Iris: 03:37:52 INFO - Chipset Model: Intel Iris 03:37:52 INFO - Type: GPU 03:37:52 INFO - Bus: Built-In 03:37:52 INFO - VRAM (Dynamic, Max): 1536 MB 03:37:52 INFO - Vendor: Intel (0x8086) 03:37:52 INFO - Device ID: 0x0a2e 03:37:52 INFO - Revision ID: 0x0009 03:37:52 INFO - Displays: 03:37:52 INFO - Display: 03:37:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:37:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:37:52 INFO - Display Serial Number: 1600x1200 60 03:37:52 INFO - Main Display: Yes 03:37:52 INFO - Mirror: Off 03:37:52 INFO - Online: Yes 03:37:52 INFO - Rotation: Supported 03:37:52 INFO - Return code: 0 03:37:52 INFO - Running post-action listener: _resource_record_post_action 03:37:52 INFO - [mozharness: 2016-12-18 11:37:52.244810Z] Finished run-tests step (success) 03:37:52 INFO - Running post-run listener: _resource_record_post_run 03:37:52 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 33550336; Write bytes: 663992320; Read time: 200; Write time: 1902 03:37:52 INFO - TinderboxPrint: CPU usage
54.7% 03:37:52 INFO - TinderboxPrint: I/O read bytes / time
33,550,336 / 200 03:37:52 INFO - TinderboxPrint: I/O write bytes / time
663,992,320 / 1,902 03:37:52 INFO - TinderboxPrint: CPU idle
2,194.3 (45.3%) 03:37:52 INFO - TinderboxPrint: CPU system
986.5 (20.4%) 03:37:52 INFO - TinderboxPrint: CPU user
1,664.8 (34.4%) 03:37:52 INFO - TinderboxPrint: Swap in / out
378,105,856 / 0 03:37:52 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 345770496; Write bytes: 346007552; Read time: 27823; Write time: 692 03:37:52 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 31465472; Write bytes: 310837248; Read time: 180; Write time: 1181 03:37:52 INFO - Running post-run listener: _upload_blobber_files 03:37:52 INFO - Blob upload gear active. 03:37:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:37:52 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 03:37:52 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'] 03:37:52 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 03:37:53 INFO - (blobuploader) - INFO - Open directory for files ... 03:37:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:37:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:37:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:37:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:37:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:37:53 INFO - (blobuploader) - INFO - Done attempting. 03:37:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:37:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:37:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:37:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:37:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:37:54 INFO - (blobuploader) - INFO - Done attempting. 03:37:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:37:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:37:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:37:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:37:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:37:56 INFO - (blobuploader) - INFO - Done attempting. 03:37:56 INFO - (blobuploader) - INFO - Iteration through files over. 03:37:56 INFO - Return code: 0 03:37:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:37:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:37:56 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4b8692af976af7b00d178ece771b04e4665181b1521ed6579f85894ae9d628c58968289495fcf29315ec76ace87da64790832252712653fe537af7dae5f8bf06", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c9419b541dc052dd72c58caf062c84466ee7eb49b1c21e7996f497c3be9e4a8422b367f5be89733fcd42800bf1dcc525aee46e0ed90be867e9c2db596cde3652", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1f8cf7205d561efa1f055c86809db601cf2f51216cad545a505b49ef4d244202242756571848d4a05f03f1ebc4ca364c8b0c2c08bb6fe1bb5df2fc3f27df2a6c"} 03:37:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:37:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:37:56 INFO - Contents: 03:37:56 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4b8692af976af7b00d178ece771b04e4665181b1521ed6579f85894ae9d628c58968289495fcf29315ec76ace87da64790832252712653fe537af7dae5f8bf06", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c9419b541dc052dd72c58caf062c84466ee7eb49b1c21e7996f497c3be9e4a8422b367f5be89733fcd42800bf1dcc525aee46e0ed90be867e9c2db596cde3652", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1f8cf7205d561efa1f055c86809db601cf2f51216cad545a505b49ef4d244202242756571848d4a05f03f1ebc4ca364c8b0c2c08bb6fe1bb5df2fc3f27df2a6c"} 03:37:56 INFO - Running post-run listener: copy_logs_to_upload_dir 03:37:56 INFO - Copying logs to upload dir... 03:37:56 INFO - mkdir: /builds/slave/test/build/upload/logs 03:37:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.158928 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-12-18 03:37:56.964146) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-18 03:37:56.967245) ========= 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.kgoaSsVP8a/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.HsDhBySUJ9/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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/4b8692af976af7b00d178ece771b04e4665181b1521ed6579f85894ae9d628c58968289495fcf29315ec76ace87da64790832252712653fe537af7dae5f8bf06", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c9419b541dc052dd72c58caf062c84466ee7eb49b1c21e7996f497c3be9e4a8422b367f5be89733fcd42800bf1dcc525aee46e0ed90be867e9c2db596cde3652", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1f8cf7205d561efa1f055c86809db601cf2f51216cad545a505b49ef4d244202242756571848d4a05f03f1ebc4ca364c8b0c2c08bb6fe1bb5df2fc3f27df2a6c"} build_url:https://queue.taskcluster.net/v1/task/Yoy68BNfRWWS2uRXTvaZsg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011194 build_url: 'https://queue.taskcluster.net/v1/task/Yoy68BNfRWWS2uRXTvaZsg/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/4b8692af976af7b00d178ece771b04e4665181b1521ed6579f85894ae9d628c58968289495fcf29315ec76ace87da64790832252712653fe537af7dae5f8bf06", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c9419b541dc052dd72c58caf062c84466ee7eb49b1c21e7996f497c3be9e4a8422b367f5be89733fcd42800bf1dcc525aee46e0ed90be867e9c2db596cde3652", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1f8cf7205d561efa1f055c86809db601cf2f51216cad545a505b49ef4d244202242756571848d4a05f03f1ebc4ca364c8b0c2c08bb6fe1bb5df2fc3f27df2a6c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-18 03:37:57.000417) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 03:37:57.000715) ========= 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.kgoaSsVP8a/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.HsDhBySUJ9/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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.005276 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-18 03:37:57.051318) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-18 03:37:57.051595) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-18 03:37:57.054885) ========= ========= Total master_lag: 0.22 =========