builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0179 starttime: 1479152681.76 results: success (0) buildid: 20161114081542 builduid: c0b4dd1356ae41cf97e49288305ed2d3 revision: f11ca24b2382ed6c8c7970a82fff53f7f3e0aa6d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 11:44:41.763250) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 11:44:41.763688) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 11:44:41.763964) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 11:44:41.790227) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 11:44:41.790606) ========= 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.X6VVUHoU3h/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.ZGbks5BoIX/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-14 11:44:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 20.8M=0.001s 2016-11-14 11:44:41 (20.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.096646 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 11:44:41.910094) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 11:44:41.910426) ========= 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.X6VVUHoU3h/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.ZGbks5BoIX/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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.027486 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 11:44:41.959451) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-14 11:44:41.959820) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f11ca24b2382ed6c8c7970a82fff53f7f3e0aa6d --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 f11ca24b2382ed6c8c7970a82fff53f7f3e0aa6d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.X6VVUHoU3h/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.ZGbks5BoIX/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-14 11:44:42,044 truncating revision to first 12 chars 2016-11-14 11:44:42,044 Setting DEBUG logging. 2016-11-14 11:44:42,044 attempt 1/10 2016-11-14 11:44:42,044 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/f11ca24b2382?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-14 11:44:43,035 unpacking tar archive at: mozilla-aurora-f11ca24b2382/testing/mozharness/ program finished with exit code 0 elapsedTime=1.241481 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-14 11:44:43.278993) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 11:44:43.279409) ========= script_repo_revision: f11ca24b2382ed6c8c7970a82fff53f7f3e0aa6d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 11:44:43.279915) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 11:44:43.280257) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 11:44:43.299219) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-14 11:44:43.299621) ========= /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.X6VVUHoU3h/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.ZGbks5BoIX/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 11:44:43 INFO - MultiFileLogger online at 20161114 11:44:43 in /builds/slave/test 11:44:43 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 11:44:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:44:43 INFO - {'append_to_log': False, 11:44:43 INFO - 'base_work_dir': '/builds/slave/test', 11:44:43 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 11:44:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:44:43 INFO - 'branch': 'Mozilla-Aurora', 11:44:43 INFO - 'buildbot_json_path': 'buildprops.json', 11:44:43 INFO - 'config_files': ('talos/mac_config.py',), 11:44:43 INFO - 'default_actions': ('clobber', 11:44:43 INFO - 'read-buildbot-config', 11:44:43 INFO - 'download-and-extract', 11:44:43 INFO - 'populate-webroot', 11:44:43 INFO - 'create-virtualenv', 11:44:43 INFO - 'install', 11:44:43 INFO - 'run-tests'), 11:44:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:44:43 INFO - 'download_minidump_stackwalk': True, 11:44:43 INFO - 'download_symbols': 'ondemand', 11:44:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:44:43 INFO - 'tooltool.py': '/tools/tooltool.py', 11:44:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:44:43 INFO - '/tools/misc-python/virtualenv.py')}, 11:44:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:44:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:44:43 INFO - 'installer_path': 'installer.exe', 11:44:43 INFO - 'log_level': 'info', 11:44:43 INFO - 'log_name': 'talos', 11:44:43 INFO - 'log_to_console': True, 11:44:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:44:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:44:43 INFO - 'opt_config_files': (), 11:44:43 INFO - 'pip_index': False, 11:44:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:44:43 INFO - 'cmd': ('bash', 11:44:43 INFO - '-c', 11:44:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:44:43 INFO - 'enabled': True, 11:44:43 INFO - 'halt_on_failure': False, 11:44:43 INFO - 'name': 'check_screen_resolution'},), 11:44:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:44:43 INFO - 'cmd': ('bash', 11:44:43 INFO - '-c', 11:44:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:44:43 INFO - 'enabled': True, 11:44:43 INFO - 'halt_on_failure': False, 11:44:43 INFO - 'name': 'check_screen_resolution'},), 11:44:43 INFO - 'run_cmd_checks_enabled': True, 11:44:43 INFO - 'sps_profile': False, 11:44:43 INFO - 'sps_profile_interval': 0, 11:44:43 INFO - 'suite': 'dromaeojs-e10s', 11:44:43 INFO - 'system_bits': '32', 11:44:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:44:43 INFO - 'title': 't-yosemite-r7-0179', 11:44:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:44:43 INFO - 'use_talos_json': True, 11:44:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:44:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:44:43 INFO - 'work_dir': 'build'} 11:44:43 INFO - [mozharness: 2016-11-14 19:44:43.512974Z] Running clobber step. 11:44:43 INFO - Running pre-action listener: _resource_record_pre_action 11:44:43 INFO - Running main action method: clobber 11:44:43 INFO - rmtree: /builds/slave/test/build 11:44:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:44:46 INFO - Running post-action listener: _resource_record_post_action 11:44:46 INFO - [mozharness: 2016-11-14 19:44:46.126237Z] Finished clobber step (success) 11:44:46 INFO - [mozharness: 2016-11-14 19:44:46.126561Z] Running read-buildbot-config step. 11:44:46 INFO - Running pre-action listener: _resource_record_pre_action 11:44:46 INFO - Running main action method: read_buildbot_config 11:44:46 INFO - Using buildbot properties: 11:44:46 INFO - { 11:44:46 INFO - "project": "", 11:44:46 INFO - "product": "firefox", 11:44:46 INFO - "script_repo_revision": "production", 11:44:46 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 11:44:46 INFO - "repository": "", 11:44:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 11:44:46 INFO - "buildid": "20161114081542", 11:44:46 INFO - "pgo_build": "False", 11:44:46 INFO - "basedir": "/builds/slave/test", 11:44:46 INFO - "buildnumber": 37, 11:44:46 INFO - "slavename": "t-yosemite-r7-0179", 11:44:46 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 11:44:46 INFO - "platform": "yosemite_r7", 11:44:46 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 11:44:46 INFO - "branch": "mozilla-aurora", 11:44:46 INFO - "repo_path": "releases/mozilla-aurora", 11:44:46 INFO - "revision": "f11ca24b2382ed6c8c7970a82fff53f7f3e0aa6d", 11:44:46 INFO - "stage_platform": "macosx64", 11:44:46 INFO - "builduid": "c0b4dd1356ae41cf97e49288305ed2d3", 11:44:46 INFO - "slavebuilddir": "test" 11:44:46 INFO - } 11:44:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 11:44:46 INFO - Running post-action listener: _resource_record_post_action 11:44:46 INFO - [mozharness: 2016-11-14 19:44:46.132228Z] Finished read-buildbot-config step (success) 11:44:46 INFO - [mozharness: 2016-11-14 19:44:46.132420Z] Running download-and-extract step. 11:44:46 INFO - Running pre-action listener: _resource_record_pre_action 11:44:46 INFO - Running main action method: download_and_extract 11:44:46 INFO - mkdir: /builds/slave/test/build/tests 11:44:46 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')]} 11:44:46 INFO - trying https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 11:44:46 INFO - Downloading https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/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 11:44:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/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 11:44:47 INFO - Downloaded 1199 bytes. 11:44:47 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 11:44:47 INFO - Using the following test package requirements: 11:44:47 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 11:44:47 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:44:47 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 11:44:47 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:44:47 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 11:44:47 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:44:47 INFO - u'jsshell-mac.zip'], 11:44:47 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:44:47 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 11:44:47 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 11:44:47 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:44:47 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 11:44:47 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:44:47 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 11:44:47 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:44:47 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 11:44:47 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:44:47 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 11:44:47 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 11:44:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 11:44:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:44:47 INFO - Fetch https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 11:44:48 INFO - Http code: 200 11:44:48 INFO - via: 1.1 4c2196e36fd33f377384d605b4424e95.cloudfront.net (CloudFront) 11:44:48 INFO - x-amz-cf-id: 6cs-Cyw-He5vHOMTgiDe7zGEPuWkCoEzeDPrDNjTlz210vmdlzYFAw== 11:44:48 INFO - x-amz-version-id: beIhMZs6B_1lhCUU5tXTJZFEtB99JGu9 11:44:48 INFO - Expected file size: 16327504 11:44:48 INFO - Obtained file size: 16327504 11:44: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 11:44:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 11:44:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:44:55 INFO - Fetch https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 11:44:55 INFO - Http code: 200 11:44:55 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 11:44:55 INFO - x-amz-cf-id: zdWoUnnN2STDRXj7Te0pmALJEzcWWdHMPtdncGvyH12nFEGyfCM0qQ== 11:44:55 INFO - x-amz-version-id: beIhMZs6B_1lhCUU5tXTJZFEtB99JGu9 11:44:56 INFO - Expected file size: 16327504 11:44:56 INFO - Obtained file size: 16327504 11:45:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 11:45:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 11:45:02 INFO - Fetch https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 11:45:02 INFO - Http code: 200 11:45:02 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 11:45:02 INFO - x-amz-cf-id: 4l7EgKfuDHGL025IZevqIokymqVM8QXOXhI5VdWDA-TGYpRyDzD6aw== 11:45:02 INFO - x-amz-version-id: fL.n5SYgNX8ZLi3RUYlMNYmEt036hZfV 11:45:03 INFO - Expected file size: 14409038 11:45:03 INFO - Obtained file size: 14409038 11:45:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:45:03 INFO - trying https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 11:45:03 INFO - Downloading https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 11:45:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/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 11:45:06 INFO - Downloaded 114359577 bytes. 11:45:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 11:45:06 INFO - mkdir: /builds/slave/test/properties 11:45:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:45:06 INFO - Writing to file /builds/slave/test/properties/build_url 11:45:06 INFO - Contents: 11:45:06 INFO - build_url:https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 11:45:07 INFO - Running post-action listener: _resource_record_post_action 11:45:07 INFO - Running post-action listener: set_extra_try_arguments 11:45:07 INFO - [mozharness: 2016-11-14 19:45:07.503418Z] Finished download-and-extract step (success) 11:45:07 INFO - [mozharness: 2016-11-14 19:45:07.503557Z] Running populate-webroot step. 11:45:07 INFO - Running pre-action listener: _resource_record_pre_action 11:45:07 INFO - Running main action method: populate_webroot 11:45:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:45:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:45:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:45:07 INFO - '--tppagecycles', 11:45:07 INFO - '7'], 11:45:07 INFO - 'tests': ['tsvgm']}}, 11:45:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:45:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:45:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:45:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:45:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:45:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:45:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:45:07 INFO - 'talos_options': ['--disable-e10s'], 11:45:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:45:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:45:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:45:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:45:07 INFO - 'talos_options': ['--disable-e10s'], 11:45:07 INFO - 'tests': ['damp', 'tps']}, 11:45:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:45:07 INFO - 'tests': ['damp', 'tps']}, 11:45:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:45:07 INFO - 'tests': ['dromaeo_dom']}, 11:45:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:45:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:45:07 INFO - 'tests': ['basic_compositor_video']}, 11:45:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:45:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:45:07 INFO - 'tests': ['a11yr', 11:45:07 INFO - 'ts_paint', 11:45:07 INFO - 'tpaint', 11:45:07 INFO - 'sessionrestore', 11:45:07 INFO - 'sessionrestore_no_auto_restore', 11:45:07 INFO - 'tabpaint']}, 11:45:07 INFO - 'other-e10s': {'tests': ['a11yr', 11:45:07 INFO - 'ts_paint', 11:45:07 INFO - 'tpaint', 11:45:07 INFO - 'sessionrestore', 11:45:07 INFO - 'sessionrestore_no_auto_restore', 11:45:07 INFO - 'tabpaint']}, 11:45:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:45:07 INFO - 'ts_paint', 11:45:07 INFO - 'tpaint', 11:45:07 INFO - 'sessionrestore', 11:45:07 INFO - 'sessionrestore_no_auto_restore', 11:45:07 INFO - 'tabpaint']}, 11:45:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:45:07 INFO - 'ts_paint', 11:45:07 INFO - 'tpaint', 11:45:07 INFO - 'sessionrestore', 11:45:07 INFO - 'sessionrestore_no_auto_restore', 11:45:07 INFO - 'tabpaint']}, 11:45:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:45:07 INFO - 'tests': ['a11yr', 11:45:07 INFO - 'ts_paint', 11:45:07 INFO - 'tpaint', 11:45:07 INFO - 'sessionrestore', 11:45:07 INFO - 'sessionrestore_no_auto_restore', 11:45:07 INFO - 'tabpaint']}, 11:45:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:45:07 INFO - 'tests': ['a11yr', 11:45:07 INFO - 'ts_paint', 11:45:07 INFO - 'tpaint', 11:45:07 INFO - 'sessionrestore', 11:45:07 INFO - 'sessionrestore_no_auto_restore', 11:45:07 INFO - 'tabpaint']}, 11:45:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:45:07 INFO - 'tests': ['tsvgx', 11:45:07 INFO - 'tsvgr_opacity', 11:45:07 INFO - 'tart', 11:45:07 INFO - 'tscrollx', 11:45:07 INFO - 'cart']}, 11:45:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:45:07 INFO - 'tsvgr_opacity', 11:45:07 INFO - 'tart', 11:45:07 INFO - 'tscrollx', 11:45:07 INFO - 'cart']}, 11:45:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:45:07 INFO - 'talos_options': ['--disable-e10s'], 11:45:07 INFO - 'tests': ['tp5o']}, 11:45:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:45:07 INFO - 'tests': ['tp5o']}, 11:45:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:45:07 INFO - 'talos_options': ['--disable-e10s', 11:45:07 INFO - '--xperf_path', 11:45:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:45:07 INFO - 'tests': ['tp5n']}, 11:45:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:45:07 INFO - 'talos_options': ['--xperf_path', 11:45:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:45:07 INFO - 'tests': ['tp5n']}}, 11:45:07 INFO - 'talos.zip': {'path': '', 11:45:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:45:07 INFO - Running post-action listener: _resource_record_post_action 11:45:07 INFO - [mozharness: 2016-11-14 19:45:07.522619Z] Finished populate-webroot step (success) 11:45:07 INFO - [mozharness: 2016-11-14 19:45:07.522750Z] Running create-virtualenv step. 11:45:07 INFO - Running pre-action listener: _resource_record_pre_action 11:45:07 INFO - Running main action method: create_virtualenv 11:45:07 INFO - Creating virtualenv /builds/slave/test/build/venv 11:45:07 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 11:45:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:45:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:45:07 INFO - Using real prefix '/tools/python27' 11:45:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:45:08 INFO - Installing distribute.............................................................................................................................................................................................done. 11:45:11 INFO - Installing pip.................done. 11:45:11 INFO - Return code: 0 11:45:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:45:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:45:11 INFO - Reading from file tmpfile_stdout 11:45:11 INFO - Output received: 11:45:11 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) 11:45:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:45:11 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')]} 11:45:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:45:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:45:11 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')]} 11:45:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:45:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:45:11 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 0x105f71490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ee7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe40c9a1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d85d30>, '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 0x105bebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d7db20>, '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.X6VVUHoU3h/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.ZGbks5BoIX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 11:45:11 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 11:45:11 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 11:45:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X6VVUHoU3h/Render', 11:45:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:45:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:45:11 INFO - 'HOME': '/Users/cltbld', 11:45:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:45:11 INFO - 'LOGNAME': 'cltbld', 11:45:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:45:11 INFO - 'MOZ_NO_REMOTE': '1', 11:45:11 INFO - 'NO_EM_RESTART': '1', 11:45:11 INFO - 'PAGER': '/bin/cat', 11:45:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:45:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:45:11 INFO - 'PWD': '/builds/slave/test', 11:45:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:45:11 INFO - 'SHELL': '/bin/bash', 11:45:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZGbks5BoIX/Listeners', 11:45:11 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 11:45:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:45:11 INFO - 'USER': 'cltbld', 11:45:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:45:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:45:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:45:11 INFO - 'XPC_FLAGS': '0x0', 11:45:11 INFO - 'XPC_SERVICE_NAME': '0', 11:45:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:45:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:45:11 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 11:45:11 INFO - Cleaning up... 11:45:11 INFO - Return code: 0 11:45:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:45:11 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')]} 11:45:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:45:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:45:11 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')]} 11:45:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:45:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:45:11 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 0x105f71490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ee7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe40c9a1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d85d30>, '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 0x105bebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d7db20>, '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.X6VVUHoU3h/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.ZGbks5BoIX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 11:45:11 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 11:45:11 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 11:45:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X6VVUHoU3h/Render', 11:45:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:45:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:45:11 INFO - 'HOME': '/Users/cltbld', 11:45:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:45:11 INFO - 'LOGNAME': 'cltbld', 11:45:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:45:11 INFO - 'MOZ_NO_REMOTE': '1', 11:45:11 INFO - 'NO_EM_RESTART': '1', 11:45:11 INFO - 'PAGER': '/bin/cat', 11:45:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:45:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:45:11 INFO - 'PWD': '/builds/slave/test', 11:45:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:45:11 INFO - 'SHELL': '/bin/bash', 11:45:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZGbks5BoIX/Listeners', 11:45:11 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 11:45:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:45:11 INFO - 'USER': 'cltbld', 11:45:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:45:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:45:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:45:11 INFO - 'XPC_FLAGS': '0x0', 11:45:11 INFO - 'XPC_SERVICE_NAME': '0', 11:45:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:45:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:45:12 INFO - Downloading/unpacking psutil>=3.1.1 11:45: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 11:45: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 11:45: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 11:45: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 11:45: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 11:45: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 11:45:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:45:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:45:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:45:15 INFO - Installing collected packages: psutil 11:45:15 INFO - Running setup.py install for psutil 11:45:15 INFO - building 'psutil._psutil_osx' extension 11:45:15 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 11:45: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_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 11:45: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/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 11:45:16 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 11:45:16 INFO - building 'psutil._psutil_posix' extension 11:45:16 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 11:45:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:45:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:45:16 INFO - ^ 11:45:16 INFO - 1 warning generated. 11:45:16 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 11:45:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:45:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:45:16 INFO - Successfully installed psutil 11:45:16 INFO - Cleaning up... 11:45:16 INFO - Return code: 0 11:45:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:45:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:45:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:45:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:45:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:45:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:45:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:45:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105f71490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ee7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe40c9a1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d85d30>, '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 0x105bebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d7db20>, '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.X6VVUHoU3h/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.ZGbks5BoIX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 11:45:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:45:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:45:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X6VVUHoU3h/Render', 11:45:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:45:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:45:16 INFO - 'HOME': '/Users/cltbld', 11:45:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:45:16 INFO - 'LOGNAME': 'cltbld', 11:45:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:45:16 INFO - 'MOZ_NO_REMOTE': '1', 11:45:16 INFO - 'NO_EM_RESTART': '1', 11:45:16 INFO - 'PAGER': '/bin/cat', 11:45:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:45:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:45:16 INFO - 'PWD': '/builds/slave/test', 11:45:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:45:16 INFO - 'SHELL': '/bin/bash', 11:45:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZGbks5BoIX/Listeners', 11:45:16 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 11:45:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:45:16 INFO - 'USER': 'cltbld', 11:45:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:45:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:45:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:45:16 INFO - 'XPC_FLAGS': '0x0', 11:45:16 INFO - 'XPC_SERVICE_NAME': '0', 11:45:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:45:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:45:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:45:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:45:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:45:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:45:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:45:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:45:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:45:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:45:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:45:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:45:19 INFO - Installing collected packages: mozsystemmonitor 11:45:19 INFO - Running setup.py install for mozsystemmonitor 11:45:19 INFO - Successfully installed mozsystemmonitor 11:45:19 INFO - Cleaning up... 11:45:19 INFO - Return code: 0 11:45:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:45:19 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')]} 11:45:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:45:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:45:19 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')]} 11:45:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:45:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:45:19 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 0x105f71490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ee7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe40c9a1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d85d30>, '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 0x105bebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d7db20>, '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.X6VVUHoU3h/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.ZGbks5BoIX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 11:45:19 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 11:45:19 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 11:45:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X6VVUHoU3h/Render', 11:45:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:45:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:45:19 INFO - 'HOME': '/Users/cltbld', 11:45:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:45:19 INFO - 'LOGNAME': 'cltbld', 11:45:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:45:19 INFO - 'MOZ_NO_REMOTE': '1', 11:45:19 INFO - 'NO_EM_RESTART': '1', 11:45:19 INFO - 'PAGER': '/bin/cat', 11:45:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:45:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:45:19 INFO - 'PWD': '/builds/slave/test', 11:45:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:45:19 INFO - 'SHELL': '/bin/bash', 11:45:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZGbks5BoIX/Listeners', 11:45:19 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 11:45:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:45:19 INFO - 'USER': 'cltbld', 11:45:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:45:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:45:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:45:19 INFO - 'XPC_FLAGS': '0x0', 11:45:19 INFO - 'XPC_SERVICE_NAME': '0', 11:45:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:45:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:45:20 INFO - Downloading/unpacking jsonschema==2.5.1 11:45:20 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 11:45:20 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 11:45:20 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 11:45:20 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 11:45:20 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 11:45:20 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 11:45:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:45:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:45:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:45:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:45:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:45:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:45:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:45:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:45:23 INFO - Downloading functools32-3.2.3-2.tar.gz 11:45:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:45:23 INFO - warning: no files found matching '*.txt' 11:45:23 INFO - no previously-included directories found matching 'build' 11:45:23 INFO - no previously-included directories found matching 'dist' 11:45:23 INFO - no previously-included directories found matching '.git*' 11:45:23 INFO - Installing collected packages: jsonschema, functools32 11:45:23 INFO - Running setup.py install for functools32 11:45:23 INFO - warning: no files found matching '*.txt' 11:45:23 INFO - no previously-included directories found matching 'build' 11:45:23 INFO - no previously-included directories found matching 'dist' 11:45:23 INFO - no previously-included directories found matching '.git*' 11:45:23 INFO - Successfully installed jsonschema functools32 11:45:23 INFO - Cleaning up... 11:45:23 INFO - Return code: 0 11:45:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:45:23 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')]} 11:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:45:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:45:23 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')]} 11:45:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:45:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:45:23 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 0x105f71490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ee7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe40c9a1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d85d30>, '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 0x105bebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d7db20>, '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.X6VVUHoU3h/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.ZGbks5BoIX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 11:45:23 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 11:45:23 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 11:45:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X6VVUHoU3h/Render', 11:45:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:45:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:45:23 INFO - 'HOME': '/Users/cltbld', 11:45:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:45:23 INFO - 'LOGNAME': 'cltbld', 11:45:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:45:23 INFO - 'MOZ_NO_REMOTE': '1', 11:45:23 INFO - 'NO_EM_RESTART': '1', 11:45:23 INFO - 'PAGER': '/bin/cat', 11:45:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:45:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:45:23 INFO - 'PWD': '/builds/slave/test', 11:45:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:45:23 INFO - 'SHELL': '/bin/bash', 11:45:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZGbks5BoIX/Listeners', 11:45:23 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 11:45:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:45:23 INFO - 'USER': 'cltbld', 11:45:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:45:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:45:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:45:23 INFO - 'XPC_FLAGS': '0x0', 11:45:23 INFO - 'XPC_SERVICE_NAME': '0', 11:45:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:45:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:45:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:45:23 INFO - Cleaning up... 11:45:23 INFO - Return code: 0 11:45:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:45:23 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')]} 11:45:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:45:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:45:23 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')]} 11:45:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:45:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:45:23 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 0x105f71490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ee7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe40c9a1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d85d30>, '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 0x105bebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d7db20>, '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.X6VVUHoU3h/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.ZGbks5BoIX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 11:45:23 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 11:45:23 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 11:45:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X6VVUHoU3h/Render', 11:45:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:45:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:45:23 INFO - 'HOME': '/Users/cltbld', 11:45:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:45:23 INFO - 'LOGNAME': 'cltbld', 11:45:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:45:23 INFO - 'MOZ_NO_REMOTE': '1', 11:45:23 INFO - 'NO_EM_RESTART': '1', 11:45:23 INFO - 'PAGER': '/bin/cat', 11:45:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:45:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:45:23 INFO - 'PWD': '/builds/slave/test', 11:45:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:45:23 INFO - 'SHELL': '/bin/bash', 11:45:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZGbks5BoIX/Listeners', 11:45:23 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 11:45:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:45:23 INFO - 'USER': 'cltbld', 11:45:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:45:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:45:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:45:23 INFO - 'XPC_FLAGS': '0x0', 11:45:23 INFO - 'XPC_SERVICE_NAME': '0', 11:45:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:45:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:45:23 INFO - Downloading/unpacking blobuploader==1.2.4 11:45: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 11:45: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 11:45: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 11:45: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 11:45: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 11:45: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 11:45:26 INFO - Downloading blobuploader-1.2.4.tar.gz 11:45:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:45:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:45:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:45:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:45:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:45:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:45:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:45:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:45: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 11:45: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 11:45: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 11:45: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 11:45: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 11:45: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 11:45:27 INFO - Downloading docopt-0.6.1.tar.gz 11:45:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:45:27 INFO - Installing collected packages: blobuploader, requests, docopt 11:45:27 INFO - Running setup.py install for blobuploader 11:45:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:45:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:45:28 INFO - Running setup.py install for requests 11:45:28 INFO - Running setup.py install for docopt 11:45:28 INFO - Successfully installed blobuploader requests docopt 11:45:28 INFO - Cleaning up... 11:45:28 INFO - Return code: 0 11:45:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:45:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:45:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:45:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:45:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:45:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:45:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:45:28 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 0x105f71490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ee7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe40c9a1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d85d30>, '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 0x105bebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d7db20>, '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.X6VVUHoU3h/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.ZGbks5BoIX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 11:45:28 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 11:45:28 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 11:45:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X6VVUHoU3h/Render', 11:45:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:45:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:45:28 INFO - 'HOME': '/Users/cltbld', 11:45:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:45:28 INFO - 'LOGNAME': 'cltbld', 11:45:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:45:28 INFO - 'MOZ_NO_REMOTE': '1', 11:45:28 INFO - 'NO_EM_RESTART': '1', 11:45:28 INFO - 'PAGER': '/bin/cat', 11:45:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:45:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:45:28 INFO - 'PWD': '/builds/slave/test', 11:45:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:45:28 INFO - 'SHELL': '/bin/bash', 11:45:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZGbks5BoIX/Listeners', 11:45:28 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 11:45:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:45:28 INFO - 'USER': 'cltbld', 11:45:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:45:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:45:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:45:28 INFO - 'XPC_FLAGS': '0x0', 11:45:28 INFO - 'XPC_SERVICE_NAME': '0', 11:45:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:45:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:45:29 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-Oaaka1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:45:29 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-dnJXGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:45:29 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-WSiZe0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:45:29 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-NdvmbB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:45:29 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-2l4B7T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:45:29 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-qOm0U2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:45:29 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-Nw5KhI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:45:29 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-rqtuJE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:45:29 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-F07bqf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:45:30 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-W6Wk9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:45:30 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-UvU9_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:45:30 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-klJbc8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:45:30 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-k2d_JG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:45:30 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-wz0jrC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:45:30 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-tFsJHU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:45:30 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-5LLfJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:45:30 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-WbMuHs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:45:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:45:30 INFO - Running setup.py install for manifestparser 11:45:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:45:31 INFO - Running setup.py install for mozcrash 11:45:31 INFO - Running setup.py install for mozdebug 11:45:31 INFO - Running setup.py install for mozdevice 11:45:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:45:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:45:31 INFO - Running setup.py install for mozfile 11:45:31 INFO - Running setup.py install for mozhttpd 11:45:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:45:31 INFO - Running setup.py install for mozinfo 11:45:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:45:31 INFO - Running setup.py install for mozInstall 11:45:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:45:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:45:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:45:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:45:32 INFO - Running setup.py install for mozleak 11:45:32 INFO - Running setup.py install for mozlog 11:45:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:45:32 INFO - Running setup.py install for moznetwork 11:45:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:45:32 INFO - Running setup.py install for mozprocess 11:45:32 INFO - Running setup.py install for mozprofile 11:45:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:45:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:45:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:45:32 INFO - Running setup.py install for mozrunner 11:45:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:45:33 INFO - Running setup.py install for mozscreenshot 11:45:33 INFO - Running setup.py install for moztest 11:45:33 INFO - Running setup.py install for mozversion 11:45:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:45:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:45:33 INFO - Cleaning up... 11:45:33 INFO - Return code: 0 11:45:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:45:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:45:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:45:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:45:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:45:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:45:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:45:33 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 0x105f71490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ee7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe40c9a1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d85d30>, '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 0x105bebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d7db20>, '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.X6VVUHoU3h/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.ZGbks5BoIX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 11:45:33 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 11:45:33 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 11:45:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X6VVUHoU3h/Render', 11:45:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:45:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:45:33 INFO - 'HOME': '/Users/cltbld', 11:45:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:45:33 INFO - 'LOGNAME': 'cltbld', 11:45:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:45:33 INFO - 'MOZ_NO_REMOTE': '1', 11:45:33 INFO - 'NO_EM_RESTART': '1', 11:45:33 INFO - 'PAGER': '/bin/cat', 11:45:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:45:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:45:33 INFO - 'PWD': '/builds/slave/test', 11:45:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:45:33 INFO - 'SHELL': '/bin/bash', 11:45:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZGbks5BoIX/Listeners', 11:45:33 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 11:45:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:45:33 INFO - 'USER': 'cltbld', 11:45:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:45:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:45:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:45:33 INFO - 'XPC_FLAGS': '0x0', 11:45:33 INFO - 'XPC_SERVICE_NAME': '0', 11:45:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:45:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:45:33 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-uHLyxS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:45:33 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)) 11:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:45:33 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-1K7M0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:45:33 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)) 11:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:45:33 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-Y3dAlm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:45:34 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)) 11:45:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:45:34 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-f7c6h0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:45:34 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)) 11:45:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:45:34 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-pnufMC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:45:34 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)) 11:45:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:45:34 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-y5zM_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:45:34 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)) 11:45:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:45:34 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-pcbXjT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:45:34 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)) 11:45:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:45:34 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-VYVArS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:45:34 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)) 11:45:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:45:34 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-r5PA3L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:45:34 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)) 11:45:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:45:34 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-VHwW7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:45:34 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)) 11:45:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:45:34 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-nJ2tIm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:45:34 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)) 11:45:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:45:34 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-oDSEeM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:45: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)) 11:45:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:45:35 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-DmIBmI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:45:35 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)) 11:45:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:45:35 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-Xr2SxR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:45:35 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)) 11:45:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:45:35 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-njzQUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:45:35 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)) 11:45:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:45:35 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-BlMgU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:45:35 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)) 11:45:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:45:35 INFO - Running setup.py (path:/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/pip-O8L2iK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:45:35 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)) 11:45:35 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)) 11:45:35 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)) 11:45:35 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)) 11:45:35 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)) 11:45:35 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)) 11:45:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:45:35 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 11:45:35 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 11:45:35 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 11:45:35 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 11:45:35 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 11:45:35 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 11:45:38 INFO - Downloading blessings-1.6.tar.gz 11:45:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:45:38 INFO - Installing collected packages: blessings 11:45:38 INFO - Running setup.py install for blessings 11:45:38 INFO - Successfully installed blessings 11:45:38 INFO - Cleaning up... 11:45:38 INFO - Return code: 0 11:45:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:45:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:45:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:45:39 INFO - Reading from file tmpfile_stdout 11:45:39 INFO - Current package versions: 11:45:39 INFO - blessings == 1.6 11:45:39 INFO - blobuploader == 1.2.4 11:45:39 INFO - docopt == 0.6.1 11:45:39 INFO - functools32 == 3.2.3-2 11:45:39 INFO - jsonschema == 2.5.1 11:45:39 INFO - manifestparser == 1.1 11:45:39 INFO - mozInstall == 1.12 11:45:39 INFO - mozcrash == 1.0 11:45:39 INFO - mozdebug == 0.1 11:45:39 INFO - mozdevice == 0.48 11:45:39 INFO - mozfile == 1.2 11:45:39 INFO - mozhttpd == 0.7 11:45:39 INFO - mozinfo == 0.9 11:45:39 INFO - mozleak == 0.1 11:45:39 INFO - mozlog == 3.4 11:45:39 INFO - moznetwork == 0.27 11:45:39 INFO - mozprocess == 0.23 11:45:39 INFO - mozprofile == 0.28 11:45:39 INFO - mozrunner == 6.13 11:45:39 INFO - mozscreenshot == 0.1 11:45:39 INFO - mozsystemmonitor == 0.3 11:45:39 INFO - moztest == 0.7 11:45:39 INFO - mozversion == 1.4 11:45:39 INFO - psutil == 3.1.1 11:45:39 INFO - requests == 1.2.3 11:45:39 INFO - wsgiref == 0.1.2 11:45:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:45: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')]} 11:45:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:45:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:45: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')]} 11:45:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:45:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:45: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 0x105f71490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ee7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe40c9a1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d85d30>, '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 0x105bebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d7db20>, '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.X6VVUHoU3h/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.ZGbks5BoIX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 11:45: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 11:45: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 11:45:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X6VVUHoU3h/Render', 11:45:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:45:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:45:39 INFO - 'HOME': '/Users/cltbld', 11:45:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:45:39 INFO - 'LOGNAME': 'cltbld', 11:45:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:45:39 INFO - 'MOZ_NO_REMOTE': '1', 11:45:39 INFO - 'NO_EM_RESTART': '1', 11:45:39 INFO - 'PAGER': '/bin/cat', 11:45:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:45:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:45:39 INFO - 'PWD': '/builds/slave/test', 11:45:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:45:39 INFO - 'SHELL': '/bin/bash', 11:45:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZGbks5BoIX/Listeners', 11:45:39 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 11:45:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:45:39 INFO - 'USER': 'cltbld', 11:45:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:45:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:45:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:45:39 INFO - 'XPC_FLAGS': '0x0', 11:45:39 INFO - 'XPC_SERVICE_NAME': '0', 11:45:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:45:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:45: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)) 11:45: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)) 11:45: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)) 11:45: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)) 11:45: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)) 11:45: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)) 11:45: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)) 11:45: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)) 11:45: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)) 11:45: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)) 11:45: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)) 11:45:39 INFO - Cleaning up... 11:45:39 INFO - Return code: 0 11:45:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:45: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')]} 11:45:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:45:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:45: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')]} 11:45:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:45:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:45: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 0x105f71490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ee7570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe40c9a1e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d85d30>, '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 0x105bebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d7db20>, '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.X6VVUHoU3h/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.ZGbks5BoIX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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 11:45: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 11:45: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 11:45:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X6VVUHoU3h/Render', 11:45:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:45:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:45:39 INFO - 'HOME': '/Users/cltbld', 11:45:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:45:39 INFO - 'LOGNAME': 'cltbld', 11:45:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:45:39 INFO - 'MOZ_NO_REMOTE': '1', 11:45:39 INFO - 'NO_EM_RESTART': '1', 11:45:39 INFO - 'PAGER': '/bin/cat', 11:45:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:45:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:45:39 INFO - 'PWD': '/builds/slave/test', 11:45:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:45:39 INFO - 'SHELL': '/bin/bash', 11:45:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZGbks5BoIX/Listeners', 11:45:39 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 11:45:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:45:39 INFO - 'USER': 'cltbld', 11:45:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:45:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:45:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:45:39 INFO - 'XPC_FLAGS': '0x0', 11:45:39 INFO - 'XPC_SERVICE_NAME': '0', 11:45:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:45:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:45:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:45:40 INFO - Cleaning up... 11:45:40 INFO - Return code: 0 11:45:40 INFO - Running post-action listener: _resource_record_post_action 11:45:40 INFO - Running post-action listener: _start_resource_monitoring 11:45:40 INFO - Starting resource monitoring. 11:45:40 INFO - [mozharness: 2016-11-14 19:45:40.173072Z] Finished create-virtualenv step (success) 11:45:40 INFO - [mozharness: 2016-11-14 19:45:40.174149Z] Running install step. 11:45:40 INFO - Running pre-action listener: _resource_record_pre_action 11:45:40 INFO - Running main action method: install 11:45:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:45:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:45:40 INFO - Reading from file tmpfile_stdout 11:45:40 INFO - Detecting whether we're running mozinstall >=1.0... 11:45:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:45:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:45:40 INFO - Reading from file tmpfile_stdout 11:45:40 INFO - Output received: 11:45:40 INFO - Usage: mozinstall [options] installer 11:45:40 INFO - Options: 11:45:40 INFO - -h, --help show this help message and exit 11:45:40 INFO - -d DEST, --destination=DEST 11:45:40 INFO - Directory to install application into. [default: 11:45:40 INFO - "/builds/slave/test"] 11:45:40 INFO - --app=APP Application being installed. [default: firefox] 11:45:40 INFO - mkdir: /builds/slave/test/build/application 11:45: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'] 11:45: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 11:46:13 INFO - Reading from file tmpfile_stdout 11:46:13 INFO - Output received: 11:46:13 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 11:46:13 INFO - Running post-action listener: _resource_record_post_action 11:46:13 INFO - [mozharness: 2016-11-14 19:46:13.552127Z] Finished install step (success) 11:46:13 INFO - [mozharness: 2016-11-14 19:46:13.552294Z] Running run-tests step. 11:46:13 INFO - Running pre-action listener: _resource_record_pre_action 11:46:13 INFO - Running main action method: run_tests 11:46:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:46:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:46:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:46:13 INFO - 2016-11-14 11:46:13.566 screenresolution[1641:7522] starting screenresolution argv=screenresolution get 11:46:13 INFO - 2016-11-14 11:46:13.569 screenresolution[1641:7522] Display 0: 1600x1200x32@60 11:46:13 INFO - 2016-11-14 11:46:13.575 screenresolution[1642:7524] starting screenresolution argv=screenresolution list 11:46:13 INFO - Available Modes on Display 0 11:46:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:46:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:46:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:46:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:46:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:46:13 INFO - Intel Iris: 11:46:13 INFO - Chipset Model: Intel Iris 11:46:13 INFO - Type: GPU 11:46:13 INFO - Bus: Built-In 11:46:13 INFO - VRAM (Dynamic, Max): 1536 MB 11:46:13 INFO - Vendor: Intel (0x8086) 11:46:13 INFO - Device ID: 0x0a2e 11:46:13 INFO - Revision ID: 0x0009 11:46:13 INFO - Displays: 11:46:13 INFO - Display: 11:46:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:46:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:46:13 INFO - Display Serial Number: 1600x1200 60 11:46:13 INFO - Main Display: Yes 11:46:13 INFO - Mirror: Off 11:46:13 INFO - Online: Yes 11:46:13 INFO - Rotation: Supported 11:46:13 INFO - Return code: 0 11:46:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:46:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:46:13 INFO - Python 2.7.3 11:46:13 INFO - Return code: 0 11:46:13 INFO - grabbing minidump binary from tooltool 11:46:13 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')]} 11:46:13 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 0x105d85d30>, '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 0x105bebdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d7db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:46:13 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 11:46:13 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 11:46:13 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 11:46:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:46:13 INFO - Return code: 0 11:46:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:46:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:46:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:46:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:46:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:46: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/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0179', '--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 11:46: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/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0179 --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 11:46:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X6VVUHoU3h/Render', 11:46:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:46:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:46:13 INFO - 'HOME': '/Users/cltbld', 11:46:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:46:13 INFO - 'LOGNAME': 'cltbld', 11:46:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:46:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:46:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:46:13 INFO - 'MOZ_NO_REMOTE': '1', 11:46:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:46:13 INFO - 'NO_EM_RESTART': '1', 11:46:13 INFO - 'PAGER': '/bin/cat', 11:46:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:46:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:46:13 INFO - 'PWD': '/builds/slave/test', 11:46:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:46:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:46:13 INFO - 'SHELL': '/bin/bash', 11:46:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZGbks5BoIX/Listeners', 11:46:13 INFO - 'TMPDIR': '/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/', 11:46:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:46:13 INFO - 'USER': 'cltbld', 11:46:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:46:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:46:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:46:13 INFO - 'XPC_FLAGS': '0x0', 11:46:13 INFO - 'XPC_SERVICE_NAME': '0', 11:46:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:46: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/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0179', '--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 11:46:14 INFO - mozversion application_buildid: 20161114081542 11:46:14 INFO - mozversion application_changeset: f11ca24b2382ed6c8c7970a82fff53f7f3e0aa6d 11:46:14 INFO - mozversion application_display_name: FirefoxDeveloperEdition 11:46:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:46:14 INFO - mozversion application_name: Firefox 11:46:14 INFO - mozversion application_remotingname: firefox-dev 11:46:14 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:46:14 INFO - mozversion application_vendor: Mozilla 11:46:14 INFO - mozversion application_version: 52.0a2 11:46:14 INFO - mozversion platform_buildid: 20161114081542 11:46:14 INFO - mozversion platform_changeset: f11ca24b2382ed6c8c7970a82fff53f7f3e0aa6d 11:46:14 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:46:14 INFO - mozversion platform_version: 52.0a2 11:46:14 INFO - using testdate: 1479152774 11:46:14 INFO - actual date: 1479152774 11:46:14 INFO - starting webserver on 'localhost:49278' 11:46:14 INFO - SUITE-START | Running 2 tests 11:46:14 INFO - TEST-START | dromaeo_css 11:46:14 INFO - operating with platform_type : mac_ 11:46:14 INFO - Initialising browser for dromaeo_css test... 11:46:14 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmptC3Dof/profile http://localhost:49278/getInfo.html) 11:46:16 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 11:46:17 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 11:46:17 INFO - PROCESS | 1654 | colorDepth:24 11:46:17 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 11:46:17 INFO - PROCESS | 1654 | __metrics 11:46:17 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3178: TypeError: this.tabs is undefined 11:46:18 INFO - TEST-INFO | 1654: exit 0 11:46:18 INFO - Browser initialized. 11:46:18 INFO - Running cycle 1/1 for dromaeo_css test... 11:46:18 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmptC3Dof/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:46:30 INFO - PROCESS | 1658 | RSS: Main: 223940608 11:46:30 INFO - PROCESS | 1658 | 11:47:20 INFO - PROCESS | 1658 | 1479152840241 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] 11:49:34 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 11:49:36 INFO - PROCESS | 1658 | RSS: Main: 236277760 11:49:36 INFO - PROCESS | 1658 | 11:52:39 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 11:52:39 INFO - PROCESS | 1658 | RSS: Main: 247517184 11:52:39 INFO - PROCESS | 1658 | 11:55:41 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 11:55:42 INFO - PROCESS | 1658 | RSS: Main: 251588608 11:55:42 INFO - PROCESS | 1658 | 11:58:44 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 11:58:44 INFO - PROCESS | 1658 | RSS: Main: 250753024 11:58:44 INFO - PROCESS | 1658 | 12:01:47 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 12:01:48 INFO - PROCESS | 1658 | RSS: Main: 254234624 12:01:48 INFO - PROCESS | 1658 | 12:04:50 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 12:04:51 INFO - PROCESS | 1658 | RSS: Main: 255483904 12:04:51 INFO - PROCESS | 1658 | 12:04:51 INFO - PROCESS | 1658 | __start_tp_report 12:04:51 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 12:04:51 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 12:04:51 INFO - PROCESS | 1658 | |i|pagename|runs| 12:04:51 INFO - PROCESS | 1658 | |0;dojo.html;3383.8646454141835;3405.1487128217946;3407.4888766684994;3424.366492198945;3424.7260219182467;5739.25389699339;5806.012977793769;5817.0983497557945;5821.767129314833;5837.182794408786;5824.883502329951;5880.441358070987;5888.852778680535;5924.851878703034;5932.881342373161;5275.0768615492425;5302.893942121155;5318.069337865877;5369.301990741198;5378.273933019051;5326.387465441466;5356.250124982505;5379.220013098098;5379.757910894;5453.909218156365;5195.116830138885;5234.555062819646;5370.758315875775;5382.273393091941;5388.0570900092325;5272.815451459181;5308.177232528964;5309.548688361494;5318.867028324285;5398.352197736258;5129.820456284012;5277.973610131963;5348.171033489815;5351.678899266057;5354.357477102733;5999.400059994009;6038.57729958903;6039.969800150971;6062.454379105901;6079.696015199266;5177.45636708144;6198.783042593533;6230.595011324294;6286.339934306878;6320.62076275425;5806.59353845231;5885.793412350501;6294.214941910314;6313.021481670348;6361.332060133665;5935.910961335583;5940.227770389908;5949.494292985145;5954.523638108941;5980.55145864062;6177.104319873593;6246.843828904313;6256.374362563707;6362.427381535684;6364.172657554488;5892.145638882424;6165.938340616626;6172.9074063889075;6176.042713379434;6181.103739957771;6278.4035516625845;6290.968545157337;6331.873362532816;6345.96827015862;6346.30190679025;6270.122182894398;6287.811365659038;6352.428281454691;6372.490200783926;6373.681315934185;6175.413335733188;6252.499800015988;6268.059791031291;6278.811635650938;6325.462335701425;4982.501749824988;4999.525045120708;5002.649814513022;5008.799648014038;5014.5737612303365;4489.842855500042;4496.482904465963;4510.548945105464;4518.2319005769095;4532.116237333688;5885.352611212763;5912.586118971631;5949.9107513387335;6013.188219590299;6022.819315420545;6264.279607845065;6293.937060629427;6303.558750887486;6347.873042539123;6362.331955144736;5894.056950887923;5919.993601087739;5925.229720136354;5927.792527261525;5927.932593669715;5424.566034717213;5438.673679579238;5450.809221677301;5476.452354764571;5477.780888764484;6260.40526150015;6388.9041664376;6408.00675895237;6412.551121421549;6417.839554011093;6236.937630623635;6267.6866156692895;6291.465225455888;6303.401176888188;6387.776427825004;6388.744450222125;6400.583962042639;6402.455791257796;6412.9679351602945;6421.422072013541;6211.943969525101;6297.086922396135;6309.337519560564;6321.336259692666;6344.68276586178;6102.792231004566;6264;6283.59156654816;6286.905696414649;6292.370762923671;6217.253929528486;6272.466840318441;6276.874462510816;6293.056041593798;6304.84237894038;6203.937960620336;6231;6252;6301.779437719658;6338.334474880256;5969.343372228965;6031.366706495754;6041;6056.788012419544;6080.391960803884;5141.28022076917;5810.215620891126;6208;6291.653959032297;6300.464460520907;6109.389061093855;6150.323464418821;6163.322034576283;6173.32093469709;6183.227096612924 12:04:51 INFO - PROCESS | 1658 | |1;ext.html;445.6234481862826;464.3800526297393;503.80855274995497;514.979400823967;521.4160140642479;28874.278143046435;30240;37244;38310.808445957766;40037;30746.231344216405;32139;32448.18879528013;33495.66504334956;39956.800215998956;9052.230560402373;9163.587638556248;9221.95389023054;9245.815083698322;9254.352195346328;9176.485879829854;9240.768980775501;9258.259339252843;9277.118673725985;9332.72001839946;9093;9106.908930910706;9126.361154719172;9151.588178531982;9156.816863662723;9199.954000229956;9231.892172939222;9262.999999999965;9309.860352094724;9311.487868167249;9015.27877769777;9084.137006984325;9113.908860911371;9142.948560915513;9166.26669866409;38358.616413835785;38538;38592.8422147336;38701.80649096765;38735;3210.807351558914;3248.610166779978;3357.0096821437733;3371.662833716609;3832.310184166849;28073.43853122926;28335.008274710493;30074;30140.84929575338;30296.24259393511;3138.6704396038544;3140.0736782649174;3157.778955473118;3184.85668144934;3248.8538015789345;493703;493892;497483;498236;498375;54661.1800822988;54929.72535137379;55040.72479637576;55068.62328441821;55104.72447637736;57800;58025.70987145122;58056;58127;58363;15105.244737763069;15292.235388230543;15460.304286307142;15480.690386192438;15501.534953951399;10084.394936303845;11954.521819127138;11961.102917281065;12026.639200823814;12028;15119.395224191128;15301.46444874424;15382.384704611914;15504;15728.764068538982;3115.9844200779303;3233.417984762765;5303.541526080282;5438.945610543923;6473.190851143607;827.735124760071;966.6423423333412;971.3686104032436;996.0089710737863;1015.669472990385;27078.323041924108;27714.722852771618;28082.43835123286;28277.293067673472;28317.433651326857;955.1308309438377;1014.9543270552842;1022.7749895023084;1027.6146445083093;1034.5758239121924;14146.78779818283;14493.130412175304;14868.405263789327;15343.539693809204;15608.531744047697;653733;659943;661163;661895;664683;54375;54799.72600136974;54888.72555637196;54893;55119.448805512235;1312.7637025335341;1345.1055048392905;1346.3873937358478;1347.3802051056427;1359.16411406984;28144.85927570349;28788.712112879442;28830.567541487315;28878.422431550833;29122.56316155302;47491.52508474871;51710.96578068493;53302;53525.464745352045;56668.7166564181;534090;535171;539705;539840;540221;40449.79775101106;40801.795991019855;41049.38425923673;41286.1742765137;41305;44880;44888.77555612201;45146.77426612846;45263;45430.86422839455;29855.29473862467;30209.942652007074;32320;36984.44523332206;37213.62786372102;32219;33138.17154571155;35621.643783561834;37354.252914942095;38087.428688570246;36842.63157368392;37495;37653.81173094117;38231.42652860265;38795.806020969714;24510;29622;32002.679973200902;33879;34040.82979585086;25037.998480060593;25066.74933250717;25179.992800288517;25185.37036574027;25416.36783807516 12:04:51 INFO - PROCESS | 1658 | |2;jquery.html;361.1494929441166;364.90147660131765;373.6002477349236;377.18527979564146;391.75319548684325;18744.531386715324;19134.712979305303;19440.513987150294;19849.50376240593;19957;11433.199676022681;11824;11826.23129496582;11829.994450471699;11836.585719499819;4737.005228901918;5042.16804227303;5353.116735738598;5402.270693456372;5469.890602187954;3792.848286068554;4035.737677050997;5213.635045546814;5406.40529541752;5412.972935135319;5989.490893274055;6085.630733085065;6088.543359248074;6094.908576371358;6105.664188469633;6072.240969878765;6239.844003899916;6542.803715888507;6570.342965703439;6573;5713.74288157034;5823.5341172706085;5831.446012628793;5844;6922.7923162305215;26747.33131671704;27158.864205678845;27283.590746138823;27995.580066299022;28423;2174.913003479859;2505.837120587156;2538.352720056392;3147.9370412591848;3194.7444204463586;13531.864681353258;15514.61213469661;16089.275982580812;16223.51329460118;17067.829321706748;1463.4585203474676;1930.0433812531373;1930.237556165321;2044.8875311857842;2051.4050925231663;110983;112292.99999999919;116907.41546292299;131371;135590.96613550806;19493;19953.40372770175;21892.45268868291;21960.89019554892;22303;35144.47283290753;35334;35872.28255434876;36033.45949810755;36091.27817443689;8809.559522023874;8879.600417981206;10600.522976465923;10608.946955265174;10836;8703.260222881;8729;8790.516521591373;8968.551572421353;8981.910180898238;8255.42212045151;9451.960284368713;9555.23558115349;9635.56639951204;9760.267979901535;1572.1196130167143;1657.8756593255555;3128.749700023993;3828.942565861514;3846.74996125251;786.8465649198466;799.4403917257827;962.7737481691797;998.7802683409639;1010.3079390617451;14854.777178342334;16426.83573164277;16901.91549042247;17595.56011099733;19522.8047719521;823.8434697407473;892.4199270474114;927.6567669962158;933.8272419602396;989.3322007644811;9119.179273865384;9915.851262231072;10145.594176233015;10663.040326370663;10677;528269;595418;604233;604611;617143;82171.5891420551;126230.36884815517;129325;131896.3405182987;133245.0013249802;924.0251534630894;936.0826390137568;979.6228452046014;991.0436428846054;993.0169132558859;14400.567982960529;17661.911690441466;18080;18292.542686432946;18438.078096094872;46056.61830145101;51733.7413312931;52516.47483525116;52868.206976896145;53819.6545086376;78537;80002.19997800181;80113.19886801057;80182;81019;6921.653917304014;7340.706371745284;7386.372158366599;7421.554706717614;7505.249475052451;12020.579279725167;12566.560170393992;12675.556355527513;12684.302363370103;12749.808752868528;10836.566537338418;10888.074513666426;10899.618513351994;11141.72145696364;11225.550977960791;10808.135349172204;10980.231383803057;10985.285956413127;11010.779784404427;11051.005409513182;5720.141978703228;8235.876461852957;9331.60025996106;11054.723631909266;11212.607558735404;6857.27998560144;7364.189939106587;7415.888761668687;7460.552366858006;7566.546007239584;10318.329308594917;10887.78224435491;11051;11139.665810025712;11337 12:04:51 INFO - PROCESS | 1658 | |3;mootools.html;1433.2833583208396;1437.7052704195637;1574.6614477887251;1709.7862767154106;1710.1962077823418;7643.197464266255;7898.644560994755;8227.670893164273;8636.91363086369;8664.43681160725;8085;8410.285125764318;8415.49507029577;8501.489910605367;8509.957450212756;2303.297494264253;2337.649352597107;2342.437814924423;2342.8945697443655;2350.588646986779;2326.697529321186;2362.2204672458133;2363.5745565798147;2368.9407764805846;2379.95240095198;2293.6100862853273;2295.2999335202758;2317.8609283443047;2321.7213934327906;2328.75548067453;1982.742243508342;2300.6088964875926;2318.710161229846;2323;2337;2328.7438381777993;2358.4339758457845;2362.4093976505874;2366.0299277296226;2366.1245339224424;8453.408261421702;8522.82954340916;8568.143185681445;8585.570721463964;8677.826443471158;5130.51260130287;5277.947220527784;5295.020421222048;5297.549708274802;5312.867178320535;3164.430402527544;3258.266889949766;3455.8963231103107;3613.927721445583;3622.2393297407575;999.7650552120245;1016.7305663999047;1031.2111234905303;1092.8852470490642;1097.544519024604;11021.118310535137;11093.500792464358;11094.55621775128;11134.16493762971;11146.554137834477;4505.346724724896;4583.679142459996;4678.157931572349;4685;4759.286107083965;9446.527673616292;9502.90497095034;9519.762005949768;9525.333226674205;9556.331056825955;5192.143296356135;5224.686518808808;5242.449542798027;5246.08193566132;5256.553192978563;4231.640310573574;4249.383839343278;4586.289125185602;4645.74448405341;4849.636277279147;3380.7829181494644;3698.8705395311035;3765.9435108473394;3855.9036024099623;3883.0486530800135;1258.5783762439685;1275.6236910111538;1278.3288773393856;1282.8396450443695;1288.890444312244;866.6966561703354;881.4931414436596;884.6284560484612;886.2068448738343;901.0628945896326;5641.774329026793;5710.486056254874;5745.454181852718;5778.624389414675;5871.588988770745;829.9045260256524;836.0928392693874;929.0894922975522;938.5448057692014;965.3821554205314;3617.2403795203304;3675.3016926783826;3703.4259689748133;3736;3785.8674946376746;8050.919490805134;9625.374350667184;10082.395056296498;10170.745731356626;10363.63727269542;10088.394696318097;10252.38485690846;10326.896731032744;10329.535170917326;10342.534585943651;7822;8056.637451314705;8094.959525202454;8122;8123.431359804875;5598.216249725123;5634.464725851038;5692.1461780733225;5788.479036886701;5813.593048486566;8391.454555453876;9447.905520944703;9524.85712714296;9611.038896110333;9636.662716804878;9400.435973841319;9507.762305942406;9663.46850923206;9768.853467197856;9843.360181588174;7122.750703725552;7246.60143692102;7267.890981635174;7294.890576641248;7326.267373262631;7798.883016754639;7838.608069596383;7850.646720897459;7909.9209007909185;7924.960375198087;7024.929750702632;7153.928460715326;7218.530795498275;7289.562626242442;7291.41668666516;6928.168619765459;7040.929590704232;7054.435645148478;7147.892781608384;7158.713651453883;6652.667366631552;6705.731770729311;6742.9662851685425;6764.763233286812;6769.221539522821;4025.255327764373;4703.3885594872445;4717.646176536705;4789.688670236424;4790.113828941657;4164.271252530855;4711.505291944297;4735.928961065656;4739.312799644102;4820.156472617348 12:04:51 INFO - PROCESS | 1658 | |4;prototype.html;265.9946801063979;294.8511001944018;303.6326045484963;307.17370273963036;307.41590977143437;3987.7806720630356;5143.717095559743;5347.417230455594;5396.352437707471;5426.864328391792;4882.780274887629;5161.922571161426;5296.987144884948;5322.467753224676;5369.731513424323;4043.919121617573;4068.7965601719798;4084.8319038252157;4102.979485102585;4103.548609652936;3459.6540345965327;3943.5464921533962;4085.774267719687;4121.258173528764;4126.66986641068;3362.6469220731838;4039.1113954930015;4044.4539987101657;4064.2074795414906;4098.139390727936;4031.294523458412;4052.6555242804247;4073.714839961204;4080.2451546464;4118.876433706993;3145.35520218357;3580.2481478889463;3586.659267369596;3595;3610.85154333174;4911.263310503453;5107.591392688576;5163.767630456601;5176.430592634807;5190.299309593194;4258.61672449481;4426.269665505188;4543.864033991502;4659.58063774262;4730.86459249781;5317.361916570037;5617.269754931874;5632.268349850319;5674.432556744334;5707.71461426927;3564.4475106358555;3840.2895464339185;3844.6347596978244;3854.903627409309;3987.760734355919;5888;5944.910826337608;5946.405359464019;5948.553858460633;5970.910436343502;3553.916055603066;3890.513685789276;3937.448757173998;3951.920961580752;3952.01199700075;3762.6454476388476;3898.602359548857;3957.3866050762176;3971.841126354971;3987;4202.495700515958;4563.794629241692;4596.851156957495;4598.356230127785;4599.9080018399445;5095.974520127375;5667.234923285386;5692.516136128475;5693.857653558694;5714.771409143671;1227.6623928419756;1251.5119103549623;1262.0282382565374;1273.3951373097705;1273.5669872243482;769.4959801330137;831.3681601982537;859.4928991894812;867.2454964181203;867.3798234262406;4281.871543853689;4843.418789745253;5078.765343248959;5082.415522214918;5123.846284611468;5053.84838454847;5092.460739785887;5315.893682126336;5506.669599824023;5565.877534711865;4554.4990051094355;4662.417197850269;4820.975895120572;5043.8903441242865;5054.523283591014;2872.314386049051;2887.2898325651436;2893.3923875985856;2896.7392934635564;2915.927101822429;5155.716435596079;5697.686627235542;5706.400827913092;5769.134629805562;5799.5650326225705;4500.8649740507835;5017.058197675287;5648.802291919846;5649.717514124307;5649.841782434591;4810.542998415145;4950.727709975986;5553.944750497392;5701.543876489954;5728.541716662574;4527.864164075083;5364.658835291178;5471.632091977006;5551.389347171727;5552.529124539541;4681.133990211685;5308.946910531;5359.866003349792;5498.2268617951795;5770.567207459373;5248.9737551312;5864.501517370901;6127.234095738033;6131.294901086432;6162.815115546541;3706.388445906503;3935.7244992850224;3936.4488971543433;3938.133610605662;3968.0079980005;4820.903581928272;5634.267545219259;5867.618604790675;5874.5887787854435;5904.586678932434;4558.723557403932;5222.321098257202;5581.49766521015;5609.074502707008;5654.491095801398;4417.293233082692;5690.3456102548735;5729.28383952006;5736.053551164011;5738.9139162913425;4690.859274221779;5330.280412144466;5580.665160090408;5588.832335029955;5613.46672066153;5602.13166959122;5664.3856500897045;5671.716414179192;5680.829575112753;5691.288588926384;5593.692346920959;5610.635308704921;5614.101743720985;5615.298087739033;5648.661080335193 12:04:51 INFO - PROCESS | 1658 | |5;yui.html;579.4553120067137;666.6366830077608;714.2928500784225;720.8522252938145;757.333865495909;13705.725885482298;14282.928585357085;14306.642333941656;14373.640658983531;14467.974483572296;12739.426725797339;12903.741925161492;12910.225386476817;12911.418986145622;12916.741665166692;783.1307248953657;785.0344076785557;798.8402319536087;813.0203105258179;819.5001049359886;602.6715440085164;693.1647364925278;700.5201437015635;782.3858271257064;804.4409135650695;818.2226884459787;820.7783898347443;843.5107637570195;846.4032856835915;847.8304339132168;806.2824086562964;832.097174565597;836.85773418519;843.0599881132555;845.247729520727;550.1637510983282;617.9340637400493;630.9400606942334;631.3307893632764;634.330781026022;14145.717085658333;14150.575482735534;14178.574642760734;14199.71600567983;14206.360713767905;11592.420378981102;12595.685107872376;13181.670458238525;13403.731925361439;13438.19370837743;11368.204225704205;11450.885491145065;11501.367424791633;11615.941920290428;11978.940105299505;2937.985310073457;3220.5652236947944;3588.3361578108133;3589.335972845032;3618.222082252328;89336;90083.54958225232;92761;92872;93700.53149734208;10041.497925103715;10146.543405546769;10318.43248621333;10334.483275836179;10468.63359782404;16229.675406491804;16692.41576544815;19107.23571057142;19820.70268945967;20083;7578.658960346797;7607.389979251578;7796.259355361231;7877.4091943104495;7947.284744373034;4267.914641707149;4304.612584867377;4309.978450107729;4461.442319710036;4496.033352829158;3415.2315728960784;3497.1257185703575;3507.7544571879725;3510.420780571177;3517.314123745845;627.6171535363425;639.9424051835357;646.8253571535658;652.3509108437136;661.8841702702008;533.1522878623008;581.1312088427743;602.9276486821609;636.9585976911578;638.5211091681239;8771.254443372385;9038.819223615623;9119.863202051974;9287.024862389488;9320.161185493338;601.2004034633926;628.9590727346249;650.768977013163;661.0744957060155;663.8041777675597;3586.6951309139017;3611.494390785292;4512.323151527232;4524.524924882906;4622.583967442946;89220;95198.0480195203;98193.50903245439;99945.50027249963;121845.78154218344;98169;98369.5244571332;101231.48152777714;102757;104931;89976.6503502448;92004;93206;94697.52651236838;95775.52112239394;8760.649574017223;8796.296296296408;8798.34012449056;8799.736007919773;8939.821203576023;38745.80627096847;40977.975550611474;52760;52924.522678957954;55064.44935550593;74757;76138.23861761311;76738;78394.21605784097;79357.60321198357;10892.564297428331;11119.388433635904;11316;11589.59436419755;11948.940255299016;13611.251381173734;13784.931075344559;14427.49503767363;14525.564233073024;14613;12667.366631668196;12999.285039322927;13246.47014119463;13277.653917007383;14125.223112728898;13103.01727370432;13204.207747535178;13757.17456952586;13985.860141398864;14089;12186.451609677762;12858.614241572768;12869.678258043623;13202.405891735087;13291.003174762125;10698;11091.667249982513;11338.773224535298;11452;11588.94205528967;9365.812683746151;11188;11403.714907127056;11443.542258309575;11486.540538378371 12:04:51 INFO - PROCESS | 1658 | __end_tp_report 12:04:51 INFO - PROCESS | 1658 | __start_cc_report 12:04:51 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,372 12:04:51 INFO - PROCESS | 1658 | __end_cc_report 12:04:51 INFO - PROCESS | 1658 | __startTimestamp1479153891119__endTimestamp 12:04:51 INFO - PROCESS | 1658 | 12:04:51 INFO - PROCESS | 1658 | ------- Summary: start ------- 12:04:51 INFO - PROCESS | 1658 | Number of tests: 6 12:04:51 INFO - PROCESS | 1658 | 12:04:51 INFO - PROCESS | 1658 | [#0] dojo.html Cycles:165 Average:5838.73 Median:6056.79 stddev:639.63 (10.6%) stddev-sans-first:611.92 12:04:51 INFO - PROCESS | 1658 | Values: 3383.9 3405.1 3407.5 3424.4 3424.7 5739.3 5806.0 5817.1 5821.8 5837.2 5824.9 5880.4 5888.9 5924.9 5932.9 5275.1 5302.9 5318.1 5369.3 5378.3 5326.4 5356.3 5379.2 5379.8 5453.9 5195.1 5234.6 5370.8 5382.3 5388.1 5272.8 5308.2 5309.5 5318.9 5398.4 5129.8 5278.0 5348.2 5351.7 5354.4 5999.4 6038.6 6040.0 6062.5 6079.7 5177.5 6198.8 6230.6 6286.3 6320.6 5806.6 5885.8 6294.2 6313.0 6361.3 5935.9 5940.2 5949.5 5954.5 5980.6 6177.1 6246.8 6256.4 6362.4 6364.2 5892.1 6165.9 6172.9 6176.0 6181.1 6278.4 6291.0 6331.9 6346.0 6346.3 6270.1 6287.8 6352.4 6372.5 6373.7 6175.4 6252.5 6268.1 6278.8 6325.5 4982.5 4999.5 5002.6 5008.8 5014.6 4489.8 4496.5 4510.5 4518.2 4532.1 5885.4 5912.6 5949.9 6013.2 6022.8 6264.3 6293.9 6303.6 6347.9 6362.3 5894.1 5920.0 5925.2 5927.8 5927.9 5424.6 5438.7 5450.8 5476.5 5477.8 6260.4 6388.9 6408.0 6412.6 6417.8 6236.9 6267.7 6291.5 6303.4 6387.8 6388.7 6400.6 6402.5 6413.0 6421.4 6211.9 6297.1 6309.3 6321.3 6344.7 6102.8 6264.0 6283.6 6286.9 6292.4 6217.3 6272.5 6276.9 6293.1 6304.8 6203.9 6231.0 6252.0 6301.8 6338.3 5969.3 6031.4 6041.0 6056.8 6080.4 5141.3 5810.2 6208.0 6291.7 6300.5 6109.4 6150.3 6163.3 6173.3 6183.2 12:04:51 INFO - PROCESS | 1658 | 12:04:51 INFO - PROCESS | 1658 | [#1] ext.html Cycles:180 Average:68748.11 Median:38565.42 stddev:152297.90 (394.9%) stddev-sans-first:152638.80 12:04:51 INFO - PROCESS | 1658 | Values: 445.6 464.4 503.8 515.0 521.4 28874.3 30240.0 37244.0 38310.8 40037.0 30746.2 32139.0 32448.2 33495.7 39956.8 9052.2 9163.6 9222.0 9245.8 9254.4 9176.5 9240.8 9258.3 9277.1 9332.7 9093.0 9106.9 9126.4 9151.6 9156.8 9200.0 9231.9 9263.0 9309.9 9311.5 9015.3 9084.1 9113.9 9142.9 9166.3 38358.6 38538.0 38592.8 38701.8 38735.0 3210.8 3248.6 3357.0 3371.7 3832.3 28073.4 28335.0 30074.0 30140.8 30296.2 3138.7 3140.1 3157.8 3184.9 3248.9 493703.0 493892.0 497483.0 498236.0 498375.0 54661.2 54929.7 55040.7 55068.6 55104.7 57800.0 58025.7 58056.0 58127.0 58363.0 15105.2 15292.2 15460.3 15480.7 15501.5 10084.4 11954.5 11961.1 12026.6 12028.0 15119.4 15301.5 15382.4 15504.0 15728.8 3116.0 3233.4 5303.5 5438.9 6473.2 827.7 966.6 971.4 996.0 1015.7 27078.3 27714.7 28082.4 28277.3 28317.4 955.1 1015.0 1022.8 1027.6 1034.6 14146.8 14493.1 14868.4 15343.5 15608.5 653733.0 659943.0 661163.0 661895.0 664683.0 54375.0 54799.7 54888.7 54893.0 55119.4 1312.8 1345.1 1346.4 1347.4 1359.2 28144.9 28788.7 28830.6 28878.4 29122.6 47491.5 51711.0 53302.0 53525.5 56668.7 534090.0 535171.0 539705.0 539840.0 540221.0 40449.8 40801.8 41049.4 41286.2 41305.0 44880.0 44888.8 45146.8 45263.0 45430.9 29855.3 30209.9 32320.0 36984.4 37213.6 32219.0 33138.2 35621.6 37354.3 38087.4 36842.6 37495.0 37653.8 38231.4 38795.8 24510.0 29622.0 32002.7 33879.0 34040.8 25038.0 25066.7 25180.0 25185.4 25416.4 12:04:51 INFO - PROCESS | 1658 | 12:04:51 INFO - PROCESS | 1658 | [#2] jquery.html Cycles:180 Average:35427.99 Median:18226.21 stddev:98558.06 (540.7%) stddev-sans-first:98799.37 12:04:51 INFO - PROCESS | 1658 | Values: 361.1 364.9 373.6 377.2 391.8 18744.5 19134.7 19440.5 19849.5 19957.0 11433.2 11824.0 11826.2 11830.0 11836.6 4737.0 5042.2 5353.1 5402.3 5469.9 3792.8 4035.7 5213.6 5406.4 5413.0 5989.5 6085.6 6088.5 6094.9 6105.7 6072.2 6239.8 6542.8 6570.3 6573.0 5713.7 5823.5 5831.4 5844.0 6922.8 26747.3 27158.9 27283.6 27995.6 28423.0 2174.9 2505.8 2538.4 3147.9 3194.7 13531.9 15514.6 16089.3 16223.5 17067.8 1463.5 1930.0 1930.2 2044.9 2051.4 110983.0 112293.0 116907.4 131371.0 135591.0 19493.0 19953.4 21892.5 21960.9 22303.0 35144.5 35334.0 35872.3 36033.5 36091.3 8809.6 8879.6 10600.5 10608.9 10836.0 8703.3 8729.0 8790.5 8968.6 8981.9 8255.4 9452.0 9555.2 9635.6 9760.3 1572.1 1657.9 3128.7 3828.9 3846.7 786.8 799.4 962.8 998.8 1010.3 14854.8 16426.8 16901.9 17595.6 19522.8 823.8 892.4 927.7 933.8 989.3 9119.2 9915.9 10145.6 10663.0 10677.0 528269.0 595418.0 604233.0 604611.0 617143.0 82171.6 126230.4 129325.0 131896.3 133245.0 924.0 936.1 979.6 991.0 993.0 14400.6 17661.9 18080.0 18292.5 18438.1 46056.6 51733.7 52516.5 52868.2 53819.7 78537.0 80002.2 80113.2 80182.0 81019.0 6921.7 7340.7 7386.4 7421.6 7505.2 12020.6 12566.6 12675.6 12684.3 12749.8 10836.6 10888.1 10899.6 11141.7 11225.6 10808.1 10980.2 10985.3 11010.8 11051.0 5720.1 8235.9 9331.6 11054.7 11212.6 6857.3 7364.2 7415.9 7460.6 7566.5 10318.3 10887.8 11051.0 11139.7 11337.0 12:04:51 INFO - PROCESS | 1658 | 12:04:51 INFO - PROCESS | 1658 | [#3] mootools.html Cycles:180 Average:5480.22 Median:5244.27 stddev:3026.60 (57.7%) stddev-sans-first:3019.81 12:04:51 INFO - PROCESS | 1658 | Values: 1433.3 1437.7 1574.7 1709.8 1710.2 7643.2 7898.6 8227.7 8636.9 8664.4 8085.0 8410.3 8415.5 8501.5 8510.0 2303.3 2337.6 2342.4 2342.9 2350.6 2326.7 2362.2 2363.6 2368.9 2380.0 2293.6 2295.3 2317.9 2321.7 2328.8 1982.7 2300.6 2318.7 2323.0 2337.0 2328.7 2358.4 2362.4 2366.0 2366.1 8453.4 8522.8 8568.1 8585.6 8677.8 5130.5 5277.9 5295.0 5297.5 5312.9 3164.4 3258.3 3455.9 3613.9 3622.2 999.8 1016.7 1031.2 1092.9 1097.5 11021.1 11093.5 11094.6 11134.2 11146.6 4505.3 4583.7 4678.2 4685.0 4759.3 9446.5 9502.9 9519.8 9525.3 9556.3 5192.1 5224.7 5242.4 5246.1 5256.6 4231.6 4249.4 4586.3 4645.7 4849.6 3380.8 3698.9 3765.9 3855.9 3883.0 1258.6 1275.6 1278.3 1282.8 1288.9 866.7 881.5 884.6 886.2 901.1 5641.8 5710.5 5745.5 5778.6 5871.6 829.9 836.1 929.1 938.5 965.4 3617.2 3675.3 3703.4 3736.0 3785.9 8050.9 9625.4 10082.4 10170.7 10363.6 10088.4 10252.4 10326.9 10329.5 10342.5 7822.0 8056.6 8095.0 8122.0 8123.4 5598.2 5634.5 5692.1 5788.5 5813.6 8391.5 9447.9 9524.9 9611.0 9636.7 9400.4 9507.8 9663.5 9768.9 9843.4 7122.8 7246.6 7267.9 7294.9 7326.3 7798.9 7838.6 7850.6 7909.9 7925.0 7024.9 7153.9 7218.5 7289.6 7291.4 6928.2 7040.9 7054.4 7147.9 7158.7 6652.7 6705.7 6743.0 6764.8 6769.2 4025.3 4703.4 4717.6 4789.7 4790.1 4164.3 4711.5 4735.9 4739.3 4820.2 12:04:51 INFO - PROCESS | 1658 | 12:04:51 INFO - PROCESS | 1658 | [#4] prototype.html Cycles:180 Average:4518.61 Median:5066.64 stddev:1386.24 (27.4%) stddev-sans-first:1352.88 12:04:51 INFO - PROCESS | 1658 | Values: 266.0 294.9 303.6 307.2 307.4 3987.8 5143.7 5347.4 5396.4 5426.9 4882.8 5161.9 5297.0 5322.5 5369.7 4043.9 4068.8 4084.8 4103.0 4103.5 3459.7 3943.5 4085.8 4121.3 4126.7 3362.6 4039.1 4044.5 4064.2 4098.1 4031.3 4052.7 4073.7 4080.2 4118.9 3145.4 3580.2 3586.7 3595.0 3610.9 4911.3 5107.6 5163.8 5176.4 5190.3 4258.6 4426.3 4543.9 4659.6 4730.9 5317.4 5617.3 5632.3 5674.4 5707.7 3564.4 3840.3 3844.6 3854.9 3987.8 5888.0 5944.9 5946.4 5948.6 5970.9 3553.9 3890.5 3937.4 3951.9 3952.0 3762.6 3898.6 3957.4 3971.8 3987.0 4202.5 4563.8 4596.9 4598.4 4599.9 5096.0 5667.2 5692.5 5693.9 5714.8 1227.7 1251.5 1262.0 1273.4 1273.6 769.5 831.4 859.5 867.2 867.4 4281.9 4843.4 5078.8 5082.4 5123.8 5053.8 5092.5 5315.9 5506.7 5565.9 4554.5 4662.4 4821.0 5043.9 5054.5 2872.3 2887.3 2893.4 2896.7 2915.9 5155.7 5697.7 5706.4 5769.1 5799.6 4500.9 5017.1 5648.8 5649.7 5649.8 4810.5 4950.7 5553.9 5701.5 5728.5 4527.9 5364.7 5471.6 5551.4 5552.5 4681.1 5308.9 5359.9 5498.2 5770.6 5249.0 5864.5 6127.2 6131.3 6162.8 3706.4 3935.7 3936.4 3938.1 3968.0 4820.9 5634.3 5867.6 5874.6 5904.6 4558.7 5222.3 5581.5 5609.1 5654.5 4417.3 5690.3 5729.3 5736.1 5738.9 4690.9 5330.3 5580.7 5588.8 5613.5 5602.1 5664.4 5671.7 5680.8 5691.3 5593.7 5610.6 5614.1 5615.3 5648.7 12:04:51 INFO - PROCESS | 1658 | 12:04:51 INFO - PROCESS | 1658 | [#5] yui.html Cycles:180 Average:20594.68 Median:4478.74 stddev:30529.12 (681.6%) stddev-sans-first:30577.78 12:04:51 INFO - PROCESS | 1658 | Values: 579.5 666.6 714.3 720.9 757.3 13705.7 14282.9 14306.6 14373.6 14468.0 12739.4 12903.7 12910.2 12911.4 12916.7 783.1 785.0 798.8 813.0 819.5 602.7 693.2 700.5 782.4 804.4 818.2 820.8 843.5 846.4 847.8 806.3 832.1 836.9 843.1 845.2 550.2 617.9 630.9 631.3 634.3 14145.7 14150.6 14178.6 14199.7 14206.4 11592.4 12595.7 13181.7 13403.7 13438.2 11368.2 11450.9 11501.4 11615.9 11978.9 2938.0 3220.6 3588.3 3589.3 3618.2 89336.0 90083.5 92761.0 92872.0 93700.5 10041.5 10146.5 10318.4 10334.5 10468.6 16229.7 16692.4 19107.2 19820.7 20083.0 7578.7 7607.4 7796.3 7877.4 7947.3 4267.9 4304.6 4310.0 4461.4 4496.0 3415.2 3497.1 3507.8 3510.4 3517.3 627.6 639.9 646.8 652.4 661.9 533.2 581.1 602.9 637.0 638.5 8771.3 9038.8 9119.9 9287.0 9320.2 601.2 629.0 650.8 661.1 663.8 3586.7 3611.5 4512.3 4524.5 4622.6 89220.0 95198.0 98193.5 99945.5 121845.8 98169.0 98369.5 101231.5 102757.0 104931.0 89976.7 92004.0 93206.0 94697.5 95775.5 8760.6 8796.3 8798.3 8799.7 8939.8 38745.8 40978.0 52760.0 52924.5 55064.4 74757.0 76138.2 76738.0 78394.2 79357.6 10892.6 11119.4 11316.0 11589.6 11948.9 13611.3 13784.9 14427.5 14525.6 14613.0 12667.4 12999.3 13246.5 13277.7 14125.2 13103.0 13204.2 13757.2 13985.9 14089.0 12186.5 12858.6 12869.7 13202.4 13291.0 10698.0 11091.7 11338.8 11452.0 11588.9 9365.8 11188.0 11403.7 11443.5 11486.5 12:04:51 INFO - PROCESS | 1658 | -------- Summary: end -------- 12:04:51 INFO - PROCESS | 1658 | 12:04:51 INFO - TEST-INFO | 1658: exit 0 12:04:51 INFO - TEST-OK | dromaeo_css | took 1117467ms 12:04:51 INFO - TEST-START | kraken 12:04:51 INFO - operating with platform_type : mac_ 12:04:51 INFO - Initialising browser for kraken test... 12:04:51 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmp2f4sDL/profile http://localhost:49278/getInfo.html) 12:04:53 ERROR - PROCESS | 1667 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 12:04:54 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 12:04:54 INFO - PROCESS | 1667 | colorDepth:24 12:04:54 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 12:04:54 INFO - PROCESS | 1667 | __metrics 12:04:54 ERROR - PROCESS | 1667 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3178: TypeError: this.tabs is undefined 12:04:55 INFO - TEST-INFO | 1667: exit 0 12:04:55 INFO - Browser initialized. 12:04:55 INFO - Running cycle 1/1 for kraken test... 12:04:55 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/T/tmp2f4sDL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:05:07 INFO - PROCESS | 1669 | RSS: Main: 224530432 12:05:07 INFO - PROCESS | 1669 | 12:05:10 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 12:05:11 INFO - PROCESS | 1669 | RSS: Main: 236552192 12:05:11 INFO - PROCESS | 1669 | 12:05:13 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 12:05:14 INFO - PROCESS | 1669 | RSS: Main: 244080640 12:05:14 INFO - PROCESS | 1669 | 12:05:17 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 12:05:18 INFO - PROCESS | 1669 | RSS: Main: 247771136 12:05:18 INFO - PROCESS | 1669 | 12:05:20 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 12:05:20 INFO - PROCESS | 1669 | RSS: Main: 249102336 12:05:20 INFO - PROCESS | 1669 | 12:05:22 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:05:23 INFO - PROCESS | 1669 | RSS: Main: 249458688 12:05:23 INFO - PROCESS | 1669 | 12:05:26 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 12:05:27 INFO - PROCESS | 1669 | RSS: Main: 253308928 12:05:27 INFO - PROCESS | 1669 | 12:05:30 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 12:05:31 INFO - PROCESS | 1669 | RSS: Main: 252403712 12:05:31 INFO - PROCESS | 1669 | 12:05:33 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 12:05:34 INFO - PROCESS | 1669 | RSS: Main: 252624896 12:05:34 INFO - PROCESS | 1669 | 12:05:36 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:05:36 INFO - PROCESS | 1669 | RSS: Main: 251846656 12:05:36 INFO - PROCESS | 1669 | 12:05:38 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:05:39 INFO - PROCESS | 1669 | RSS: Main: 251940864 12:05:39 INFO - PROCESS | 1669 | 12:05:41 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:05:42 INFO - PROCESS | 1669 | RSS: Main: 251908096 12:05:42 INFO - PROCESS | 1669 | 12:05:45 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:05:46 INFO - PROCESS | 1669 | RSS: Main: 252162048 12:05:46 INFO - PROCESS | 1669 | 12:05:49 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:05:49 INFO - PROCESS | 1669 | RSS: Main: 252035072 12:05:49 INFO - PROCESS | 1669 | 12:05:51 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 12:05:51 INFO - PROCESS | 1669 | RSS: Main: 253587456 12:05:51 INFO - PROCESS | 1669 | 12:05:51 INFO - PROCESS | 1669 | __start_tp_report 12:05:51 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 12:05:51 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 12:05:51 INFO - PROCESS | 1669 | |i|pagename|runs| 12:05:51 INFO - PROCESS | 1669 | |0;ai-astar;107;120;119;119;119;120;118;119;132;177 12:05:51 INFO - PROCESS | 1669 | |1;audio-beat-detection;146;117;118;148;116;118;116;117;140;122 12:05:51 INFO - PROCESS | 1669 | |2;audio-dft;196;206;198;202;197;227;184;274;215;197 12:05:51 INFO - PROCESS | 1669 | |3;audio-fft;83;59;60;93;64;61;60;61;82;74 12:05:51 INFO - PROCESS | 1669 | |4;audio-oscillator;79;72;91;72;74;71;87;70;72;88 12:05:51 INFO - PROCESS | 1669 | |5;imaging-gaussian-blur;132;131;95;98;137;98;127;132;113;115 12:05:51 INFO - PROCESS | 1669 | |6;imaging-darkroom;102;104;112;101;102;100;103;147;128;112 12:05:51 INFO - PROCESS | 1669 | |7;imaging-desaturate;89;95;125;100;87;88;90;81;119;71 12:05:51 INFO - PROCESS | 1669 | |8;json-parse-financial;56;55;57;59;59;61;59;60;58;59 12:05:51 INFO - PROCESS | 1669 | |9;json-stringify-tinderbox;68;75;71;79;78;71;92;103;106;88 12:05:51 INFO - PROCESS | 1669 | |10;stanford-crypto-aes;82;85;85;85;87;86;88;98;87;86 12:05:51 INFO - PROCESS | 1669 | |11;stanford-crypto-ccm;149;185;144;154;155;158;164;164;215;159 12:05:51 INFO - PROCESS | 1669 | |12;stanford-crypto-pbkdf2;165;175;166;166;170;173;171;176;171;166 12:05:51 INFO - PROCESS | 1669 | |13;stanford-crypto-sha256-iterative;69;68;66;70;68;81;83;87;90;77 12:05:51 INFO - PROCESS | 1669 | __end_tp_report 12:05:51 INFO - PROCESS | 1669 | __start_cc_report 12:05:51 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,1007 12:05:51 INFO - PROCESS | 1669 | __end_cc_report 12:05:51 INFO - PROCESS | 1669 | __startTimestamp1479153951786__endTimestamp 12:05:51 INFO - PROCESS | 1669 | 12:05:51 INFO - PROCESS | 1669 | ------- Summary: start ------- 12:05:51 INFO - PROCESS | 1669 | Number of tests: 14 12:05:51 INFO - PROCESS | 1669 | 12:05:51 INFO - PROCESS | 1669 | [#0] ai-astar Cycles:10 Average:125.00 Median:119.50 stddev:19.21 (16.1%) stddev-sans-first:19.24 12:05:51 INFO - PROCESS | 1669 | Values: 107.0 120.0 119.0 119.0 119.0 120.0 118.0 119.0 132.0 177.0 12:05:51 INFO - PROCESS | 1669 | 12:05:51 INFO - PROCESS | 1669 | [#1] audio-beat-detection Cycles:10 Average:125.80 Median:120.00 stddev:13.27 (11.1%) stddev-sans-first:11.90 12:05:51 INFO - PROCESS | 1669 | Values: 146.0 117.0 118.0 148.0 116.0 118.0 116.0 117.0 140.0 122.0 12:05:51 INFO - PROCESS | 1669 | 12:05:51 INFO - PROCESS | 1669 | [#2] audio-dft Cycles:10 Average:209.60 Median:204.00 stddev:25.48 (12.5%) stddev-sans-first:26.54 12:05:51 INFO - PROCESS | 1669 | Values: 196.0 206.0 198.0 202.0 197.0 227.0 184.0 274.0 215.0 197.0 12:05:51 INFO - PROCESS | 1669 | 12:05:51 INFO - PROCESS | 1669 | [#3] audio-fft Cycles:10 Average:69.70 Median:69.00 stddev:12.37 (17.9%) stddev-sans-first:12.14 12:05:51 INFO - PROCESS | 1669 | Values: 83.0 59.0 60.0 93.0 64.0 61.0 60.0 61.0 82.0 74.0 12:05:51 INFO - PROCESS | 1669 | 12:05:51 INFO - PROCESS | 1669 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:76.50 stddev:8.07 (10.6%) stddev-sans-first:8.55 12:05:51 INFO - PROCESS | 1669 | Values: 79.0 72.0 91.0 72.0 74.0 71.0 87.0 70.0 72.0 88.0 12:05:51 INFO - PROCESS | 1669 | 12:05:51 INFO - PROCESS | 1669 | [#5] imaging-gaussian-blur Cycles:10 Average:117.80 Median:134.50 stddev:16.21 (12.1%) stddev-sans-first:16.36 12:05:51 INFO - PROCESS | 1669 | Values: 132.0 131.0 95.0 98.0 137.0 98.0 127.0 132.0 113.0 115.0 12:05:51 INFO - PROCESS | 1669 | 12:05:51 INFO - PROCESS | 1669 | [#6] imaging-darkroom Cycles:10 Average:111.10 Median:108.00 stddev:15.21 (14.1%) stddev-sans-first:15.78 12:05:51 INFO - PROCESS | 1669 | Values: 102.0 104.0 112.0 101.0 102.0 100.0 103.0 147.0 128.0 112.0 12:05:51 INFO - PROCESS | 1669 | 12:05:51 INFO - PROCESS | 1669 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:87.50 stddev:16.48 (18.8%) stddev-sans-first:17.36 12:05:51 INFO - PROCESS | 1669 | Values: 89.0 95.0 125.0 100.0 87.0 88.0 90.0 81.0 119.0 71.0 12:05:51 INFO - PROCESS | 1669 | 12:05:51 INFO - PROCESS | 1669 | [#8] json-parse-financial Cycles:10 Average:58.30 Median:59.00 stddev:1.83 (3.1%) stddev-sans-first:1.74 12:05:51 INFO - PROCESS | 1669 | Values: 56.0 55.0 57.0 59.0 59.0 61.0 59.0 60.0 58.0 59.0 12:05:51 INFO - PROCESS | 1669 | 12:05:51 INFO - PROCESS | 1669 | [#9] json-stringify-tinderbox Cycles:10 Average:83.10 Median:76.50 stddev:13.55 (17.7%) stddev-sans-first:13.23 12:05:51 INFO - PROCESS | 1669 | Values: 68.0 75.0 71.0 79.0 78.0 71.0 92.0 103.0 106.0 88.0 12:05:51 INFO - PROCESS | 1669 | 12:05:51 INFO - PROCESS | 1669 | [#10] stanford-crypto-aes Cycles:10 Average:86.90 Median:86.50 stddev:4.23 (4.9%) stddev-sans-first:4.10 12:05:51 INFO - PROCESS | 1669 | Values: 82.0 85.0 85.0 85.0 87.0 86.0 88.0 98.0 87.0 86.0 12:05:51 INFO - PROCESS | 1669 | 12:05:51 INFO - PROCESS | 1669 | [#11] stanford-crypto-ccm Cycles:10 Average:164.70 Median:161.50 stddev:20.83 (12.9%) stddev-sans-first:21.30 12:05:51 INFO - PROCESS | 1669 | Values: 149.0 185.0 144.0 154.0 155.0 158.0 164.0 164.0 215.0 159.0 12:05:51 INFO - PROCESS | 1669 | 12:05:51 INFO - PROCESS | 1669 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.90 Median:171.00 stddev:4.01 (2.3%) stddev-sans-first:3.84 12:05:51 INFO - PROCESS | 1669 | Values: 165.0 175.0 166.0 166.0 170.0 173.0 171.0 176.0 171.0 166.0 12:05:51 INFO - PROCESS | 1669 | 12:05:51 INFO - PROCESS | 1669 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.90 Median:79.00 stddev:8.85 (11.2%) stddev-sans-first:9.03 12:05:51 INFO - PROCESS | 1669 | Values: 69.0 68.0 66.0 70.0 68.0 81.0 83.0 87.0 90.0 77.0 12:05:51 INFO - PROCESS | 1669 | -------- Summary: end -------- 12:05:51 INFO - PROCESS | 1669 | 12:05:52 INFO - TEST-INFO | 1669: exit 0 12:05:52 INFO - TEST-OK | kraken | took 60891ms 12:05:52 INFO - SUITE-END | took 1178s 12:05:52 INFO - Completed test suite (00:19:38) 12:05:52 INFO - javascript benchmark 12:05:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7230.937394103206, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3383.8646454141835, 3405.1487128217946, 3407.4888766684994, 3424.366492198945, 3424.7260219182467, 5739.25389699339, 5806.012977793769, 5817.0983497557945, 5821.767129314833, 5837.182794408786, 5824.883502329951, 5880.441358070987, 5888.852778680535, 5924.851878703034, 5932.881342373161, 5275.0768615492425, 5302.893942121155, 5318.069337865877, 5369.301990741198, 5378.273933019051, 5326.387465441466, 5356.250124982505, 5379.220013098098, 5379.757910894, 5453.909218156365, 5195.116830138885, 5234.555062819646, 5370.758315875775, 5382.273393091941, 5388.0570900092325, 5272.815451459181, 5308.177232528964, 5309.548688361494, 5318.867028324285, 5398.352197736258, 5129.820456284012, 5277.973610131963, 5348.171033489815, 5351.678899266057, 5354.357477102733, 5999.400059994009, 6038.57729958903, 6039.969800150971, 6062.454379105901, 6079.696015199266, 5177.45636708144, 6198.783042593533, 6230.595011324294, 6286.339934306878, 6320.62076275425, 5806.59353845231, 5885.793412350501, 6294.214941910314, 6313.021481670348, 6361.332060133665, 5935.910961335583, 5940.227770389908, 5949.494292985145, 5954.523638108941, 5980.55145864062, 6177.104319873593, 6246.843828904313, 6256.374362563707, 6362.427381535684, 6364.172657554488, 5892.145638882424, 6165.938340616626, 6172.9074063889075, 6176.042713379434, 6181.103739957771, 6278.4035516625845, 6290.968545157337, 6331.873362532816, 6345.96827015862, 6346.30190679025, 6270.122182894398, 6287.811365659038, 6352.428281454691, 6372.490200783926, 6373.681315934185, 6175.413335733188, 6252.499800015988, 6268.059791031291, 6278.811635650938, 6325.462335701425, 4982.501749824988, 4999.525045120708, 5002.649814513022, 5008.799648014038, 5014.5737612303365, 4489.842855500042, 4496.482904465963, 4510.548945105464, 4518.2319005769095, 4532.116237333688, 5885.352611212763, 5912.586118971631, 5949.9107513387335, 6013.188219590299, 6022.819315420545, 6264.279607845065, 6293.937060629427, 6303.558750887486, 6347.873042539123, 6362.331955144736, 5894.056950887923, 5919.993601087739, 5925.229720136354, 5927.792527261525, 5927.932593669715, 5424.566034717213, 5438.673679579238, 5450.809221677301, 5476.452354764571, 5477.780888764484, 6260.40526150015, 6388.9041664376, 6408.00675895237, 6412.551121421549, 6417.839554011093, 6236.937630623635, 6267.6866156692895, 6291.465225455888, 6303.401176888188, 6387.776427825004, 6388.744450222125, 6400.583962042639, 6402.455791257796, 6412.9679351602945, 6421.422072013541, 6211.943969525101, 6297.086922396135, 6309.337519560564, 6321.336259692666, 6344.68276586178, 6102.792231004566, 6264.0, 6283.59156654816, 6286.905696414649, 6292.370762923671, 6217.253929528486, 6272.466840318441, 6276.874462510816, 6293.056041593798, 6304.84237894038, 6203.937960620336, 6231.0, 6252.0, 6301.779437719658, 6338.334474880256, 5969.343372228965, 6031.366706495754, 6041.0, 6056.788012419544, 6080.391960803884, 5141.28022076917, 5810.215620891126, 6208.0, 6291.653959032297, 6300.464460520907, 6109.389061093855, 6150.323464418821, 6163.322034576283, 6173.32093469709, 6183.227096612924], "value": 5798.239022889714, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [445.6234481862826, 464.3800526297393, 503.80855274995497, 514.979400823967, 521.4160140642479, 28874.278143046435, 30240.0, 37244.0, 38310.808445957766, 40037.0, 30746.231344216405, 32139.0, 32448.18879528013, 33495.66504334956, 39956.800215998956, 9052.230560402373, 9163.587638556248, 9221.95389023054, 9245.815083698322, 9254.352195346328, 9176.485879829854, 9240.768980775501, 9258.259339252843, 9277.118673725985, 9332.72001839946, 9093.0, 9106.908930910706, 9126.361154719172, 9151.588178531982, 9156.816863662723, 9199.954000229956, 9231.892172939222, 9262.999999999965, 9309.860352094724, 9311.487868167249, 9015.27877769777, 9084.137006984325, 9113.908860911371, 9142.948560915513, 9166.26669866409, 38358.616413835785, 38538.0, 38592.8422147336, 38701.80649096765, 38735.0, 3210.807351558914, 3248.610166779978, 3357.0096821437733, 3371.662833716609, 3832.310184166849, 28073.43853122926, 28335.008274710493, 30074.0, 30140.84929575338, 30296.24259393511, 3138.6704396038544, 3140.0736782649174, 3157.778955473118, 3184.85668144934, 3248.8538015789345, 493703.0, 493892.0, 497483.0, 498236.0, 498375.0, 54661.1800822988, 54929.72535137379, 55040.72479637576, 55068.62328441821, 55104.72447637736, 57800.0, 58025.70987145122, 58056.0, 58127.0, 58363.0, 15105.244737763069, 15292.235388230543, 15460.304286307142, 15480.690386192438, 15501.534953951399, 10084.394936303845, 11954.521819127138, 11961.102917281065, 12026.639200823814, 12028.0, 15119.395224191128, 15301.46444874424, 15382.384704611914, 15504.0, 15728.764068538982, 3115.9844200779303, 3233.417984762765, 5303.541526080282, 5438.945610543923, 6473.190851143607, 827.735124760071, 966.6423423333412, 971.3686104032436, 996.0089710737863, 1015.669472990385, 27078.323041924108, 27714.722852771618, 28082.43835123286, 28277.293067673472, 28317.433651326857, 955.1308309438377, 1014.9543270552842, 1022.7749895023084, 1027.6146445083093, 1034.5758239121924, 14146.78779818283, 14493.130412175304, 14868.405263789327, 15343.539693809204, 15608.531744047697, 653733.0, 659943.0, 661163.0, 661895.0, 664683.0, 54375.0, 54799.72600136974, 54888.72555637196, 54893.0, 55119.448805512235, 1312.7637025335341, 1345.1055048392905, 1346.3873937358478, 1347.3802051056427, 1359.16411406984, 28144.85927570349, 28788.712112879442, 28830.567541487315, 28878.422431550833, 29122.56316155302, 47491.52508474871, 51710.96578068493, 53302.0, 53525.464745352045, 56668.7166564181, 534090.0, 535171.0, 539705.0, 539840.0, 540221.0, 40449.79775101106, 40801.795991019855, 41049.38425923673, 41286.1742765137, 41305.0, 44880.0, 44888.77555612201, 45146.77426612846, 45263.0, 45430.86422839455, 29855.29473862467, 30209.942652007074, 32320.0, 36984.44523332206, 37213.62786372102, 32219.0, 33138.17154571155, 35621.643783561834, 37354.252914942095, 38087.428688570246, 36842.63157368392, 37495.0, 37653.81173094117, 38231.42652860265, 38795.806020969714, 24510.0, 29622.0, 32002.679973200902, 33879.0, 34040.82979585086, 25037.998480060593, 25066.74933250717, 25179.992800288517, 25185.37036574027, 25416.36783807516], "value": 18938.83867064677, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.1494929441166, 364.90147660131765, 373.6002477349236, 377.18527979564146, 391.75319548684325, 18744.531386715324, 19134.712979305303, 19440.513987150294, 19849.50376240593, 19957.0, 11433.199676022681, 11824.0, 11826.23129496582, 11829.994450471699, 11836.585719499819, 4737.005228901918, 5042.16804227303, 5353.116735738598, 5402.270693456372, 5469.890602187954, 3792.848286068554, 4035.737677050997, 5213.635045546814, 5406.40529541752, 5412.972935135319, 5989.490893274055, 6085.630733085065, 6088.543359248074, 6094.908576371358, 6105.664188469633, 6072.240969878765, 6239.844003899916, 6542.803715888507, 6570.342965703439, 6573.0, 5713.74288157034, 5823.5341172706085, 5831.446012628793, 5844.0, 6922.7923162305215, 26747.33131671704, 27158.864205678845, 27283.590746138823, 27995.580066299022, 28423.0, 2174.913003479859, 2505.837120587156, 2538.352720056392, 3147.9370412591848, 3194.7444204463586, 13531.864681353258, 15514.61213469661, 16089.275982580812, 16223.51329460118, 17067.829321706748, 1463.4585203474676, 1930.0433812531373, 1930.237556165321, 2044.8875311857842, 2051.4050925231663, 110983.0, 112292.99999999919, 116907.41546292299, 131371.0, 135590.96613550806, 19493.0, 19953.40372770175, 21892.45268868291, 21960.89019554892, 22303.0, 35144.47283290753, 35334.0, 35872.28255434876, 36033.45949810755, 36091.27817443689, 8809.559522023874, 8879.600417981206, 10600.522976465923, 10608.946955265174, 10836.0, 8703.260222881, 8729.0, 8790.516521591373, 8968.551572421353, 8981.910180898238, 8255.42212045151, 9451.960284368713, 9555.23558115349, 9635.56639951204, 9760.267979901535, 1572.1196130167143, 1657.8756593255555, 3128.749700023993, 3828.942565861514, 3846.74996125251, 786.8465649198466, 799.4403917257827, 962.7737481691797, 998.7802683409639, 1010.3079390617451, 14854.777178342334, 16426.83573164277, 16901.91549042247, 17595.56011099733, 19522.8047719521, 823.8434697407473, 892.4199270474114, 927.6567669962158, 933.8272419602396, 989.3322007644811, 9119.179273865384, 9915.851262231072, 10145.594176233015, 10663.040326370663, 10677.0, 528269.0, 595418.0, 604233.0, 604611.0, 617143.0, 82171.5891420551, 126230.36884815517, 129325.0, 131896.3405182987, 133245.0013249802, 924.0251534630894, 936.0826390137568, 979.6228452046014, 991.0436428846054, 993.0169132558859, 14400.567982960529, 17661.911690441466, 18080.0, 18292.542686432946, 18438.078096094872, 46056.61830145101, 51733.7413312931, 52516.47483525116, 52868.206976896145, 53819.6545086376, 78537.0, 80002.19997800181, 80113.19886801057, 80182.0, 81019.0, 6921.653917304014, 7340.706371745284, 7386.372158366599, 7421.554706717614, 7505.249475052451, 12020.579279725167, 12566.560170393992, 12675.556355527513, 12684.302363370103, 12749.808752868528, 10836.566537338418, 10888.074513666426, 10899.618513351994, 11141.72145696364, 11225.550977960791, 10808.135349172204, 10980.231383803057, 10985.285956413127, 11010.779784404427, 11051.005409513182, 5720.141978703228, 8235.876461852957, 9331.60025996106, 11054.723631909266, 11212.607558735404, 6857.27998560144, 7364.189939106587, 7415.888761668687, 7460.552366858006, 7566.546007239584, 10318.329308594917, 10887.78224435491, 11051.0, 11139.665810025712, 11337.0], "value": 10077.469476985749, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1433.2833583208396, 1437.7052704195637, 1574.6614477887251, 1709.7862767154106, 1710.1962077823418, 7643.197464266255, 7898.644560994755, 8227.670893164273, 8636.91363086369, 8664.43681160725, 8085.0, 8410.285125764318, 8415.49507029577, 8501.489910605367, 8509.957450212756, 2303.297494264253, 2337.649352597107, 2342.437814924423, 2342.8945697443655, 2350.588646986779, 2326.697529321186, 2362.2204672458133, 2363.5745565798147, 2368.9407764805846, 2379.95240095198, 2293.6100862853273, 2295.2999335202758, 2317.8609283443047, 2321.7213934327906, 2328.75548067453, 1982.742243508342, 2300.6088964875926, 2318.710161229846, 2323.0, 2337.0, 2328.7438381777993, 2358.4339758457845, 2362.4093976505874, 2366.0299277296226, 2366.1245339224424, 8453.408261421702, 8522.82954340916, 8568.143185681445, 8585.570721463964, 8677.826443471158, 5130.51260130287, 5277.947220527784, 5295.020421222048, 5297.549708274802, 5312.867178320535, 3164.430402527544, 3258.266889949766, 3455.8963231103107, 3613.927721445583, 3622.2393297407575, 999.7650552120245, 1016.7305663999047, 1031.2111234905303, 1092.8852470490642, 1097.544519024604, 11021.118310535137, 11093.500792464358, 11094.55621775128, 11134.16493762971, 11146.554137834477, 4505.346724724896, 4583.679142459996, 4678.157931572349, 4685.0, 4759.286107083965, 9446.527673616292, 9502.90497095034, 9519.762005949768, 9525.333226674205, 9556.331056825955, 5192.143296356135, 5224.686518808808, 5242.449542798027, 5246.08193566132, 5256.553192978563, 4231.640310573574, 4249.383839343278, 4586.289125185602, 4645.74448405341, 4849.636277279147, 3380.7829181494644, 3698.8705395311035, 3765.9435108473394, 3855.9036024099623, 3883.0486530800135, 1258.5783762439685, 1275.6236910111538, 1278.3288773393856, 1282.8396450443695, 1288.890444312244, 866.6966561703354, 881.4931414436596, 884.6284560484612, 886.2068448738343, 901.0628945896326, 5641.774329026793, 5710.486056254874, 5745.454181852718, 5778.624389414675, 5871.588988770745, 829.9045260256524, 836.0928392693874, 929.0894922975522, 938.5448057692014, 965.3821554205314, 3617.2403795203304, 3675.3016926783826, 3703.4259689748133, 3736.0, 3785.8674946376746, 8050.919490805134, 9625.374350667184, 10082.395056296498, 10170.745731356626, 10363.63727269542, 10088.394696318097, 10252.38485690846, 10326.896731032744, 10329.535170917326, 10342.534585943651, 7822.0, 8056.637451314705, 8094.959525202454, 8122.0, 8123.431359804875, 5598.216249725123, 5634.464725851038, 5692.1461780733225, 5788.479036886701, 5813.593048486566, 8391.454555453876, 9447.905520944703, 9524.85712714296, 9611.038896110333, 9636.662716804878, 9400.435973841319, 9507.762305942406, 9663.46850923206, 9768.853467197856, 9843.360181588174, 7122.750703725552, 7246.60143692102, 7267.890981635174, 7294.890576641248, 7326.267373262631, 7798.883016754639, 7838.608069596383, 7850.646720897459, 7909.9209007909185, 7924.960375198087, 7024.929750702632, 7153.928460715326, 7218.530795498275, 7289.562626242442, 7291.41668666516, 6928.168619765459, 7040.929590704232, 7054.435645148478, 7147.892781608384, 7158.713651453883, 6652.667366631552, 6705.731770729311, 6742.9662851685425, 6764.763233286812, 6769.221539522821, 4025.255327764373, 4703.3885594872445, 4717.646176536705, 4789.688670236424, 4790.113828941657, 4164.271252530855, 4711.505291944297, 4735.928961065656, 4739.312799644102, 4820.156472617348], "value": 4430.982785759142, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [265.9946801063979, 294.8511001944018, 303.6326045484963, 307.17370273963036, 307.41590977143437, 3987.7806720630356, 5143.717095559743, 5347.417230455594, 5396.352437707471, 5426.864328391792, 4882.780274887629, 5161.922571161426, 5296.987144884948, 5322.467753224676, 5369.731513424323, 4043.919121617573, 4068.7965601719798, 4084.8319038252157, 4102.979485102585, 4103.548609652936, 3459.6540345965327, 3943.5464921533962, 4085.774267719687, 4121.258173528764, 4126.66986641068, 3362.6469220731838, 4039.1113954930015, 4044.4539987101657, 4064.2074795414906, 4098.139390727936, 4031.294523458412, 4052.6555242804247, 4073.714839961204, 4080.2451546464, 4118.876433706993, 3145.35520218357, 3580.2481478889463, 3586.659267369596, 3595.0, 3610.85154333174, 4911.263310503453, 5107.591392688576, 5163.767630456601, 5176.430592634807, 5190.299309593194, 4258.61672449481, 4426.269665505188, 4543.864033991502, 4659.58063774262, 4730.86459249781, 5317.361916570037, 5617.269754931874, 5632.268349850319, 5674.432556744334, 5707.71461426927, 3564.4475106358555, 3840.2895464339185, 3844.6347596978244, 3854.903627409309, 3987.760734355919, 5888.0, 5944.910826337608, 5946.405359464019, 5948.553858460633, 5970.910436343502, 3553.916055603066, 3890.513685789276, 3937.448757173998, 3951.920961580752, 3952.01199700075, 3762.6454476388476, 3898.602359548857, 3957.3866050762176, 3971.841126354971, 3987.0, 4202.495700515958, 4563.794629241692, 4596.851156957495, 4598.356230127785, 4599.9080018399445, 5095.974520127375, 5667.234923285386, 5692.516136128475, 5693.857653558694, 5714.771409143671, 1227.6623928419756, 1251.5119103549623, 1262.0282382565374, 1273.3951373097705, 1273.5669872243482, 769.4959801330137, 831.3681601982537, 859.4928991894812, 867.2454964181203, 867.3798234262406, 4281.871543853689, 4843.418789745253, 5078.765343248959, 5082.415522214918, 5123.846284611468, 5053.84838454847, 5092.460739785887, 5315.893682126336, 5506.669599824023, 5565.877534711865, 4554.4990051094355, 4662.417197850269, 4820.975895120572, 5043.8903441242865, 5054.523283591014, 2872.314386049051, 2887.2898325651436, 2893.3923875985856, 2896.7392934635564, 2915.927101822429, 5155.716435596079, 5697.686627235542, 5706.400827913092, 5769.134629805562, 5799.5650326225705, 4500.8649740507835, 5017.058197675287, 5648.802291919846, 5649.717514124307, 5649.841782434591, 4810.542998415145, 4950.727709975986, 5553.944750497392, 5701.543876489954, 5728.541716662574, 4527.864164075083, 5364.658835291178, 5471.632091977006, 5551.389347171727, 5552.529124539541, 4681.133990211685, 5308.946910531, 5359.866003349792, 5498.2268617951795, 5770.567207459373, 5248.9737551312, 5864.501517370901, 6127.234095738033, 6131.294901086432, 6162.815115546541, 3706.388445906503, 3935.7244992850224, 3936.4488971543433, 3938.133610605662, 3968.0079980005, 4820.903581928272, 5634.267545219259, 5867.618604790675, 5874.5887787854435, 5904.586678932434, 4558.723557403932, 5222.321098257202, 5581.49766521015, 5609.074502707008, 5654.491095801398, 4417.293233082692, 5690.3456102548735, 5729.28383952006, 5736.053551164011, 5738.9139162913425, 4690.859274221779, 5330.280412144466, 5580.665160090408, 5588.832335029955, 5613.46672066153, 5602.13166959122, 5664.3856500897045, 5671.716414179192, 5680.829575112753, 5691.288588926384, 5593.692346920959, 5610.635308704921, 5614.101743720985, 5615.298087739033, 5648.661080335193], "value": 4067.5042349231217, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [579.4553120067137, 666.6366830077608, 714.2928500784225, 720.8522252938145, 757.333865495909, 13705.725885482298, 14282.928585357085, 14306.642333941656, 14373.640658983531, 14467.974483572296, 12739.426725797339, 12903.741925161492, 12910.225386476817, 12911.418986145622, 12916.741665166692, 783.1307248953657, 785.0344076785557, 798.8402319536087, 813.0203105258179, 819.5001049359886, 602.6715440085164, 693.1647364925278, 700.5201437015635, 782.3858271257064, 804.4409135650695, 818.2226884459787, 820.7783898347443, 843.5107637570195, 846.4032856835915, 847.8304339132168, 806.2824086562964, 832.097174565597, 836.85773418519, 843.0599881132555, 845.247729520727, 550.1637510983282, 617.9340637400493, 630.9400606942334, 631.3307893632764, 634.330781026022, 14145.717085658333, 14150.575482735534, 14178.574642760734, 14199.71600567983, 14206.360713767905, 11592.420378981102, 12595.685107872376, 13181.670458238525, 13403.731925361439, 13438.19370837743, 11368.204225704205, 11450.885491145065, 11501.367424791633, 11615.941920290428, 11978.940105299505, 2937.985310073457, 3220.5652236947944, 3588.3361578108133, 3589.335972845032, 3618.222082252328, 89336.0, 90083.54958225232, 92761.0, 92872.0, 93700.53149734208, 10041.497925103715, 10146.543405546769, 10318.43248621333, 10334.483275836179, 10468.63359782404, 16229.675406491804, 16692.41576544815, 19107.23571057142, 19820.70268945967, 20083.0, 7578.658960346797, 7607.389979251578, 7796.259355361231, 7877.4091943104495, 7947.284744373034, 4267.914641707149, 4304.612584867377, 4309.978450107729, 4461.442319710036, 4496.033352829158, 3415.2315728960784, 3497.1257185703575, 3507.7544571879725, 3510.420780571177, 3517.314123745845, 627.6171535363425, 639.9424051835357, 646.8253571535658, 652.3509108437136, 661.8841702702008, 533.1522878623008, 581.1312088427743, 602.9276486821609, 636.9585976911578, 638.5211091681239, 8771.254443372385, 9038.819223615623, 9119.863202051974, 9287.024862389488, 9320.161185493338, 601.2004034633926, 628.9590727346249, 650.768977013163, 661.0744957060155, 663.8041777675597, 3586.6951309139017, 3611.494390785292, 4512.323151527232, 4524.524924882906, 4622.583967442946, 89220.0, 95198.0480195203, 98193.50903245439, 99945.50027249963, 121845.78154218344, 98169.0, 98369.5244571332, 101231.48152777714, 102757.0, 104931.0, 89976.6503502448, 92004.0, 93206.0, 94697.52651236838, 95775.52112239394, 8760.649574017223, 8796.296296296408, 8798.34012449056, 8799.736007919773, 8939.821203576023, 38745.80627096847, 40977.975550611474, 52760.0, 52924.522678957954, 55064.44935550593, 74757.0, 76138.23861761311, 76738.0, 78394.21605784097, 79357.60321198357, 10892.564297428331, 11119.388433635904, 11316.0, 11589.59436419755, 11948.940255299016, 13611.251381173734, 13784.931075344559, 14427.49503767363, 14525.564233073024, 14613.0, 12667.366631668196, 12999.285039322927, 13246.47014119463, 13277.653917007383, 14125.223112728898, 13103.01727370432, 13204.207747535178, 13757.17456952586, 13985.860141398864, 14089.0, 12186.451609677762, 12858.614241572768, 12869.678258043623, 13202.405891735087, 13291.003174762125, 10698.0, 11091.667249982513, 11338.773224535298, 11452.0, 11588.94205528967, 9365.812683746151, 11188.0, 11403.714907127056, 11443.542258309575, 11486.540538378371], "value": 7166.261341103255, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1569.9000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 120.0, 119.0, 119.0, 119.0, 120.0, 118.0, 119.0, 132.0, 177.0], "value": 125.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 117.0, 118.0, 148.0, 116.0, 118.0, 116.0, 117.0, 140.0, 122.0], "value": 125.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 206.0, 198.0, 202.0, 197.0, 227.0, 184.0, 274.0, 215.0, 197.0], "value": 209.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 60.0, 93.0, 64.0, 61.0, 60.0, 61.0, 82.0, 74.0], "value": 69.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 72.0, 91.0, 72.0, 74.0, 71.0, 87.0, 70.0, 72.0, 88.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 131.0, 95.0, 98.0, 137.0, 98.0, 127.0, 132.0, 113.0, 115.0], "value": 117.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 104.0, 112.0, 101.0, 102.0, 100.0, 103.0, 147.0, 128.0, 112.0], "value": 111.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 95.0, 125.0, 100.0, 87.0, 88.0, 90.0, 81.0, 119.0, 71.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 57.0, 59.0, 59.0, 61.0, 59.0, 60.0, 58.0, 59.0], "value": 58.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 75.0, 71.0, 79.0, 78.0, 71.0, 92.0, 103.0, 106.0, 88.0], "value": 83.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 85.0, 85.0, 87.0, 86.0, 88.0, 98.0, 87.0, 86.0], "value": 86.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 185.0, 144.0, 154.0, 155.0, 158.0, 164.0, 164.0, 215.0, 159.0], "value": 164.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 175.0, 166.0, 166.0, 170.0, 173.0, 171.0, 176.0, 171.0, 166.0], "value": 169.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 66.0, 70.0, 68.0, 81.0, 83.0, 87.0, 90.0, 77.0], "value": 75.9, "unit": "score"}]}]} 12:05:53 INFO - Return code: 0 12:05:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:05:53 ERROR - # TBPL SUCCESS # 12:05:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:05:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:05:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:05:53 INFO - 2016-11-14 12:05:53.097 screenresolution[1674:10728] starting screenresolution argv=screenresolution get 12:05:53 INFO - 2016-11-14 12:05:53.100 screenresolution[1674:10728] Display 0: 1600x1200x32@60 12:05:53 INFO - 2016-11-14 12:05:53.106 screenresolution[1675:10731] starting screenresolution argv=screenresolution list 12:05:53 INFO - Available Modes on Display 0 12:05:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:05:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:05:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:05:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:05:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:05:53 INFO - Intel Iris: 12:05:53 INFO - Chipset Model: Intel Iris 12:05:53 INFO - Type: GPU 12:05:53 INFO - Bus: Built-In 12:05:53 INFO - VRAM (Dynamic, Max): 1536 MB 12:05:53 INFO - Vendor: Intel (0x8086) 12:05:53 INFO - Device ID: 0x0a2e 12:05:53 INFO - Revision ID: 0x0009 12:05:53 INFO - Displays: 12:05:53 INFO - Display: 12:05:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:05:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:05:53 INFO - Display Serial Number: 1600x1200 60 12:05:53 INFO - Main Display: Yes 12:05:53 INFO - Mirror: Off 12:05:53 INFO - Online: Yes 12:05:53 INFO - Rotation: Supported 12:05:53 INFO - Return code: 0 12:05:53 INFO - Running post-action listener: _resource_record_post_action 12:05:53 INFO - [mozharness: 2016-11-14 20:05:53.348059Z] Finished run-tests step (success) 12:05:53 INFO - Running post-run listener: _resource_record_post_run 12:05:53 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 36656128; Write bytes: 657996288; Read time: 375; Write time: 1972 12:05:53 INFO - TinderboxPrint: CPU usage
54.4% 12:05:53 INFO - TinderboxPrint: I/O read bytes / time
36,656,128 / 375 12:05:53 INFO - TinderboxPrint: I/O write bytes / time
657,996,288 / 1,972 12:05:53 INFO - TinderboxPrint: CPU idle
2,212.8 (45.6%) 12:05:53 INFO - TinderboxPrint: CPU system
983.1 (20.3%) 12:05:53 INFO - TinderboxPrint: CPU user
1,654.0 (34.1%) 12:05:53 INFO - TinderboxPrint: Swap in / out
389,632,000 / 0 12:05:53 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 350593536; Write bytes: 346420736; Read time: 28767; Write time: 702 12:05:53 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 32220160; Write bytes: 300614656; Read time: 317; Write time: 1236 12:05:53 INFO - Running post-run listener: _upload_blobber_files 12:05:53 INFO - Blob upload gear active. 12:05:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:05:53 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:05:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:05:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:05:54 INFO - (blobuploader) - INFO - Open directory for files ... 12:05:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:05:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:05:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:05:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:05:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:05:55 INFO - (blobuploader) - INFO - Done attempting. 12:05:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:05:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:05:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:05:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:05:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:05:56 INFO - (blobuploader) - INFO - Done attempting. 12:05:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:05:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:05:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:05:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:05:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:05:59 INFO - (blobuploader) - INFO - Done attempting. 12:05:59 INFO - (blobuploader) - INFO - Iteration through files over. 12:05:59 INFO - Return code: 0 12:05:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:05:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:05:59 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/27ce1e2c52b3c43e4846fcaed0e47ef5e8fe66ec49bdea20fc288a0b498261503757c61f3c764309718d2baf37707a3fa45826b2553de2cf4b9c015372f91859", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/77d8e4afb83f0cf7c837642b123939dff8d1f4ac1456bc7b54414badfb010b5a9ef184f2b470f1e467bb72479701c78436b94313ec1b32a5879d917c71cfc6c2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5aa347ba1163643a4771bd0e27e78ead49aa446111bb08295512c9d8b81fe2a51075da0b7183e4f714afeeb485b6a77b98a0b7a8ae838568e059776c242adbad"} 12:05:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:05:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:05:59 INFO - Contents: 12:05:59 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/27ce1e2c52b3c43e4846fcaed0e47ef5e8fe66ec49bdea20fc288a0b498261503757c61f3c764309718d2baf37707a3fa45826b2553de2cf4b9c015372f91859", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/77d8e4afb83f0cf7c837642b123939dff8d1f4ac1456bc7b54414badfb010b5a9ef184f2b470f1e467bb72479701c78436b94313ec1b32a5879d917c71cfc6c2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5aa347ba1163643a4771bd0e27e78ead49aa446111bb08295512c9d8b81fe2a51075da0b7183e4f714afeeb485b6a77b98a0b7a8ae838568e059776c242adbad"} 12:05:59 INFO - Running post-run listener: copy_logs_to_upload_dir 12:05:59 INFO - Copying logs to upload dir... 12:05:59 INFO - mkdir: /builds/slave/test/build/upload/logs 12:05:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.222208 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-14 12:05:59.559334) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 12:05:59.562890) ========= 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.X6VVUHoU3h/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.ZGbks5BoIX/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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/27ce1e2c52b3c43e4846fcaed0e47ef5e8fe66ec49bdea20fc288a0b498261503757c61f3c764309718d2baf37707a3fa45826b2553de2cf4b9c015372f91859", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/77d8e4afb83f0cf7c837642b123939dff8d1f4ac1456bc7b54414badfb010b5a9ef184f2b470f1e467bb72479701c78436b94313ec1b32a5879d917c71cfc6c2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5aa347ba1163643a4771bd0e27e78ead49aa446111bb08295512c9d8b81fe2a51075da0b7183e4f714afeeb485b6a77b98a0b7a8ae838568e059776c242adbad"} build_url:https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011195 build_url: 'https://queue.taskcluster.net/v1/task/eZyhYxOYSvy1ST5dDi66KQ/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/27ce1e2c52b3c43e4846fcaed0e47ef5e8fe66ec49bdea20fc288a0b498261503757c61f3c764309718d2baf37707a3fa45826b2553de2cf4b9c015372f91859", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/77d8e4afb83f0cf7c837642b123939dff8d1f4ac1456bc7b54414badfb010b5a9ef184f2b470f1e467bb72479701c78436b94313ec1b32a5879d917c71cfc6c2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5aa347ba1163643a4771bd0e27e78ead49aa446111bb08295512c9d8b81fe2a51075da0b7183e4f714afeeb485b6a77b98a0b7a8ae838568e059776c242adbad"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 12:05:59.595979) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 12:05:59.596274) ========= 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.X6VVUHoU3h/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.ZGbks5BoIX/Listeners TMPDIR=/var/folders/q3/qtb6pr455yg4tfb08p3q6f5h00000w/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.005151 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 12:05:59.646948) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 12:05:59.647659) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 12:05:59.648407) ========= ========= Total master_lag: 0.23 =========