builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0220 starttime: 1482338742.41 results: success (0) buildid: 20161221062253 builduid: be73f60bd9d147e4ab9638f222b0de82 revision: 6c1dd7d65949799726e1ab823d66070927f622e8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 08:45:42.410387) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 08:45:42.410771) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 08:45:42.411039) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 08:45:42.443382) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-12-21 08:45:42.443640) ========= 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.cXMxEeesbM/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.8LwyT9la9s/Listeners TMPDIR=/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-21 08:45:42-- 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% 30.6M=0s 2016-12-21 08:45:42 (30.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.104121 ========= master_lag: 3.53 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-12-21 08:45:46.072824) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-12-21 08:45:46.077170) ========= 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.cXMxEeesbM/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.8LwyT9la9s/Listeners TMPDIR=/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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.020170 ========= master_lag: 0.99 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-12-21 08:45:47.085100) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-21 08:45:47.085462) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 6c1dd7d65949799726e1ab823d66070927f622e8 --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 6c1dd7d65949799726e1ab823d66070927f622e8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cXMxEeesbM/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.8LwyT9la9s/Listeners TMPDIR=/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-21 08:45:47,234 truncating revision to first 12 chars 2016-12-21 08:45:47,234 Setting DEBUG logging. 2016-12-21 08:45:47,234 attempt 1/10 2016-12-21 08:45:47,234 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/6c1dd7d65949?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-21 08:45:48,223 unpacking tar archive at: mozilla-aurora-6c1dd7d65949/testing/mozharness/ program finished with exit code 0 elapsedTime=1.233480 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-21 08:45:48.343009) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 08:45:48.346345) ========= script_repo_revision: 6c1dd7d65949799726e1ab823d66070927f622e8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 08:45:48.346726) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 08:45:48.346981) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 08:45:48.366430) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-21 08:45:48.366726) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.cXMxEeesbM/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.8LwyT9la9s/Listeners TMPDIR=/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 08:45:48 INFO - MultiFileLogger online at 20161221 08:45:48 in /builds/slave/test 08:45:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:45:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:45:48 INFO - {'append_to_log': False, 08:45:48 INFO - 'base_work_dir': '/builds/slave/test', 08:45:48 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:45:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:45:48 INFO - 'branch': 'Mozilla-Aurora', 08:45:48 INFO - 'buildbot_json_path': 'buildprops.json', 08:45:48 INFO - 'config_files': ('talos/mac_config.py',), 08:45:48 INFO - 'default_actions': ('clobber', 08:45:48 INFO - 'read-buildbot-config', 08:45:48 INFO - 'download-and-extract', 08:45:48 INFO - 'populate-webroot', 08:45:48 INFO - 'create-virtualenv', 08:45:48 INFO - 'install', 08:45:48 INFO - 'run-tests'), 08:45:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:45:48 INFO - 'download_minidump_stackwalk': True, 08:45:48 INFO - 'download_symbols': 'ondemand', 08:45:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:45:48 INFO - 'tooltool.py': '/tools/tooltool.py', 08:45:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:45:48 INFO - '/tools/misc-python/virtualenv.py')}, 08:45:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:45:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:45:48 INFO - 'installer_path': 'installer.exe', 08:45:48 INFO - 'log_level': 'info', 08:45:48 INFO - 'log_name': 'talos', 08:45:48 INFO - 'log_to_console': True, 08:45:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:45:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:45:48 INFO - 'opt_config_files': (), 08:45:48 INFO - 'pip_index': False, 08:45:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:45:48 INFO - 'cmd': ('bash', 08:45:48 INFO - '-c', 08:45:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:45:48 INFO - 'enabled': True, 08:45:48 INFO - 'halt_on_failure': False, 08:45:48 INFO - 'name': 'check_screen_resolution'},), 08:45:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:45:48 INFO - 'cmd': ('bash', 08:45:48 INFO - '-c', 08:45:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:45:48 INFO - 'enabled': True, 08:45:48 INFO - 'halt_on_failure': False, 08:45:48 INFO - 'name': 'check_screen_resolution'},), 08:45:48 INFO - 'run_cmd_checks_enabled': True, 08:45:48 INFO - 'sps_profile': False, 08:45:48 INFO - 'sps_profile_interval': 0, 08:45:48 INFO - 'suite': 'dromaeojs', 08:45:48 INFO - 'system_bits': '32', 08:45:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:45:48 INFO - 'title': 't-yosemite-r7-0220', 08:45:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:45:48 INFO - 'use_talos_json': True, 08:45:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:45:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:45:48 INFO - 'work_dir': 'build'} 08:45:48 INFO - [mozharness: 2016-12-21 16:45:48.631098Z] Running clobber step. 08:45:48 INFO - Running pre-action listener: _resource_record_pre_action 08:45:48 INFO - Running main action method: clobber 08:45:48 INFO - rmtree: /builds/slave/test/build 08:45:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:45:49 INFO - Running post-action listener: _resource_record_post_action 08:45:49 INFO - [mozharness: 2016-12-21 16:45:49.383328Z] Finished clobber step (success) 08:45:49 INFO - [mozharness: 2016-12-21 16:45:49.383609Z] Running read-buildbot-config step. 08:45:49 INFO - Running pre-action listener: _resource_record_pre_action 08:45:49 INFO - Running main action method: read_buildbot_config 08:45:49 INFO - Using buildbot properties: 08:45:49 INFO - { 08:45:49 INFO - "project": "", 08:45:49 INFO - "product": "firefox", 08:45:49 INFO - "script_repo_revision": "production", 08:45:49 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 08:45:49 INFO - "repository": "", 08:45:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 08:45:49 INFO - "buildid": "20161221062253", 08:45:49 INFO - "pgo_build": "False", 08:45:49 INFO - "basedir": "/builds/slave/test", 08:45:49 INFO - "buildnumber": 5, 08:45:49 INFO - "slavename": "t-yosemite-r7-0220", 08:45:49 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 08:45:49 INFO - "platform": "yosemite_r7", 08:45:49 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:45:49 INFO - "branch": "mozilla-aurora", 08:45:49 INFO - "repo_path": "releases/mozilla-aurora", 08:45:49 INFO - "revision": "6c1dd7d65949799726e1ab823d66070927f622e8", 08:45:49 INFO - "stage_platform": "macosx64", 08:45:49 INFO - "builduid": "be73f60bd9d147e4ab9638f222b0de82", 08:45:49 INFO - "slavebuilddir": "test" 08:45:49 INFO - } 08:45:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/NGVcbQd6QmGqqrY3aMYZ2g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 08:45:49 INFO - Running post-action listener: _resource_record_post_action 08:45:49 INFO - [mozharness: 2016-12-21 16:45:49.389421Z] Finished read-buildbot-config step (success) 08:45:49 INFO - [mozharness: 2016-12-21 16:45:49.389793Z] Running download-and-extract step. 08:45:49 INFO - Running pre-action listener: _resource_record_pre_action 08:45:49 INFO - Running main action method: download_and_extract 08:45:49 INFO - mkdir: /builds/slave/test/build/tests 08:45:49 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')]} 08:45:49 INFO - trying https://queue.taskcluster.net/v1/task/NGVcbQd6QmGqqrY3aMYZ2g/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 08:45:49 INFO - Downloading https://queue.taskcluster.net/v1/task/NGVcbQd6QmGqqrY3aMYZ2g/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 08:45:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NGVcbQd6QmGqqrY3aMYZ2g/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 08:45:50 INFO - Downloaded 1199 bytes. 08:45:50 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:45:50 INFO - Using the following test package requirements: 08:45:50 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:45:50 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:45:50 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 08:45:50 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:45:50 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 08:45:50 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:45:50 INFO - u'jsshell-mac.zip'], 08:45:50 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:45:50 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 08:45:50 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:45:50 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:45:50 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 08:45:50 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:45:50 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 08:45:50 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:45:50 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 08:45:50 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:45:50 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 08:45:50 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:45:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NGVcbQd6QmGqqrY3aMYZ2g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:45:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NGVcbQd6QmGqqrY3aMYZ2g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:45:50 INFO - Fetch https://queue.taskcluster.net/v1/task/NGVcbQd6QmGqqrY3aMYZ2g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:45:51 INFO - Http code: 200 08:45:51 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 08:45:51 INFO - x-amz-cf-id: UpE5GNhUI4lKAz_VpGFYa7sa7epKQOXrQYs_qFNqajeWTJXl2XP4vw== 08:45:51 INFO - x-amz-version-id: jNFS1m6MQNQ0oi0.nLQmtSdA1axq9CNE 08:45:52 INFO - Expected file size: 16267640 08:45:52 INFO - Obtained file size: 16267640 08:45:58 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 08:45:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NGVcbQd6QmGqqrY3aMYZ2g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:45:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NGVcbQd6QmGqqrY3aMYZ2g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:45:58 INFO - Fetch https://queue.taskcluster.net/v1/task/NGVcbQd6QmGqqrY3aMYZ2g/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:45:59 INFO - Http code: 200 08:45:59 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 08:45:59 INFO - x-amz-cf-id: HqznVtRwhiXWiTXoYejRtmsAlT5KTd1_K_fO4D8Hn5auR9Tw7dOs3g== 08:45:59 INFO - x-amz-version-id: jNFS1m6MQNQ0oi0.nLQmtSdA1axq9CNE 08:46:00 INFO - Expected file size: 16267640 08:46:00 INFO - Obtained file size: 16267640 08:46:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NGVcbQd6QmGqqrY3aMYZ2g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 08:46:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NGVcbQd6QmGqqrY3aMYZ2g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:46:07 INFO - Fetch https://queue.taskcluster.net/v1/task/NGVcbQd6QmGqqrY3aMYZ2g/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 08:46:08 INFO - Http code: 200 08:46:08 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 08:46:08 INFO - x-amz-cf-id: J9iJpSnrN06utKZ0F8uYYkIOf5iNFd3KEu9rMNKbbDF9-eVX2NxdEg== 08:46:08 INFO - x-amz-version-id: xMv54d2rDZevaiyDLXXsi3f8.Mn42Uxz 08:46:08 INFO - Expected file size: 14409038 08:46:08 INFO - Obtained file size: 14409038 08:46:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:09 INFO - trying https://queue.taskcluster.net/v1/task/NGVcbQd6QmGqqrY3aMYZ2g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:46:09 INFO - Downloading https://queue.taskcluster.net/v1/task/NGVcbQd6QmGqqrY3aMYZ2g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 08:46:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NGVcbQd6QmGqqrY3aMYZ2g/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 08:46:11 INFO - Downloaded 109771517 bytes. 08:46:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NGVcbQd6QmGqqrY3aMYZ2g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:46:11 INFO - mkdir: /builds/slave/test/properties 08:46:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:46:11 INFO - Writing to file /builds/slave/test/properties/build_url 08:46:11 INFO - Contents: 08:46:11 INFO - build_url:https://queue.taskcluster.net/v1/task/NGVcbQd6QmGqqrY3aMYZ2g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:46:12 INFO - Running post-action listener: _resource_record_post_action 08:46:12 INFO - Running post-action listener: set_extra_try_arguments 08:46:12 INFO - [mozharness: 2016-12-21 16:46:12.588449Z] Finished download-and-extract step (success) 08:46:12 INFO - [mozharness: 2016-12-21 16:46:12.588566Z] Running populate-webroot step. 08:46:12 INFO - Running pre-action listener: _resource_record_pre_action 08:46:12 INFO - Running main action method: populate_webroot 08:46:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:46:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:46:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:46:12 INFO - '--tppagecycles', 08:46:12 INFO - '7'], 08:46:12 INFO - 'tests': ['tsvgm']}}, 08:46:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:46:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:46:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:46:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:46:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:46:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:46:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:12 INFO - 'talos_options': ['--disable-e10s'], 08:46:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:46:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:46:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:12 INFO - 'talos_options': ['--disable-e10s'], 08:46:12 INFO - 'tests': ['damp', 'tps']}, 08:46:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:12 INFO - 'tests': ['damp', 'tps']}, 08:46:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:46:12 INFO - 'tests': ['dromaeo_dom']}, 08:46:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:46:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:46:12 INFO - 'tests': ['basic_compositor_video']}, 08:46:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:46:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:46:12 INFO - 'tests': ['a11yr', 08:46:12 INFO - 'ts_paint', 08:46:12 INFO - 'tpaint', 08:46:12 INFO - 'sessionrestore', 08:46:12 INFO - 'sessionrestore_no_auto_restore', 08:46:12 INFO - 'tabpaint']}, 08:46:12 INFO - 'other-e10s': {'tests': ['a11yr', 08:46:12 INFO - 'ts_paint', 08:46:12 INFO - 'tpaint', 08:46:12 INFO - 'sessionrestore', 08:46:12 INFO - 'sessionrestore_no_auto_restore', 08:46:12 INFO - 'tabpaint']}, 08:46:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:46:12 INFO - 'ts_paint', 08:46:12 INFO - 'tpaint', 08:46:12 INFO - 'sessionrestore', 08:46:12 INFO - 'sessionrestore_no_auto_restore', 08:46:12 INFO - 'tabpaint']}, 08:46:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:46:12 INFO - 'ts_paint', 08:46:12 INFO - 'tpaint', 08:46:12 INFO - 'sessionrestore', 08:46:12 INFO - 'sessionrestore_no_auto_restore', 08:46:12 INFO - 'tabpaint']}, 08:46:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:46:12 INFO - 'tests': ['a11yr', 08:46:12 INFO - 'ts_paint', 08:46:12 INFO - 'tpaint', 08:46:12 INFO - 'sessionrestore', 08:46:12 INFO - 'sessionrestore_no_auto_restore', 08:46:12 INFO - 'tabpaint']}, 08:46:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:46:12 INFO - 'tests': ['a11yr', 08:46:12 INFO - 'ts_paint', 08:46:12 INFO - 'tpaint', 08:46:12 INFO - 'sessionrestore', 08:46:12 INFO - 'sessionrestore_no_auto_restore', 08:46:12 INFO - 'tabpaint']}, 08:46:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:46:12 INFO - 'tests': ['tsvgx', 08:46:12 INFO - 'tsvgr_opacity', 08:46:12 INFO - 'tart', 08:46:12 INFO - 'tscrollx', 08:46:12 INFO - 'cart']}, 08:46:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:46:12 INFO - 'tsvgr_opacity', 08:46:12 INFO - 'tart', 08:46:12 INFO - 'tscrollx', 08:46:12 INFO - 'cart']}, 08:46:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:12 INFO - 'talos_options': ['--disable-e10s'], 08:46:12 INFO - 'tests': ['tp5o']}, 08:46:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:12 INFO - 'tests': ['tp5o']}, 08:46:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:12 INFO - 'talos_options': ['--disable-e10s', 08:46:12 INFO - '--xperf_path', 08:46:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:46:12 INFO - 'tests': ['tp5n']}, 08:46:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:46:12 INFO - 'talos_options': ['--xperf_path', 08:46:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:46:12 INFO - 'tests': ['tp5n']}}, 08:46:12 INFO - 'talos.zip': {'path': '', 08:46:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:46:12 INFO - Running post-action listener: _resource_record_post_action 08:46:12 INFO - [mozharness: 2016-12-21 16:46:12.607573Z] Finished populate-webroot step (success) 08:46:12 INFO - [mozharness: 2016-12-21 16:46:12.607705Z] Running create-virtualenv step. 08:46:12 INFO - Running pre-action listener: _resource_record_pre_action 08:46:12 INFO - Running main action method: create_virtualenv 08:46:12 INFO - Creating virtualenv /builds/slave/test/build/venv 08:46:12 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:46:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:46:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:46:12 INFO - Using real prefix '/tools/python27' 08:46:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:46:13 INFO - Installing distribute.............................................................................................................................................................................................done. 08:46:16 INFO - Installing pip.................done. 08:46:16 INFO - Return code: 0 08:46:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:46:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:46:16 INFO - Reading from file tmpfile_stdout 08:46:16 INFO - Output received: 08:46:16 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:46:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:46: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')]} 08:46:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46: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')]} 08:46:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105af2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a68570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fb8ca3170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105906d30>, '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 0x10576cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058feb20>, '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.cXMxEeesbM/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.8LwyT9la9s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 08:46:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:46:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:46:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cXMxEeesbM/Render', 08:46:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:16 INFO - 'HOME': '/Users/cltbld', 08:46:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:16 INFO - 'LOGNAME': 'cltbld', 08:46:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:16 INFO - 'MOZ_NO_REMOTE': '1', 08:46:16 INFO - 'NO_EM_RESTART': '1', 08:46:16 INFO - 'PAGER': '/bin/cat', 08:46:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:16 INFO - 'PWD': '/builds/slave/test', 08:46:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:16 INFO - 'SHELL': '/bin/bash', 08:46:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8LwyT9la9s/Listeners', 08:46:16 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 08:46:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:16 INFO - 'USER': 'cltbld', 08:46:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:16 INFO - 'XPC_FLAGS': '0x0', 08:46:16 INFO - 'XPC_SERVICE_NAME': '0', 08:46:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:16 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:46:16 INFO - Cleaning up... 08:46:16 INFO - Return code: 0 08:46:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:46: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')]} 08:46:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46: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')]} 08:46:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105af2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a68570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fb8ca3170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105906d30>, '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 0x10576cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058feb20>, '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.cXMxEeesbM/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.8LwyT9la9s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 08:46:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:46:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:46:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cXMxEeesbM/Render', 08:46:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:16 INFO - 'HOME': '/Users/cltbld', 08:46:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:16 INFO - 'LOGNAME': 'cltbld', 08:46:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:16 INFO - 'MOZ_NO_REMOTE': '1', 08:46:16 INFO - 'NO_EM_RESTART': '1', 08:46:16 INFO - 'PAGER': '/bin/cat', 08:46:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:16 INFO - 'PWD': '/builds/slave/test', 08:46:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:16 INFO - 'SHELL': '/bin/bash', 08:46:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8LwyT9la9s/Listeners', 08:46:16 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 08:46:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:16 INFO - 'USER': 'cltbld', 08:46:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:16 INFO - 'XPC_FLAGS': '0x0', 08:46:16 INFO - 'XPC_SERVICE_NAME': '0', 08:46:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:17 INFO - Downloading/unpacking psutil>=3.1.1 08:46: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 08:46: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 08:46: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 08:46: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 08:46: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 08:46: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 08:46:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:46:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:46:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:46:20 INFO - Installing collected packages: psutil 08:46:20 INFO - Running setup.py install for psutil 08:46:20 INFO - building 'psutil._psutil_osx' extension 08:46:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:46:21 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 08:46:21 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 08:46:21 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 08:46:21 INFO - building 'psutil._psutil_posix' extension 08:46:21 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 08:46:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:46:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:46:21 INFO - ^ 08:46:21 INFO - 1 warning generated. 08:46:21 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 08:46:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:46:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:46:21 INFO - Successfully installed psutil 08:46:21 INFO - Cleaning up... 08:46:21 INFO - Return code: 0 08:46:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:46:21 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')]} 08:46:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:21 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')]} 08:46:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:21 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 0x105af2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a68570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fb8ca3170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105906d30>, '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 0x10576cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058feb20>, '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.cXMxEeesbM/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.8LwyT9la9s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 08:46:21 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 08:46:21 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 08:46:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cXMxEeesbM/Render', 08:46:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:21 INFO - 'HOME': '/Users/cltbld', 08:46:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:21 INFO - 'LOGNAME': 'cltbld', 08:46:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:21 INFO - 'MOZ_NO_REMOTE': '1', 08:46:21 INFO - 'NO_EM_RESTART': '1', 08:46:21 INFO - 'PAGER': '/bin/cat', 08:46:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:21 INFO - 'PWD': '/builds/slave/test', 08:46:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:21 INFO - 'SHELL': '/bin/bash', 08:46:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8LwyT9la9s/Listeners', 08:46:21 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 08:46:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:21 INFO - 'USER': 'cltbld', 08:46:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:21 INFO - 'XPC_FLAGS': '0x0', 08:46:21 INFO - 'XPC_SERVICE_NAME': '0', 08:46:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:46:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:46:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:46:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:46:24 INFO - Installing collected packages: mozsystemmonitor 08:46:24 INFO - Running setup.py install for mozsystemmonitor 08:46:24 INFO - Successfully installed mozsystemmonitor 08:46:24 INFO - Cleaning up... 08:46:24 INFO - Return code: 0 08:46:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:46:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105af2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a68570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fb8ca3170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105906d30>, '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 0x10576cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058feb20>, '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.cXMxEeesbM/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.8LwyT9la9s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 08:46:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:46:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:46:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cXMxEeesbM/Render', 08:46:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:24 INFO - 'HOME': '/Users/cltbld', 08:46:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:24 INFO - 'LOGNAME': 'cltbld', 08:46:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:24 INFO - 'MOZ_NO_REMOTE': '1', 08:46:24 INFO - 'NO_EM_RESTART': '1', 08:46:24 INFO - 'PAGER': '/bin/cat', 08:46:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:24 INFO - 'PWD': '/builds/slave/test', 08:46:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:24 INFO - 'SHELL': '/bin/bash', 08:46:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8LwyT9la9s/Listeners', 08:46:24 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 08:46:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:24 INFO - 'USER': 'cltbld', 08:46:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:24 INFO - 'XPC_FLAGS': '0x0', 08:46:24 INFO - 'XPC_SERVICE_NAME': '0', 08:46:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:25 INFO - Downloading/unpacking jsonschema==2.5.1 08:46:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:46:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:46: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 08:46: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 08:46: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 08:46: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 08:46: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 08:46: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 08:46:28 INFO - Downloading functools32-3.2.3-2.tar.gz 08:46:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:46:28 INFO - warning: no files found matching '*.txt' 08:46:28 INFO - no previously-included directories found matching 'build' 08:46:28 INFO - no previously-included directories found matching 'dist' 08:46:28 INFO - no previously-included directories found matching '.git*' 08:46:28 INFO - Installing collected packages: jsonschema, functools32 08:46:28 INFO - Running setup.py install for functools32 08:46:28 INFO - warning: no files found matching '*.txt' 08:46:28 INFO - no previously-included directories found matching 'build' 08:46:28 INFO - no previously-included directories found matching 'dist' 08:46:28 INFO - no previously-included directories found matching '.git*' 08:46:28 INFO - Successfully installed jsonschema functools32 08:46:28 INFO - Cleaning up... 08:46:28 INFO - Return code: 0 08:46:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:46: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')]} 08:46:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46: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')]} 08:46:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:29 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 0x105af2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a68570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fb8ca3170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105906d30>, '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 0x10576cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058feb20>, '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.cXMxEeesbM/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.8LwyT9la9s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 08:46:29 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 08:46:29 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 08:46:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cXMxEeesbM/Render', 08:46:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:29 INFO - 'HOME': '/Users/cltbld', 08:46:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:29 INFO - 'LOGNAME': 'cltbld', 08:46:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:29 INFO - 'MOZ_NO_REMOTE': '1', 08:46:29 INFO - 'NO_EM_RESTART': '1', 08:46:29 INFO - 'PAGER': '/bin/cat', 08:46:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:29 INFO - 'PWD': '/builds/slave/test', 08:46:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:29 INFO - 'SHELL': '/bin/bash', 08:46:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8LwyT9la9s/Listeners', 08:46:29 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 08:46:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:29 INFO - 'USER': 'cltbld', 08:46:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:29 INFO - 'XPC_FLAGS': '0x0', 08:46:29 INFO - 'XPC_SERVICE_NAME': '0', 08:46:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:46:29 INFO - Cleaning up... 08:46:29 INFO - Return code: 0 08:46:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:46:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:29 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 0x105af2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a68570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fb8ca3170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105906d30>, '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 0x10576cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058feb20>, '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.cXMxEeesbM/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.8LwyT9la9s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 08:46:29 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 08:46:29 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 08:46:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cXMxEeesbM/Render', 08:46:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:29 INFO - 'HOME': '/Users/cltbld', 08:46:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:29 INFO - 'LOGNAME': 'cltbld', 08:46:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:29 INFO - 'MOZ_NO_REMOTE': '1', 08:46:29 INFO - 'NO_EM_RESTART': '1', 08:46:29 INFO - 'PAGER': '/bin/cat', 08:46:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:29 INFO - 'PWD': '/builds/slave/test', 08:46:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:29 INFO - 'SHELL': '/bin/bash', 08:46:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8LwyT9la9s/Listeners', 08:46:29 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 08:46:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:29 INFO - 'USER': 'cltbld', 08:46:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:29 INFO - 'XPC_FLAGS': '0x0', 08:46:29 INFO - 'XPC_SERVICE_NAME': '0', 08:46:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:29 INFO - Downloading/unpacking blobuploader==1.2.4 08:46:29 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 08:46:29 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 08:46:29 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 08:46:29 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 08:46:29 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 08:46:29 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 08:46:32 INFO - Downloading blobuploader-1.2.4.tar.gz 08:46:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:46:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:46:32 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 08:46:32 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 08:46:32 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 08:46:32 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 08:46:32 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 08:46:32 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 08:46:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:46:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:46:33 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 08:46:33 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 08:46:33 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 08:46:33 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 08:46:33 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 08:46:33 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 08:46:33 INFO - Downloading docopt-0.6.1.tar.gz 08:46:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:46:33 INFO - Installing collected packages: blobuploader, requests, docopt 08:46:33 INFO - Running setup.py install for blobuploader 08:46:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:46:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:46:33 INFO - Running setup.py install for requests 08:46:34 INFO - Running setup.py install for docopt 08:46:34 INFO - Successfully installed blobuploader requests docopt 08:46:34 INFO - Cleaning up... 08:46:34 INFO - Return code: 0 08:46:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:46:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:34 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 0x105af2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a68570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fb8ca3170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105906d30>, '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 0x10576cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058feb20>, '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.cXMxEeesbM/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.8LwyT9la9s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 08:46:34 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 08:46:34 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 08:46:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cXMxEeesbM/Render', 08:46:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:34 INFO - 'HOME': '/Users/cltbld', 08:46:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:34 INFO - 'LOGNAME': 'cltbld', 08:46:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:34 INFO - 'MOZ_NO_REMOTE': '1', 08:46:34 INFO - 'NO_EM_RESTART': '1', 08:46:34 INFO - 'PAGER': '/bin/cat', 08:46:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:34 INFO - 'PWD': '/builds/slave/test', 08:46:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:34 INFO - 'SHELL': '/bin/bash', 08:46:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8LwyT9la9s/Listeners', 08:46:34 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 08:46:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:34 INFO - 'USER': 'cltbld', 08:46:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:34 INFO - 'XPC_FLAGS': '0x0', 08:46:34 INFO - 'XPC_SERVICE_NAME': '0', 08:46:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:46:35 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-1YnlWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:46:35 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-vmCegD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:46:35 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-Oxd2Pd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:46:35 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-WlJGT2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:46:35 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-AKXZdJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:46:35 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-D5Znub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:46:35 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-PUDrr_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:46:35 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-tpxv2U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:46:35 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-XVh9Cw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:46:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:46:35 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-nLU3s5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:46:36 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-MeVNMC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:46:36 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-fHMfUd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:46:36 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-nlkrYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:46:36 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-JCvY5c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:46:36 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-tQBQNX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:46:36 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-B34PZ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:46:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:46:36 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-bFJ597-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:46:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:46:36 INFO - Running setup.py install for manifestparser 08:46:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:46:36 INFO - Running setup.py install for mozcrash 08:46:37 INFO - Running setup.py install for mozdebug 08:46:37 INFO - Running setup.py install for mozdevice 08:46:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:46:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:46:37 INFO - Running setup.py install for mozfile 08:46:37 INFO - Running setup.py install for mozhttpd 08:46:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:46:37 INFO - Running setup.py install for mozinfo 08:46:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:46:37 INFO - Running setup.py install for mozInstall 08:46:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:46:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:46:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:46:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:46:37 INFO - Running setup.py install for mozleak 08:46:38 INFO - Running setup.py install for mozlog 08:46:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:46:38 INFO - Running setup.py install for moznetwork 08:46:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:46:38 INFO - Running setup.py install for mozprocess 08:46:38 INFO - Running setup.py install for mozprofile 08:46:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:46:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:46:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:46:38 INFO - Running setup.py install for mozrunner 08:46:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:46:38 INFO - Running setup.py install for mozscreenshot 08:46:39 INFO - Running setup.py install for moztest 08:46:39 INFO - Running setup.py install for mozversion 08:46:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:46:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:46:39 INFO - Cleaning up... 08:46:39 INFO - Return code: 0 08:46:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:46: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')]} 08:46:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46: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')]} 08:46:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:39 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 0x105af2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a68570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fb8ca3170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105906d30>, '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 0x10576cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058feb20>, '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.cXMxEeesbM/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.8LwyT9la9s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 08:46:39 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 08:46:39 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 08:46:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cXMxEeesbM/Render', 08:46:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:39 INFO - 'HOME': '/Users/cltbld', 08:46:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:39 INFO - 'LOGNAME': 'cltbld', 08:46:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:39 INFO - 'MOZ_NO_REMOTE': '1', 08:46:39 INFO - 'NO_EM_RESTART': '1', 08:46:39 INFO - 'PAGER': '/bin/cat', 08:46:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:39 INFO - 'PWD': '/builds/slave/test', 08:46:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:39 INFO - 'SHELL': '/bin/bash', 08:46:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8LwyT9la9s/Listeners', 08:46:39 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 08:46:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:39 INFO - 'USER': 'cltbld', 08:46:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:39 INFO - 'XPC_FLAGS': '0x0', 08:46:39 INFO - 'XPC_SERVICE_NAME': '0', 08:46:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:46:39 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-cotZkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:46:39 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)) 08:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:46:39 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-zKVpvd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:46:39 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)) 08:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:46:39 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-8vPWXQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:46:40 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)) 08:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:46:40 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-7gzipD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:46:40 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)) 08:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:46:40 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-8B82za-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:46:40 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)) 08:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:46:40 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-f8IHNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:46:40 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)) 08:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:46:40 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-QIHPIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:46:40 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)) 08:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:46:40 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-AX435o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:46:40 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)) 08:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:46:40 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-lKc_lM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:46:40 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)) 08:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:46:40 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-lfyQ5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:46:40 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)) 08:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:46:40 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-qoiWKg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:46:40 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)) 08:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:46:40 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-NKYgDG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:46:40 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)) 08:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:46:40 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-W8gtMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:46:41 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)) 08:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:46:41 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-7LDl0I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:46:41 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)) 08:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:46:41 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-FOxnPT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:46:41 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)) 08:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:46:41 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-_dhfCA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:46:41 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)) 08:46:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:46:41 INFO - Running setup.py (path:/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/pip-VOCgB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:46:41 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)) 08:46:41 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)) 08:46:41 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)) 08:46:41 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)) 08:46:41 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)) 08:46:41 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)) 08:46:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:46:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:46:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:46:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:46:44 INFO - Downloading blessings-1.6.tar.gz 08:46:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:46:44 INFO - Installing collected packages: blessings 08:46:44 INFO - Running setup.py install for blessings 08:46:44 INFO - Successfully installed blessings 08:46:44 INFO - Cleaning up... 08:46:44 INFO - Return code: 0 08:46:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:46:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:46:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:46:44 INFO - Reading from file tmpfile_stdout 08:46:44 INFO - Current package versions: 08:46:44 INFO - blessings == 1.6 08:46:44 INFO - blobuploader == 1.2.4 08:46:44 INFO - docopt == 0.6.1 08:46:44 INFO - functools32 == 3.2.3-2 08:46:44 INFO - jsonschema == 2.5.1 08:46:44 INFO - manifestparser == 1.1 08:46:44 INFO - mozInstall == 1.12 08:46:44 INFO - mozcrash == 1.0 08:46:44 INFO - mozdebug == 0.1 08:46:44 INFO - mozdevice == 0.48 08:46:44 INFO - mozfile == 1.2 08:46:44 INFO - mozhttpd == 0.7 08:46:44 INFO - mozinfo == 0.9 08:46:44 INFO - mozleak == 0.1 08:46:44 INFO - mozlog == 3.4 08:46:44 INFO - moznetwork == 0.27 08:46:44 INFO - mozprocess == 0.23 08:46:44 INFO - mozprofile == 0.28 08:46:44 INFO - mozrunner == 6.13 08:46:44 INFO - mozscreenshot == 0.1 08:46:44 INFO - mozsystemmonitor == 0.3 08:46:44 INFO - moztest == 0.7 08:46:44 INFO - mozversion == 1.4 08:46:44 INFO - psutil == 3.1.1 08:46:44 INFO - requests == 1.2.3 08:46:44 INFO - wsgiref == 0.1.2 08:46:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:46:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:46:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:44 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 0x105af2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a68570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fb8ca3170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105906d30>, '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 0x10576cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058feb20>, '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.cXMxEeesbM/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.8LwyT9la9s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 08:46:44 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 08:46:44 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 08:46:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cXMxEeesbM/Render', 08:46:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:44 INFO - 'HOME': '/Users/cltbld', 08:46:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:44 INFO - 'LOGNAME': 'cltbld', 08:46:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:44 INFO - 'MOZ_NO_REMOTE': '1', 08:46:44 INFO - 'NO_EM_RESTART': '1', 08:46:44 INFO - 'PAGER': '/bin/cat', 08:46:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:44 INFO - 'PWD': '/builds/slave/test', 08:46:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:44 INFO - 'SHELL': '/bin/bash', 08:46:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8LwyT9la9s/Listeners', 08:46:44 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 08:46:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:44 INFO - 'USER': 'cltbld', 08:46:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:44 INFO - 'XPC_FLAGS': '0x0', 08:46:44 INFO - 'XPC_SERVICE_NAME': '0', 08:46:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:46:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:46:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:46:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:46:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:46:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:46:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:46:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:46:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:46:44 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:46:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:46:44 INFO - Cleaning up... 08:46:45 INFO - Return code: 0 08:46:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:46:45 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')]} 08:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:46:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:45 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')]} 08:46:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:46:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:46:45 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 0x105af2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105a68570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fb8ca3170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105906d30>, '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 0x10576cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058feb20>, '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.cXMxEeesbM/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.8LwyT9la9s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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 08:46:45 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 08:46:45 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 08:46:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cXMxEeesbM/Render', 08:46:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:46:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:46:45 INFO - 'HOME': '/Users/cltbld', 08:46:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:46:45 INFO - 'LOGNAME': 'cltbld', 08:46:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:46:45 INFO - 'MOZ_NO_REMOTE': '1', 08:46:45 INFO - 'NO_EM_RESTART': '1', 08:46:45 INFO - 'PAGER': '/bin/cat', 08:46:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:46:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:46:45 INFO - 'PWD': '/builds/slave/test', 08:46:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:46:45 INFO - 'SHELL': '/bin/bash', 08:46:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8LwyT9la9s/Listeners', 08:46:45 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 08:46:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:46:45 INFO - 'USER': 'cltbld', 08:46:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:46:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:46:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:46:45 INFO - 'XPC_FLAGS': '0x0', 08:46:45 INFO - 'XPC_SERVICE_NAME': '0', 08:46:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:46:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:46:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:46:45 INFO - Cleaning up... 08:46:45 INFO - Return code: 0 08:46:45 INFO - Running post-action listener: _resource_record_post_action 08:46:45 INFO - Running post-action listener: _start_resource_monitoring 08:46:45 INFO - Starting resource monitoring. 08:46:45 INFO - [mozharness: 2016-12-21 16:46:45.303377Z] Finished create-virtualenv step (success) 08:46:45 INFO - [mozharness: 2016-12-21 16:46:45.304409Z] Running install step. 08:46:45 INFO - Running pre-action listener: _resource_record_pre_action 08:46:45 INFO - Running main action method: install 08:46:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:46:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:46:45 INFO - Reading from file tmpfile_stdout 08:46:45 INFO - Detecting whether we're running mozinstall >=1.0... 08:46:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:46:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:46:45 INFO - Reading from file tmpfile_stdout 08:46:45 INFO - Output received: 08:46:45 INFO - Usage: mozinstall [options] installer 08:46:45 INFO - Options: 08:46:45 INFO - -h, --help show this help message and exit 08:46:45 INFO - -d DEST, --destination=DEST 08:46:45 INFO - Directory to install application into. [default: 08:46:45 INFO - "/builds/slave/test"] 08:46:45 INFO - --app=APP Application being installed. [default: firefox] 08:46:45 INFO - mkdir: /builds/slave/test/build/application 08:46:45 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'] 08:46:45 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 08:47:16 INFO - Reading from file tmpfile_stdout 08:47:16 INFO - Output received: 08:47:16 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:47:16 INFO - Running post-action listener: _resource_record_post_action 08:47:16 INFO - [mozharness: 2016-12-21 16:47:16.654368Z] Finished install step (success) 08:47:16 INFO - [mozharness: 2016-12-21 16:47:16.654533Z] Running run-tests step. 08:47:16 INFO - Running pre-action listener: _resource_record_pre_action 08:47:16 INFO - Running main action method: run_tests 08:47:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:47:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:47:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:47:16 INFO - 2016-12-21 08:47:16.669 screenresolution[34342:378095] starting screenresolution argv=screenresolution get 08:47:16 INFO - 2016-12-21 08:47:16.672 screenresolution[34342:378095] Display 0: 1600x1200x32@60 08:47:16 INFO - 2016-12-21 08:47:16.678 screenresolution[34343:378097] starting screenresolution argv=screenresolution list 08:47:16 INFO - Available Modes on Display 0 08:47:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:47:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:47:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:47:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:47:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:47:16 INFO - Intel Iris: 08:47:16 INFO - Chipset Model: Intel Iris 08:47:16 INFO - Type: GPU 08:47:16 INFO - Bus: Built-In 08:47:16 INFO - VRAM (Dynamic, Max): 1536 MB 08:47:16 INFO - Vendor: Intel (0x8086) 08:47:16 INFO - Device ID: 0x0a2e 08:47:16 INFO - Revision ID: 0x0009 08:47:16 INFO - Displays: 08:47:16 INFO - Display: 08:47:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:47:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:47:16 INFO - Display Serial Number: 1600x1200 60 08:47:16 INFO - Main Display: Yes 08:47:16 INFO - Mirror: Off 08:47:16 INFO - Online: Yes 08:47:16 INFO - Rotation: Supported 08:47:16 INFO - Return code: 0 08:47:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:47:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:47:16 INFO - Python 2.7.3 08:47:16 INFO - Return code: 0 08:47:16 INFO - grabbing minidump binary from tooltool 08:47: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')]} 08:47:16 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105906d30>, '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 0x10576cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1058feb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:47:16 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:47:16 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 08:47:16 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:47:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:47:17 INFO - Return code: 0 08:47:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:47:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:47:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:47:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:47:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:47:17 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', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NGVcbQd6QmGqqrY3aMYZ2g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0220', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:47:17 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 --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NGVcbQd6QmGqqrY3aMYZ2g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0220 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:47:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cXMxEeesbM/Render', 08:47:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:47:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:47:17 INFO - 'HOME': '/Users/cltbld', 08:47:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:47:17 INFO - 'LOGNAME': 'cltbld', 08:47:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:47:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:47:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:47:17 INFO - 'MOZ_NO_REMOTE': '1', 08:47:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:47:17 INFO - 'NO_EM_RESTART': '1', 08:47:17 INFO - 'PAGER': '/bin/cat', 08:47:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:47:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:47:17 INFO - 'PWD': '/builds/slave/test', 08:47:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:47:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:47:17 INFO - 'SHELL': '/bin/bash', 08:47:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8LwyT9la9s/Listeners', 08:47:17 INFO - 'TMPDIR': '/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/', 08:47:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:47:17 INFO - 'USER': 'cltbld', 08:47:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:47:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:47:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:47:17 INFO - 'XPC_FLAGS': '0x0', 08:47:17 INFO - 'XPC_SERVICE_NAME': '0', 08:47:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:47:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NGVcbQd6QmGqqrY3aMYZ2g/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0220', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:47:17 INFO - mozversion application_buildid: 20161221062253 08:47:17 INFO - mozversion application_changeset: 6c1dd7d65949799726e1ab823d66070927f622e8 08:47:17 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:47:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:47:17 INFO - mozversion application_name: Firefox 08:47:17 INFO - mozversion application_remotingname: firefox-dev 08:47:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:47:17 INFO - mozversion application_vendor: Mozilla 08:47:17 INFO - mozversion application_version: 52.0a2 08:47:17 INFO - mozversion platform_buildid: 20161221062253 08:47:17 INFO - mozversion platform_changeset: 6c1dd7d65949799726e1ab823d66070927f622e8 08:47:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:47:17 INFO - mozversion platform_version: 52.0a2 08:47:17 INFO - using testdate: 1482338837 08:47:17 INFO - actual date: 1482338837 08:47:17 INFO - starting webserver on 'localhost:58462' 08:47:17 INFO - SUITE-START | Running 2 tests 08:47:17 INFO - TEST-START | dromaeo_css 08:47:17 INFO - operating with platform_type : mac_ 08:47:17 INFO - Initialising browser for dromaeo_css test... 08:47:17 INFO - TEST-INFO | started process 34353 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/tmpz8BuZK/profile http://localhost:58462/getInfo.html) 08:47:20 INFO - PROCESS | 34353 | __metrics Screen width/height:1600/1200 08:47:20 INFO - PROCESS | 34353 | colorDepth:24 08:47:20 INFO - PROCESS | 34353 | Browser inner width/height: 1024/689 08:47:20 INFO - PROCESS | 34353 | __metrics 08:47:20 INFO - PROCESS | 34353 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:47:20 INFO - PROCESS | 34353 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:47:21 INFO - TEST-INFO | 34353: exit 0 08:47:21 INFO - Browser initialized. 08:47:21 INFO - Running cycle 1/1 for dromaeo_css test... 08:47:21 INFO - TEST-INFO | started process 34356 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/tmpz8BuZK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:47:33 INFO - PROCESS | 34356 | RSS: Main: 222683136 08:47:33 INFO - PROCESS | 34356 | 08:48:24 INFO - PROCESS | 34356 | 1482338904833 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] 08:50:37 INFO - PROCESS | 34356 | Cycle 1(1): loaded http://localhost:58462/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58462/tests/dromaeo/cssquery-ext.html) 08:50:39 INFO - PROCESS | 34356 | RSS: Main: 342700032 08:50:39 INFO - PROCESS | 34356 | 08:53:42 INFO - PROCESS | 34356 | Cycle 1(1): loaded http://localhost:58462/tests/dromaeo/cssquery-ext.html (next: http://localhost:58462/tests/dromaeo/cssquery-jquery.html) 08:53:42 INFO - PROCESS | 34356 | RSS: Main: 347815936 08:53:42 INFO - PROCESS | 34356 | 08:56:45 INFO - PROCESS | 34356 | Cycle 1(1): loaded http://localhost:58462/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58462/tests/dromaeo/cssquery-mootools.html) 08:56:45 INFO - PROCESS | 34356 | RSS: Main: 313970688 08:56:45 INFO - PROCESS | 34356 | 08:59:48 INFO - PROCESS | 34356 | Cycle 1(1): loaded http://localhost:58462/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58462/tests/dromaeo/cssquery-prototype.html) 08:59:48 INFO - PROCESS | 34356 | RSS: Main: 327622656 08:59:48 INFO - PROCESS | 34356 | 09:02:51 INFO - PROCESS | 34356 | Cycle 1(1): loaded http://localhost:58462/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58462/tests/dromaeo/cssquery-yui.html) 09:02:52 INFO - PROCESS | 34356 | RSS: Main: 307298304 09:02:52 INFO - PROCESS | 34356 | 09:05:55 INFO - PROCESS | 34356 | Cycle 1(1): loaded http://localhost:58462/tests/dromaeo/cssquery-yui.html (next: http://localhost:58462/tests/dromaeo/cssquery-dojo.html) 09:05:56 INFO - PROCESS | 34356 | RSS: Main: 402264064 09:05:56 INFO - PROCESS | 34356 | 09:05:56 INFO - PROCESS | 34356 | __start_tp_report 09:05:56 INFO - PROCESS | 34356 | _x_x_mozilla_page_load 09:05:56 INFO - PROCESS | 34356 | _x_x_mozilla_page_load_details 09:05:56 INFO - PROCESS | 34356 | |i|pagename|runs| 09:05:56 INFO - PROCESS | 34356 | |0;dojo.html;2653.7744291735203;3221.081991632384;3248.2529018325768;3250.2199472126695;3302.0589132097357;4572.954270457294;5415.404305526394;5470.8085217017415;5482.753276102575;5512.669239845607;5441.2654291670615;5470;5487.780488780444;5508.20131080993;5511.200875873006;4848.733319667417;5013.799448022084;5053.570446512051;5110.463401342861;5126.384833819928;4116.773577453238;5016.398032236137;5025.974870125644;5048.217526283432;5102.795888164487;5015.448300686921;5081.314022606938;5108.157154069574;5111.744412779346;5124.436312005676;5055.216441451581;5085.593152547787;5089.745512724386;5090.516400941905;5142.023015627019;5068.214426763858;5090.898182036375;5105.336306280197;5110;5136.922946155811;5354.732263386815;5397.676139431607;5422.810201642963;5445.700486473243;5519.69641669708;5877.118432235156;5893.381194974509;5902.996490596609;5960.523158147338;6004.219451471325;6098.420650038283;6100.450959413674;6109.297430795471;6116.633002019848;6122.47958923492;5448.073827449343;5502.80740174092;5581.413951535112;5583;5589.832305030855;6104.732011601605;6131.663631601249;6135.07955838608;6141.62210784067;6145.324014358417;5834.329052159056;5889.911651325234;5897.67562784051;5904.025835737055;5932.40675932412;5992.790252341234;5993.0710739835395;6046.123312119718;6084.756609735561;6105;5766.04860198071;5809.535237181015;5939.851503712355;5972.462478376967;5978.790742323998;5896.3219229788265;5899.7050147492455;5953.166556682068;5960.880782384328;6071.119687645267;4779.091972525209;4791.281307803857;4794.712317260975;4800.399950006249;4817.9518204818205;4259.74441533509;4275.508316543575;4283.892902677396;4283.993261583525;4284.143171365677;5509.090999984957;5570.6657600544095;5576.749046292926;5586.55307575393;5593.944060559343;5941.197938278277;6062.484688801413;6081;6087.482563982023;6094.99432593626;5631.971840140855;5649.971750141305;5654.236678048493;5667.319921609434;5670.801521946794;5038.118329292359;5228.92156617651;5233.4243233244315;5234.398044224963;5253.028189784902;6193.876122477615;6201.348858369806;6219.844503887439;6262.1546091278005;6324.557280990471;6080.939190608037;6081.057436097412;6102.908456373247;6105.6947152641305;6110.2973158085615;6206.937930620817;6240.500759939103;6243.063540468966;6255.812325630239;6269.341719119427;4667.953320466752;5740.971295143498;5796.739146738322;5822.912656310244;5837.09525023622;5934.050551911846;6034.849128771816;6042.335343112342;6043.879122417439;6059.6364218147055;5128.666636668605;5524.116141417354;5830.271216097988;5831.300243970751;5833.6208146472045;5426.8371948841595;5522.144067669517;5733.369329373849;5809;5824.242848429677;5340.839774806762;5629.437056294338;5715.771369145187;5757.769689212552;5762.279715035621;5798.159266906359;5819.388964158702;5823;5828.825135245949;5829.446202610745;5521.944780552143;5578.804741834017;5682.77268909239;5693.601447898773;5703.714814259353 09:05:56 INFO - PROCESS | 34356 | |1;ext.html;456.5480174627118;478.1512814753809;499.0991260774302;499.8875253068058;506.6554742774913;27062.594061089076;38246;39464.60535394645;39469;39538.2092358153;40397.394039089435;40601.59398406015;40649.59350406495;40754;40959.180816383654;8240.50556966581;9187.95406022969;9381.577828997712;9389.953050234773;9411.95294023529;8260.173982601751;10885.83671244932;11067.225294229407;11183.44082795857;11191.216614836965;10962.616308429206;10984.95642913922;11023.228374013823;11119.666410007712;11240.943795281011;11070.28043177191;11136.777264454666;11141.94151555601;11173.050290725298;11178.888211117866;10958.671239862817;11019.228653994223;11064.502097405637;11069.225154239206;11072;37987.43018854692;38261.617383826364;38364.23271534582;38683.8065809672;38693;3209;3267.493538501536;3267.950980735291;3297.48888922215;3328.484084966834;26488;28570.571441428394;28915.746370517434;29007.274818129503;29296.267593310124;3024.228821650487;3092.5361195820583;3108.8601012954246;3153.684631536828;3159.352332771776;421276;498427;498538.9999999964;499289;501288;32232.677673222966;57433.42566574364;57977.13034304489;58026.7098664504;58106;33240.002799916045;33954.83022584871;33996.830015849766;34288.31423371568;34364.969050928514;13892.583222503341;14026.719465610631;14037.368318425695;14041;14050.929745351412;10829.133669306437;11086.113110951104;11107.666769996913;11858.347790871585;12029.03767698582;6027.758889644453;7198.964005180045;7280.599567023864;7505.549667019996;7760.301572858357;3156.37446714942;3200.2959348943195;3211.951820722691;3233.3533293341425;3241.0438920518504;848.3806821020689;866.6966561703354;886.5124181700039;953.9713808585753;976.770458942148;27463;28211.71788282091;28341.29146771306;29186.978455753946;29245.561316580268;1018.6078359831519;1025.4411345816548;1041.9010194031555;1088.276296262992;1109.4563663804677;14332.928335358465;14850.554483365517;14908.956373054;15162.31769570372;15301.770473442908;642573;646667;648165;649006;649136;54248.728756355966;54829.72585137049;54864.45135548594;54988.72505637446;55083;1212.563477148226;1257.082329899197;1331.4274861809515;1350.2843492948557;1371.9314034298445;27249.9587576299;29618;29936.80252789864;29971;30501.847490762404;46709.766451168885;47052.29421558748;47165.764171178926;47227;47237.055258895314;545223;547827;547944;548042;548428;25918.96324147013;26822.463550729266;26837;27468.90124395082;27507.58738618882;35215.647843521234;44079.33880991724;44168.33747493855;44699.55300446954;45251.77374113108;29273.85363073171;36211.45682814707;36619.81690091533;36795;37616.87149385523;34208.97373078712;34377;36617.81691091527;36996.81501592582;37936.24127517379;36918;36951;36973.26053478861;37850.810745946095;37862.24275514529;31387.529187062668;32669.836650816593;33600.327993440485;33638.83180584081;33655.32689346249;19753;24684.38289042788;25503.6174457387;25901.48197036011;25943.870280648476 09:05:56 INFO - PROCESS | 34356 | |2;jquery.html;348.26863586468403;348.7966515521451;370.49242537723325;379.51043154330904;400.24353970994827;14296.142231466107;14772.335244913978;15086.547403577883;17933;19246.03769811511;17197.656046879056;17376.218070186842;18840.057997100157;19582.433405327574;19632.901835490837;5461.481159289871;5797.507211886995;5799.420057994188;5802.100674395476;5807.854803629922;5504.394516603171;5661;5662.037453632872;5704.058830292996;5762.366139724627;5638.718064096779;5643.576731745115;5646.124850648135;5648.378678345399;5651.802186923458;5385.272988146608;5567.246317410003;5623.718814059281;5627.774889004435;5670.149477578376;4947.084789313988;4953.727544985024;4959.925601115986;4961.18140506816;4965.677230979974;23153.421164470856;23896.402589935216;24002.87998559996;24050;24289.514209715708;2424.9272521824373;2435.2085572189108;2445.3030886197525;2690.8654567271756;2729.2221716810614;14759.92620036893;14946.476873309382;15006.804959394587;15168.848311516964;15534.223288835514;1775.9822401775946;1798.3885478937093;1805.702059160237;1880.069365664005;1903.0389653225034;101327;111432;113730;118600;118624.40687796506;8421.536815475207;10047.296689231829;10498.212634052477;10600.628977985734;10654.094401975764;13015.804762928563;13052.934735326262;13056.34718264083;13087.934560327138;13120.212787232796;8111.432199746079;8127.309178719756;8242.464239824392;8243.83512329762;8291.626876790559;6754.932450675528;8186.304164145995;8311.127331630212;8321.334293256525;8336.333093352518;6098.1157732128595;6234.470070044006;6238.563300569007;6245.656488893154;6254.468370188494;1691.9407820726215;1727.6783260805494;1803.5220666523383;1835.8990255536073;1836.2287839107607;755.47494491328;811.2293321344746;872.5942436766934;872.6691795012656;887.2148148888151;20069;20480.283190088572;20738.896305518378;20806.895965520074;20840.58318833615;815.311062152492;900.257287737606;905.5110240470204;916.3906002508257;947.4457442396138;10355.482225888825;11017.173711971634;11100.833487497694;11116.49975751077;11144.387058711687;606049;609970;611583;619004;627809;86287.56856215678;94706;101900.49049754853;102185;106106;937.1237892570468;963.8409662405764;984.2077127912099;985.571276494727;1007.1690855044618;17602.29590816353;17925.551861203574;18435.53911152232;18653.626927461646;18887.90556047211;48396.75801620969;55287.723561381936;55624.72187639036;56484.71757641186;57332.14001789893;64409.67795160994;77797;77902.6104869472;78013.43973120619;79463.60268198622;7867;7894.210578942289;7979.202079791974;8072.757817265491;8136.87794683092;8750.993635731718;11895.643130706092;12623.495060197489;12650.557230496888;12700.36498175106;10900.127989760958;10974.561017559208;11284.097272218367;11315.830262545904;11449.713757155805;10901.236913416302;10978.890211098107;10984.615538456115;11380.487878045342;11625.534978600761;6308.684565771785;10787.784244315228;11085.27945683528;11139.217725163935;11724.941375293069;7867.190546174526;7967.083785364757;8104.83790324202;8288.668453262044;8383.790405239917;10736.73158171052;10741.677749667522;10826.566937322734;10922.508487118257;11229.71925701864 09:05:56 INFO - PROCESS | 34356 | |3;mootools.html;1288.4717265920974;1425.486824743092;1462.3857979648549;1583.2558697412212;1797.5865550923288;7320.633968301584;7614.771556853297;8086.71696490623;8240.876386854203;8346.499210047401;8014.278714915657;8089.393295502831;8096.230858068474;8104.6758129674745;8119.106898241203;1899.9145038473303;2256.7404748453932;2286.1541229745017;2289.050044231642;2289.69089172962;2259.5706815705043;2310;2325.430269583954;2326.0346956013236;2327.9418014549688;2259.2318611672026;2268.1721171772265;2290.1755368067484;2293.025464177718;2297.6094064009158;1849.6670599292122;2243.4615692233747;2286.942826429336;2288.439332363582;2294.5066810635626;1882.6611209982198;2296;2313.8148948084113;2317.3627252505526;2319.8028167605776;8016.438849280553;8209;8209.220124088202;8212.507249564986;8215.301699355561;4947.826826061107;5114.465660301932;5131.358580177478;5142.07442660321;5152.304438900738;3343.782654127498;3496.1434448560267;3541.4864844597646;3564.518789963348;3572.821358932043;1043.7808060307345;1067.9466026698633;1094.162965331522;1098.1269890437122;1108.695108845074;10489.685309440729;10593.25847190697;10597.364158150536;10607.893921060844;10619.203559732974;4175.060611362419;4347.891302717458;4411.51473337939;4418.690691651618;4439.933400998988;8570.057293697688;8576.099509551408;8583.527905965107;8590.398672092893;8590.78523036929;5051.06555287273;5055.292259083732;5060.620453466005;5068.214426763858;5071.290019397287;3986.3223252047223;4252.255855225323;4384.583464570861;4669.34629151919;4742.786574604151;3594.2452085061905;3638.6543278388513;3681.7422780405113;3693.575238847567;3694.2057457646733;1129.9040280280126;1134.325076579434;1139.2594813371375;1143.9370834604176;1144.6566030190909;733.614852202598;823.7075838077396;866.6576702202594;882.6337070115958;897.2463130970017;4400.823967041347;5489.64317319373;5505.752241149158;5521.558275338044;5545.085060964976;427.9336702811069;444.88210624184615;451.24867096284316;452.34635951051075;457.95129154236423;3723.553173619183;3751.3060083884025;3765.5104836371097;3766.5856755756845;3817.8854634361014;9566.856497152548;9844.114029737359;9925.454100024568;9952.452615106095;9997.250206234561;9890.159336456481;9899.901000990041;9908.851367229498;9914.20686345105;9934.403935763878;7516.398688105048;7523.736669216661;7574.356179724675;7635.503692259986;7746.49647772893;5318.8936221276135;5376.381716102542;5417.241586178017;5498.835034948958;5658.688772117408;9007.729768106967;9222;9228.215601673664;9230.630774769203;9251.583678734607;8518.48889066658;8966.910330896868;9168.174864262248;9234.676786312446;9253.259739220981;7103.786886393416;7328.120625524758;7345;7358.1538123116525;7367.77896663101;7276.527025743309;7459.142198647225;7629.656665450179;7689.3848492121615;7697.923020769721;6851.3491218334175;6907.378335949788;6925.965370173117;6940.305969403221;6977.651117444006;5763.308402991668;6760.966195168992;6829.863402732017;6836.897446538405;7038.436925046087;6448.161738973903;6529.118568993315;6561.376669216417;6623.536352455282;6685.6322902240845;4026.3960405939342;4559.908801824011;4624.260118381043;4732.5030871759345;4868.878278042936;4653.255479123324;4821.445533763662;4870.732109733999;4893.853184404474;4903.33804936329 09:05:56 INFO - PROCESS | 34356 | |4;prototype.html;248.7823154739603;269.6413769686316;299.35489021159407;308.2094427792712;314.2834337710019;4946.035523072993;5049.495050494948;5060.898782024362;5137.511936366049;5150.665206761562;3641.7814931104153;3972.9801350993203;5029.396472423306;5035.899282014358;5096.541311281984;3920.3730451862443;3931.2923673738715;3942.7831469269177;3945.3490174121234;3951.6443520083117;3264.689854463822;3595.0293420776507;3840.5391353037535;3943.8028098594955;3948.3090459169675;3261.7879837810465;3893.396523538842;3907.7655340679507;3925.3326934421216;3946.842126314944;3906.7460615060213;3908.065507573741;3910;3922.313595120857;3928.5874983126646;3039.8176109433507;3443.3299849572936;3447.9482807757904;3478.808665523395;3481.791092534456;4875.013688265946;4899.902001959977;4924.729139897304;5015.890148108197;5041.420236672756;3749.575161438663;3882.9226363895464;4453.977730111329;4464.665150113722;4485.416895803524;5290.894182116336;5295.338082739658;5300.893982120336;5316.680999140025;5336.945276068703;3453.3438646657055;3794.582595914447;3805.154500067465;3820.808959552039;3838.673712734421;4652.464966528859;4701.64737644678;5492.780288788444;5522.66863988154;5616.803411880605;3153;3748.6438788315068;3772.3775577030033;3780.8676696315497;3788.962110378916;3808.790516521618;3822.694184465236;3835.2577906796773;3844.500214972036;3844.884653460401;3657.4513822926774;4264.957350426456;4345.217860785026;4400.73595584266;4445.310976798602;4641.605463535638;5354.491323324278;5376.7042812644895;5384.542313903362;5387.905192054067;1063.5267306048734;1139.5869122288302;1157.4964890272759;1163.2729544034978;1191.3745283725939;756.856197322507;791.9128895821455;840.3949156607233;843.4854738609444;846.627483907079;4239.152169566038;4893.681910675866;4893.9021219576125;4901.901961960741;4906.975465122723;4540.091981603692;4576.832907608538;4670.813167473331;4981.302617633534;5310.734463276821;4557.567031132037;4822.95177048225;4854.854354369375;4856.63575231852;4911.214205727067;2536.5814640584467;2562.846718976468;2687.314734742667;2714.5520989036586;2749.931251718723;4242.17277630859;5221.347331583552;5223.582113430916;5432.483914028161;5432.483914028161;4529.705569137996;4897.681650692693;5279.937636171012;5283.656562323514;5300.681959082467;5400.945990540123;5404.43253458397;5436.238926550286;5458.781648734006;5461.535769459561;4207.074443622399;4838.758032118419;5114.693118412907;5145.305383773143;5158.664686795346;4462.48681401643;4840.491748366371;4965.801367945386;5092.337996060489;5165.251038599307;4802.941514420309;5083.237514372874;5368.516833485005;5429.67421954684;5493.835184944458;3323.1858194742445;3469.3928562501965;3553.253816698419;3568.7145028398186;3570;4408.553252944646;4970;4996.226339649372;5205.397301349325;5469.453054694499;4443.4445694288215;5347.866303342292;5353.384360798558;5353.705546194997;5359.758810853443;5044;5308.787648494017;5331.200319952039;5332.693490094952;5345.465453454779;4116.196163409698;4728.808054563225;5210.244514545294;5239.030779305841;5256.448072952285;5203.375594928632;5321.893562128813;5324;5327.467253274642;5443.237946687388;4136.544980052132;4513.774311284488;5013.523715247045;5270.631055826056;5323.840284791462 09:05:56 INFO - PROCESS | 34356 | |5;yui.html;679.8300424893777;688.8587839492903;743.6653505922336;766.9501482403647;774.2180397798222;13668.521601743942;14091.365888535014;14369.35337909794;14371.496997605085;14736.336864841081;12728.236305821658;13043.80434293484;13046.412911418985;13049.086563940504;13090.280034598094;642.8682120165355;799.3165843204049;814.1736137820118;814.4298990706529;816.2694388522269;650.9153810004693;786.1548835002367;807.3379828540569;807.8424707182102;813.7029984055806;858.0175698824874;858.7252079334615;859.70769938221;862.1852349529697;862.6678728689468;842.8819965204846;852.075498084579;853.3216093205916;853.5390888919976;854.1159899503983;501.5536172806169;513.322414412975;617.4905702795212;631.0880777276836;639.4500729372736;13878.028538002343;13909.79135312971;13921.791173132411;13927.930360348235;13954.790678139836;12449.128561000734;12537.498500059986;12638.431270592846;12665.36673166338;12961.935190324082;10481.685549433529;10817.242793004492;11089.889101109047;11177.552897884076;11341;4278.358246263055;4284.892877678083;4320.740755554677;4340.891477713051;4487.439070116236;83384;83427;86657.70013449804;88818;92143;10247.795044099077;10384;10408.219383546264;10484.528196231187;10573;19736.70394944077;19782;19819;20077.096530656156;20111.095000725003;7331.083614548182;7514.511556748794;7662.73180438682;7685.615719214017;7788.8052798679355;3602.171500554887;4152.522459917088;4292.806823692941;4300.913981720348;4424.13729322779;3328.2012317043736;3407.063057659114;3407.386670399302;3447.775894566845;3452.9482057769155;558.770903929387;582.8834233153386;593.8040446652594;636.7484843486797;637.486823107403;445.578927913119;640.1997503121098;661.2362721057186;663.0650782396798;671.5701950751522;8658.497017495669;8877.378583499092;8972.41679290844;9125.133112354315;9141.725748227564;570.7888081409822;638.7125793392992;646.7445359083135;652.787843950718;673.1165395058817;3681.815909204529;4425.756583387944;4435.223835828717;4481;4559.202139625552;73433;80859;100581;103988;108103;84914;87855.56072219598;88133;88616;89375.55312223398;85814.71277930836;87795.56102219576;88371.11628883629;90218;95823;9228.123328283802;9391.201747851235;9460.195883349992;9629.229661626912;9633.807323853343;37646.62353376431;42921.141577168906;43993.12013759643;48836.511634883194;52529.47470525246;63782.36217637764;65030.37424064247;75036.12409689801;76229.61885190761;78507.21492784999;10102.69691909244;12630.242185468902;12647.241165530098;12855.814511533248;13189;11682.357470338873;13033.608991730263;14105.224212668401;14152.363143658766;14754.704905901606;13075.280859552817;13203;13358.799618005543;13535.458581656623;13791.172529648253;10758.623448179276;12521.499140034297;13103.541376051793;13196.340182991004;13344.866551334362;11772.70568235801;12161.756764864831;12162.695932601755;12539.498420063095;13222.008349374028;11188.440577971232;11221.382823944761;11819.527218911491;11861.001710625647;11870.940645296718;10472.424016679155;11282.379469129277;11380.54477820878;11521.251118677259;11616.94191529037 09:05:56 INFO - PROCESS | 34356 | __end_tp_report 09:05:56 INFO - PROCESS | 34356 | __start_cc_report 09:05:56 INFO - PROCESS | 34356 | _x_x_mozilla_cycle_collect,1388 09:05:56 INFO - PROCESS | 34356 | __end_cc_report 09:05:56 INFO - PROCESS | 34356 | __startTimestamp1482339956008__endTimestamp 09:05:56 INFO - PROCESS | 34356 | 09:05:56 INFO - PROCESS | 34356 | ------- Summary: start ------- 09:05:56 INFO - PROCESS | 34356 | Number of tests: 6 09:05:56 INFO - PROCESS | 34356 | 09:05:56 INFO - PROCESS | 34356 | [#0] dojo.html Cycles:165 Average:5518.85 Median:5667.32 stddev:643.29 (11.4%) stddev-sans-first:604.72 09:05:56 INFO - PROCESS | 34356 | Values: 2653.8 3221.1 3248.3 3250.2 3302.1 4573.0 5415.4 5470.8 5482.8 5512.7 5441.3 5470.0 5487.8 5508.2 5511.2 4848.7 5013.8 5053.6 5110.5 5126.4 4116.8 5016.4 5026.0 5048.2 5102.8 5015.4 5081.3 5108.2 5111.7 5124.4 5055.2 5085.6 5089.7 5090.5 5142.0 5068.2 5090.9 5105.3 5110.0 5136.9 5354.7 5397.7 5422.8 5445.7 5519.7 5877.1 5893.4 5903.0 5960.5 6004.2 6098.4 6100.5 6109.3 6116.6 6122.5 5448.1 5502.8 5581.4 5583.0 5589.8 6104.7 6131.7 6135.1 6141.6 6145.3 5834.3 5889.9 5897.7 5904.0 5932.4 5992.8 5993.1 6046.1 6084.8 6105.0 5766.0 5809.5 5939.9 5972.5 5978.8 5896.3 5899.7 5953.2 5960.9 6071.1 4779.1 4791.3 4794.7 4800.4 4818.0 4259.7 4275.5 4283.9 4284.0 4284.1 5509.1 5570.7 5576.7 5586.6 5593.9 5941.2 6062.5 6081.0 6087.5 6095.0 5632.0 5650.0 5654.2 5667.3 5670.8 5038.1 5228.9 5233.4 5234.4 5253.0 6193.9 6201.3 6219.8 6262.2 6324.6 6080.9 6081.1 6102.9 6105.7 6110.3 6206.9 6240.5 6243.1 6255.8 6269.3 4668.0 5741.0 5796.7 5822.9 5837.1 5934.1 6034.8 6042.3 6043.9 6059.6 5128.7 5524.1 5830.3 5831.3 5833.6 5426.8 5522.1 5733.4 5809.0 5824.2 5340.8 5629.4 5715.8 5757.8 5762.3 5798.2 5819.4 5823.0 5828.8 5829.4 5521.9 5578.8 5682.8 5693.6 5703.7 09:05:56 INFO - PROCESS | 34356 | 09:05:56 INFO - PROCESS | 34356 | [#1] ext.html Cycles:180 Average:67179.70 Median:33619.58 stddev:151038.42 (449.3%) stddev-sans-first:151379.04 09:05:56 INFO - PROCESS | 34356 | Values: 456.5 478.2 499.1 499.9 506.7 27062.6 38246.0 39464.6 39469.0 39538.2 40397.4 40601.6 40649.6 40754.0 40959.2 8240.5 9188.0 9381.6 9390.0 9412.0 8260.2 10885.8 11067.2 11183.4 11191.2 10962.6 10985.0 11023.2 11119.7 11240.9 11070.3 11136.8 11141.9 11173.1 11178.9 10958.7 11019.2 11064.5 11069.2 11072.0 37987.4 38261.6 38364.2 38683.8 38693.0 3209.0 3267.5 3268.0 3297.5 3328.5 26488.0 28570.6 28915.7 29007.3 29296.3 3024.2 3092.5 3108.9 3153.7 3159.4 421276.0 498427.0 498539.0 499289.0 501288.0 32232.7 57433.4 57977.1 58026.7 58106.0 33240.0 33954.8 33996.8 34288.3 34365.0 13892.6 14026.7 14037.4 14041.0 14050.9 10829.1 11086.1 11107.7 11858.3 12029.0 6027.8 7199.0 7280.6 7505.5 7760.3 3156.4 3200.3 3212.0 3233.4 3241.0 848.4 866.7 886.5 954.0 976.8 27463.0 28211.7 28341.3 29187.0 29245.6 1018.6 1025.4 1041.9 1088.3 1109.5 14332.9 14850.6 14909.0 15162.3 15301.8 642573.0 646667.0 648165.0 649006.0 649136.0 54248.7 54829.7 54864.5 54988.7 55083.0 1212.6 1257.1 1331.4 1350.3 1371.9 27250.0 29618.0 29936.8 29971.0 30501.8 46709.8 47052.3 47165.8 47227.0 47237.1 545223.0 547827.0 547944.0 548042.0 548428.0 25919.0 26822.5 26837.0 27468.9 27507.6 35215.6 44079.3 44168.3 44699.6 45251.8 29273.9 36211.5 36619.8 36795.0 37616.9 34209.0 34377.0 36617.8 36996.8 37936.2 36918.0 36951.0 36973.3 37850.8 37862.2 31387.5 32669.8 33600.3 33638.8 33655.3 19753.0 24684.4 25503.6 25901.5 25943.9 09:05:56 INFO - PROCESS | 34356 | 09:05:56 INFO - PROCESS | 34356 | [#2] jquery.html Cycles:180 Average:34255.85 Median:1538.75 stddev:101761.26 (6613.3%) stddev-sans-first:102014.87 09:05:56 INFO - PROCESS | 34356 | Values: 348.3 348.8 370.5 379.5 400.2 14296.1 14772.3 15086.5 17933.0 19246.0 17197.7 17376.2 18840.1 19582.4 19632.9 5461.5 5797.5 5799.4 5802.1 5807.9 5504.4 5661.0 5662.0 5704.1 5762.4 5638.7 5643.6 5646.1 5648.4 5651.8 5385.3 5567.2 5623.7 5627.8 5670.1 4947.1 4953.7 4959.9 4961.2 4965.7 23153.4 23896.4 24002.9 24050.0 24289.5 2424.9 2435.2 2445.3 2690.9 2729.2 14759.9 14946.5 15006.8 15168.8 15534.2 1776.0 1798.4 1805.7 1880.1 1903.0 101327.0 111432.0 113730.0 118600.0 118624.4 8421.5 10047.3 10498.2 10600.6 10654.1 13015.8 13052.9 13056.3 13087.9 13120.2 8111.4 8127.3 8242.5 8243.8 8291.6 6754.9 8186.3 8311.1 8321.3 8336.3 6098.1 6234.5 6238.6 6245.7 6254.5 1691.9 1727.7 1803.5 1835.9 1836.2 755.5 811.2 872.6 872.7 887.2 20069.0 20480.3 20738.9 20806.9 20840.6 815.3 900.3 905.5 916.4 947.4 10355.5 11017.2 11100.8 11116.5 11144.4 606049.0 609970.0 611583.0 619004.0 627809.0 86287.6 94706.0 101900.5 102185.0 106106.0 937.1 963.8 984.2 985.6 1007.2 17602.3 17925.6 18435.5 18653.6 18887.9 48396.8 55287.7 55624.7 56484.7 57332.1 64409.7 77797.0 77902.6 78013.4 79463.6 7867.0 7894.2 7979.2 8072.8 8136.9 8751.0 11895.6 12623.5 12650.6 12700.4 10900.1 10974.6 11284.1 11315.8 11449.7 10901.2 10978.9 10984.6 11380.5 11625.5 6308.7 10787.8 11085.3 11139.2 11724.9 7867.2 7967.1 8104.8 8288.7 8383.8 10736.7 10741.7 10826.6 10922.5 11229.7 09:05:56 INFO - PROCESS | 34356 | 09:05:56 INFO - PROCESS | 34356 | [#3] mootools.html Cycles:180 Average:5281.61 Median:5069.75 stddev:2916.43 (57.5%) stddev-sans-first:2909.17 09:05:56 INFO - PROCESS | 34356 | Values: 1288.5 1425.5 1462.4 1583.3 1797.6 7320.6 7614.8 8086.7 8240.9 8346.5 8014.3 8089.4 8096.2 8104.7 8119.1 1899.9 2256.7 2286.2 2289.1 2289.7 2259.6 2310.0 2325.4 2326.0 2327.9 2259.2 2268.2 2290.2 2293.0 2297.6 1849.7 2243.5 2286.9 2288.4 2294.5 1882.7 2296.0 2313.8 2317.4 2319.8 8016.4 8209.0 8209.2 8212.5 8215.3 4947.8 5114.5 5131.4 5142.1 5152.3 3343.8 3496.1 3541.5 3564.5 3572.8 1043.8 1067.9 1094.2 1098.1 1108.7 10489.7 10593.3 10597.4 10607.9 10619.2 4175.1 4347.9 4411.5 4418.7 4439.9 8570.1 8576.1 8583.5 8590.4 8590.8 5051.1 5055.3 5060.6 5068.2 5071.3 3986.3 4252.3 4384.6 4669.3 4742.8 3594.2 3638.7 3681.7 3693.6 3694.2 1129.9 1134.3 1139.3 1143.9 1144.7 733.6 823.7 866.7 882.6 897.2 4400.8 5489.6 5505.8 5521.6 5545.1 427.9 444.9 451.2 452.3 458.0 3723.6 3751.3 3765.5 3766.6 3817.9 9566.9 9844.1 9925.5 9952.5 9997.3 9890.2 9899.9 9908.9 9914.2 9934.4 7516.4 7523.7 7574.4 7635.5 7746.5 5318.9 5376.4 5417.2 5498.8 5658.7 9007.7 9222.0 9228.2 9230.6 9251.6 8518.5 8966.9 9168.2 9234.7 9253.3 7103.8 7328.1 7345.0 7358.2 7367.8 7276.5 7459.1 7629.7 7689.4 7697.9 6851.3 6907.4 6926.0 6940.3 6977.7 5763.3 6761.0 6829.9 6836.9 7038.4 6448.2 6529.1 6561.4 6623.5 6685.6 4026.4 4559.9 4624.3 4732.5 4868.9 4653.3 4821.4 4870.7 4893.9 4903.3 09:05:56 INFO - PROCESS | 34356 | 09:05:56 INFO - PROCESS | 34356 | [#4] prototype.html Cycles:180 Average:4240.35 Median:4812.95 stddev:1285.95 (26.7%) stddev-sans-first:1254.18 09:05:56 INFO - PROCESS | 34356 | Values: 248.8 269.6 299.4 308.2 314.3 4946.0 5049.5 5060.9 5137.5 5150.7 3641.8 3973.0 5029.4 5035.9 5096.5 3920.4 3931.3 3942.8 3945.3 3951.6 3264.7 3595.0 3840.5 3943.8 3948.3 3261.8 3893.4 3907.8 3925.3 3946.8 3906.7 3908.1 3910.0 3922.3 3928.6 3039.8 3443.3 3447.9 3478.8 3481.8 4875.0 4899.9 4924.7 5015.9 5041.4 3749.6 3882.9 4454.0 4464.7 4485.4 5290.9 5295.3 5300.9 5316.7 5336.9 3453.3 3794.6 3805.2 3820.8 3838.7 4652.5 4701.6 5492.8 5522.7 5616.8 3153.0 3748.6 3772.4 3780.9 3789.0 3808.8 3822.7 3835.3 3844.5 3844.9 3657.5 4265.0 4345.2 4400.7 4445.3 4641.6 5354.5 5376.7 5384.5 5387.9 1063.5 1139.6 1157.5 1163.3 1191.4 756.9 791.9 840.4 843.5 846.6 4239.2 4893.7 4893.9 4901.9 4907.0 4540.1 4576.8 4670.8 4981.3 5310.7 4557.6 4823.0 4854.9 4856.6 4911.2 2536.6 2562.8 2687.3 2714.6 2749.9 4242.2 5221.3 5223.6 5432.5 5432.5 4529.7 4897.7 5279.9 5283.7 5300.7 5400.9 5404.4 5436.2 5458.8 5461.5 4207.1 4838.8 5114.7 5145.3 5158.7 4462.5 4840.5 4965.8 5092.3 5165.3 4802.9 5083.2 5368.5 5429.7 5493.8 3323.2 3469.4 3553.3 3568.7 3570.0 4408.6 4970.0 4996.2 5205.4 5469.5 4443.4 5347.9 5353.4 5353.7 5359.8 5044.0 5308.8 5331.2 5332.7 5345.5 4116.2 4728.8 5210.2 5239.0 5256.4 5203.4 5321.9 5324.0 5327.5 5443.2 4136.5 4513.8 5013.5 5270.6 5323.8 09:05:56 INFO - PROCESS | 34356 | 09:05:56 INFO - PROCESS | 34356 | [#5] yui.html Cycles:180 Average:19593.78 Median:4424.95 stddev:28158.18 (636.4%) stddev-sans-first:28201.36 09:05:56 INFO - PROCESS | 34356 | Values: 679.8 688.9 743.7 767.0 774.2 13668.5 14091.4 14369.4 14371.5 14736.3 12728.2 13043.8 13046.4 13049.1 13090.3 642.9 799.3 814.2 814.4 816.3 650.9 786.2 807.3 807.8 813.7 858.0 858.7 859.7 862.2 862.7 842.9 852.1 853.3 853.5 854.1 501.6 513.3 617.5 631.1 639.5 13878.0 13909.8 13921.8 13927.9 13954.8 12449.1 12537.5 12638.4 12665.4 12961.9 10481.7 10817.2 11089.9 11177.6 11341.0 4278.4 4284.9 4320.7 4340.9 4487.4 83384.0 83427.0 86657.7 88818.0 92143.0 10247.8 10384.0 10408.2 10484.5 10573.0 19736.7 19782.0 19819.0 20077.1 20111.1 7331.1 7514.5 7662.7 7685.6 7788.8 3602.2 4152.5 4292.8 4300.9 4424.1 3328.2 3407.1 3407.4 3447.8 3452.9 558.8 582.9 593.8 636.7 637.5 445.6 640.2 661.2 663.1 671.6 8658.5 8877.4 8972.4 9125.1 9141.7 570.8 638.7 646.7 652.8 673.1 3681.8 4425.8 4435.2 4481.0 4559.2 73433.0 80859.0 100581.0 103988.0 108103.0 84914.0 87855.6 88133.0 88616.0 89375.6 85814.7 87795.6 88371.1 90218.0 95823.0 9228.1 9391.2 9460.2 9629.2 9633.8 37646.6 42921.1 43993.1 48836.5 52529.5 63782.4 65030.4 75036.1 76229.6 78507.2 10102.7 12630.2 12647.2 12855.8 13189.0 11682.4 13033.6 14105.2 14152.4 14754.7 13075.3 13203.0 13358.8 13535.5 13791.2 10758.6 12521.5 13103.5 13196.3 13344.9 11772.7 12161.8 12162.7 12539.5 13222.0 11188.4 11221.4 11819.5 11861.0 11870.9 10472.4 11282.4 11380.5 11521.3 11616.9 09:05:56 INFO - PROCESS | 34356 | -------- Summary: end -------- 09:05:56 INFO - PROCESS | 34356 | 09:05:56 INFO - TEST-INFO | 34356: exit 0 09:05:56 INFO - TEST-OK | dromaeo_css | took 1119416ms 09:05:56 INFO - TEST-START | kraken 09:05:56 INFO - operating with platform_type : mac_ 09:05:56 INFO - Initialising browser for kraken test... 09:05:56 INFO - TEST-INFO | started process 34358 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/tmph8HV8D/profile http://localhost:58462/getInfo.html) 09:05:59 INFO - PROCESS | 34358 | __metrics Screen width/height:1600/1200 09:05:59 INFO - PROCESS | 34358 | colorDepth:24 09:05:59 INFO - PROCESS | 34358 | Browser inner width/height: 1024/689 09:05:59 INFO - PROCESS | 34358 | __metrics 09:05:59 INFO - PROCESS | 34358 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:05:59 INFO - PROCESS | 34358 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:06:00 INFO - TEST-INFO | 34358: exit 0 09:06:00 INFO - Browser initialized. 09:06:00 INFO - Running cycle 1/1 for kraken test... 09:06:00 INFO - TEST-INFO | started process 34359 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/T/tmph8HV8D/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:06:12 INFO - PROCESS | 34359 | RSS: Main: 222384128 09:06:12 INFO - PROCESS | 34359 | 09:06:15 INFO - PROCESS | 34359 | Cycle 1(1): loaded http://localhost:58462/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58462/tests/kraken/driver.html?testName=audio-beat-detection) 09:06:16 INFO - PROCESS | 34359 | RSS: Main: 272228352 09:06:16 INFO - PROCESS | 34359 | 09:06:19 INFO - PROCESS | 34359 | Cycle 1(1): loaded http://localhost:58462/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58462/tests/kraken/driver.html?testName=audio-dft) 09:06:19 INFO - PROCESS | 34359 | RSS: Main: 283160576 09:06:19 INFO - PROCESS | 34359 | 09:06:22 INFO - PROCESS | 34359 | Cycle 1(1): loaded http://localhost:58462/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58462/tests/kraken/driver.html?testName=audio-fft) 09:06:23 INFO - PROCESS | 34359 | RSS: Main: 380461056 09:06:23 INFO - PROCESS | 34359 | 09:06:25 INFO - PROCESS | 34359 | Cycle 1(1): loaded http://localhost:58462/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58462/tests/kraken/driver.html?testName=audio-oscillator) 09:06:26 INFO - PROCESS | 34359 | RSS: Main: 283607040 09:06:26 INFO - PROCESS | 34359 | 09:06:28 INFO - PROCESS | 34359 | Cycle 1(1): loaded http://localhost:58462/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58462/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:06:29 INFO - PROCESS | 34359 | RSS: Main: 284753920 09:06:29 INFO - PROCESS | 34359 | 09:06:31 INFO - PROCESS | 34359 | Cycle 1(1): loaded http://localhost:58462/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58462/tests/kraken/driver.html?testName=imaging-darkroom) 09:06:32 INFO - PROCESS | 34359 | RSS: Main: 324911104 09:06:32 INFO - PROCESS | 34359 | 09:06:35 INFO - PROCESS | 34359 | Cycle 1(1): loaded http://localhost:58462/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58462/tests/kraken/driver.html?testName=imaging-desaturate) 09:06:35 INFO - PROCESS | 34359 | RSS: Main: 325537792 09:06:35 INFO - PROCESS | 34359 | 09:06:38 INFO - PROCESS | 34359 | Cycle 1(1): loaded http://localhost:58462/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58462/tests/kraken/driver.html?testName=json-parse-financial) 09:06:39 INFO - PROCESS | 34359 | RSS: Main: 322826240 09:06:39 INFO - PROCESS | 34359 | 09:06:40 INFO - PROCESS | 34359 | Cycle 1(1): loaded http://localhost:58462/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58462/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:06:41 INFO - PROCESS | 34359 | RSS: Main: 299253760 09:06:41 INFO - PROCESS | 34359 | 09:06:43 INFO - PROCESS | 34359 | Cycle 1(1): loaded http://localhost:58462/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58462/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:06:44 INFO - PROCESS | 34359 | RSS: Main: 299978752 09:06:44 INFO - PROCESS | 34359 | 09:06:46 INFO - PROCESS | 34359 | Cycle 1(1): loaded http://localhost:58462/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58462/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:06:46 INFO - PROCESS | 34359 | RSS: Main: 302346240 09:06:46 INFO - PROCESS | 34359 | 09:06:49 INFO - PROCESS | 34359 | Cycle 1(1): loaded http://localhost:58462/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58462/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:06:50 INFO - PROCESS | 34359 | RSS: Main: 311873536 09:06:50 INFO - PROCESS | 34359 | 09:06:53 INFO - PROCESS | 34359 | Cycle 1(1): loaded http://localhost:58462/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58462/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:06:54 INFO - PROCESS | 34359 | RSS: Main: 311365632 09:06:54 INFO - PROCESS | 34359 | 09:06:55 INFO - PROCESS | 34359 | Cycle 1(1): loaded http://localhost:58462/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58462/tests/kraken/driver.html?testName=ai-astar) 09:06:56 INFO - PROCESS | 34359 | RSS: Main: 355389440 09:06:56 INFO - PROCESS | 34359 | 09:06:56 INFO - PROCESS | 34359 | __start_tp_report 09:06:56 INFO - PROCESS | 34359 | _x_x_mozilla_page_load 09:06:56 INFO - PROCESS | 34359 | _x_x_mozilla_page_load_details 09:06:56 INFO - PROCESS | 34359 | |i|pagename|runs| 09:06:56 INFO - PROCESS | 34359 | |0;ai-astar;116;114;115;113;113;115;114;115;114;114 09:06:56 INFO - PROCESS | 34359 | |1;audio-beat-detection;155;138;139;146;110;109;111;109;147;137 09:06:56 INFO - PROCESS | 34359 | |2;audio-dft;183;183;182;186;182;202;218;182;185;185 09:06:56 INFO - PROCESS | 34359 | |3;audio-fft;82;71;60;89;62;61;69;82;110;84 09:06:56 INFO - PROCESS | 34359 | |4;audio-oscillator;89;71;95;71;90;98;100;68;69;92 09:06:56 INFO - PROCESS | 34359 | |5;imaging-gaussian-blur;88;85;88;85;87;84;90;86;87;82 09:06:56 INFO - PROCESS | 34359 | |6;imaging-darkroom;92;93;93;94;94;94;92;94;94;94 09:06:56 INFO - PROCESS | 34359 | |7;imaging-desaturate;83;80;83;79;82;83;85;86;83;81 09:06:56 INFO - PROCESS | 34359 | |8;json-parse-financial;58;54;52;54;55;59;59;56;56;57 09:06:56 INFO - PROCESS | 34359 | |9;json-stringify-tinderbox;68;74;79;77;75;74;75;74;74;74 09:06:56 INFO - PROCESS | 34359 | |10;stanford-crypto-aes;85;81;84;83;86;91;101;102;79;82 09:06:56 INFO - PROCESS | 34359 | |11;stanford-crypto-ccm;159;154;142;139;163;153;155;147;195;157 09:06:56 INFO - PROCESS | 34359 | |12;stanford-crypto-pbkdf2;164;165;162;165;166;164;164;164;165;163 09:06:56 INFO - PROCESS | 34359 | |13;stanford-crypto-sha256-iterative;66;68;71;69;68;68;67;67;66;67 09:06:56 INFO - PROCESS | 34359 | __end_tp_report 09:06:56 INFO - PROCESS | 34359 | __start_cc_report 09:06:56 INFO - PROCESS | 34359 | _x_x_mozilla_cycle_collect,2000 09:06:56 INFO - PROCESS | 34359 | __end_cc_report 09:06:56 INFO - PROCESS | 34359 | __startTimestamp1482340016071__endTimestamp 09:06:56 INFO - PROCESS | 34359 | 09:06:56 INFO - PROCESS | 34359 | ------- Summary: start ------- 09:06:56 INFO - PROCESS | 34359 | Number of tests: 14 09:06:56 INFO - PROCESS | 34359 | 09:06:56 INFO - PROCESS | 34359 | [#0] ai-astar Cycles:10 Average:114.30 Median:114.50 stddev:0.95 (0.8%) stddev-sans-first:0.78 09:06:56 INFO - PROCESS | 34359 | Values: 116.0 114.0 115.0 113.0 113.0 115.0 114.0 115.0 114.0 114.0 09:06:56 INFO - PROCESS | 34359 | 09:06:56 INFO - PROCESS | 34359 | [#1] audio-beat-detection Cycles:10 Average:130.10 Median:138.50 stddev:18.28 (13.2%) stddev-sans-first:17.02 09:06:56 INFO - PROCESS | 34359 | Values: 155.0 138.0 139.0 146.0 110.0 109.0 111.0 109.0 147.0 137.0 09:06:56 INFO - PROCESS | 34359 | 09:06:56 INFO - PROCESS | 34359 | [#2] audio-dft Cycles:10 Average:188.80 Median:185.00 stddev:11.88 (6.4%) stddev-sans-first:12.41 09:06:56 INFO - PROCESS | 34359 | Values: 183.0 183.0 182.0 186.0 182.0 202.0 218.0 182.0 185.0 185.0 09:06:56 INFO - PROCESS | 34359 | 09:06:56 INFO - PROCESS | 34359 | [#3] audio-fft Cycles:10 Average:77.00 Median:76.50 stddev:15.64 (20.4%) stddev-sans-first:16.49 09:06:56 INFO - PROCESS | 34359 | Values: 82.0 71.0 60.0 89.0 62.0 61.0 69.0 82.0 110.0 84.0 09:06:56 INFO - PROCESS | 34359 | 09:06:56 INFO - PROCESS | 34359 | [#4] audio-oscillator Cycles:10 Average:84.30 Median:89.50 stddev:12.98 (14.5%) stddev-sans-first:13.65 09:06:56 INFO - PROCESS | 34359 | Values: 89.0 71.0 95.0 71.0 90.0 98.0 100.0 68.0 69.0 92.0 09:06:56 INFO - PROCESS | 34359 | 09:06:56 INFO - PROCESS | 34359 | [#5] imaging-gaussian-blur Cycles:10 Average:86.20 Median:87.00 stddev:2.30 (2.6%) stddev-sans-first:2.35 09:06:56 INFO - PROCESS | 34359 | Values: 88.0 85.0 88.0 85.0 87.0 84.0 90.0 86.0 87.0 82.0 09:06:56 INFO - PROCESS | 34359 | 09:06:56 INFO - PROCESS | 34359 | [#6] imaging-darkroom Cycles:10 Average:93.40 Median:94.00 stddev:0.84 (0.9%) stddev-sans-first:0.73 09:06:56 INFO - PROCESS | 34359 | Values: 92.0 93.0 93.0 94.0 94.0 94.0 92.0 94.0 94.0 94.0 09:06:56 INFO - PROCESS | 34359 | 09:06:56 INFO - PROCESS | 34359 | [#7] imaging-desaturate Cycles:10 Average:82.50 Median:83.00 stddev:2.12 (2.6%) stddev-sans-first:2.24 09:06:56 INFO - PROCESS | 34359 | Values: 83.0 80.0 83.0 79.0 82.0 83.0 85.0 86.0 83.0 81.0 09:06:56 INFO - PROCESS | 34359 | 09:06:56 INFO - PROCESS | 34359 | [#8] json-parse-financial Cycles:10 Average:56.00 Median:56.50 stddev:2.31 (4.1%) stddev-sans-first:2.33 09:06:56 INFO - PROCESS | 34359 | Values: 58.0 54.0 52.0 54.0 55.0 59.0 59.0 56.0 56.0 57.0 09:06:56 INFO - PROCESS | 34359 | 09:06:56 INFO - PROCESS | 34359 | [#9] json-stringify-tinderbox Cycles:10 Average:74.40 Median:74.50 stddev:2.80 (3.8%) stddev-sans-first:1.76 09:06:56 INFO - PROCESS | 34359 | Values: 68.0 74.0 79.0 77.0 75.0 74.0 75.0 74.0 74.0 74.0 09:06:56 INFO - PROCESS | 34359 | 09:06:56 INFO - PROCESS | 34359 | [#10] stanford-crypto-aes Cycles:10 Average:87.40 Median:83.50 stddev:8.10 (9.7%) stddev-sans-first:8.54 09:06:56 INFO - PROCESS | 34359 | Values: 85.0 81.0 84.0 83.0 86.0 91.0 101.0 102.0 79.0 82.0 09:06:56 INFO - PROCESS | 34359 | 09:06:56 INFO - PROCESS | 34359 | [#11] stanford-crypto-ccm Cycles:10 Average:156.40 Median:156.00 stddev:15.49 (9.9%) stddev-sans-first:16.40 09:06:56 INFO - PROCESS | 34359 | Values: 159.0 154.0 142.0 139.0 163.0 153.0 155.0 147.0 195.0 157.0 09:06:56 INFO - PROCESS | 34359 | 09:06:56 INFO - PROCESS | 34359 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.20 Median:164.50 stddev:1.14 (0.7%) stddev-sans-first:1.20 09:06:56 INFO - PROCESS | 34359 | Values: 164.0 165.0 162.0 165.0 166.0 164.0 164.0 164.0 165.0 163.0 09:06:56 INFO - PROCESS | 34359 | 09:06:56 INFO - PROCESS | 34359 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:68.00 stddev:1.49 (2.2%) stddev-sans-first:1.45 09:06:56 INFO - PROCESS | 34359 | Values: 66.0 68.0 71.0 69.0 68.0 68.0 67.0 67.0 66.0 67.0 09:06:56 INFO - PROCESS | 34359 | -------- Summary: end -------- 09:06:56 INFO - PROCESS | 34359 | 09:06:57 INFO - TEST-INFO | 34359: exit 0 09:06:57 INFO - TEST-OK | kraken | took 60616ms 09:06:57 INFO - SUITE-END | took 1180s 09:06:57 INFO - Completed test suite (00:19:40) 09:06:57 INFO - javascript benchmark 09:06:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2653.7744291735203, 3221.081991632384, 3248.2529018325768, 3250.2199472126695, 3302.0589132097357, 4572.954270457294, 5415.404305526394, 5470.8085217017415, 5482.753276102575, 5512.669239845607, 5441.2654291670615, 5470.0, 5487.780488780444, 5508.20131080993, 5511.200875873006, 4848.733319667417, 5013.799448022084, 5053.570446512051, 5110.463401342861, 5126.384833819928, 4116.773577453238, 5016.398032236137, 5025.974870125644, 5048.217526283432, 5102.795888164487, 5015.448300686921, 5081.314022606938, 5108.157154069574, 5111.744412779346, 5124.436312005676, 5055.216441451581, 5085.593152547787, 5089.745512724386, 5090.516400941905, 5142.023015627019, 5068.214426763858, 5090.898182036375, 5105.336306280197, 5110.0, 5136.922946155811, 5354.732263386815, 5397.676139431607, 5422.810201642963, 5445.700486473243, 5519.69641669708, 5877.118432235156, 5893.381194974509, 5902.996490596609, 5960.523158147338, 6004.219451471325, 6098.420650038283, 6100.450959413674, 6109.297430795471, 6116.633002019848, 6122.47958923492, 5448.073827449343, 5502.80740174092, 5581.413951535112, 5583.0, 5589.832305030855, 6104.732011601605, 6131.663631601249, 6135.07955838608, 6141.62210784067, 6145.324014358417, 5834.329052159056, 5889.911651325234, 5897.67562784051, 5904.025835737055, 5932.40675932412, 5992.790252341234, 5993.0710739835395, 6046.123312119718, 6084.756609735561, 6105.0, 5766.04860198071, 5809.535237181015, 5939.851503712355, 5972.462478376967, 5978.790742323998, 5896.3219229788265, 5899.7050147492455, 5953.166556682068, 5960.880782384328, 6071.119687645267, 4779.091972525209, 4791.281307803857, 4794.712317260975, 4800.399950006249, 4817.9518204818205, 4259.74441533509, 4275.508316543575, 4283.892902677396, 4283.993261583525, 4284.143171365677, 5509.090999984957, 5570.6657600544095, 5576.749046292926, 5586.55307575393, 5593.944060559343, 5941.197938278277, 6062.484688801413, 6081.0, 6087.482563982023, 6094.99432593626, 5631.971840140855, 5649.971750141305, 5654.236678048493, 5667.319921609434, 5670.801521946794, 5038.118329292359, 5228.92156617651, 5233.4243233244315, 5234.398044224963, 5253.028189784902, 6193.876122477615, 6201.348858369806, 6219.844503887439, 6262.1546091278005, 6324.557280990471, 6080.939190608037, 6081.057436097412, 6102.908456373247, 6105.6947152641305, 6110.2973158085615, 6206.937930620817, 6240.500759939103, 6243.063540468966, 6255.812325630239, 6269.341719119427, 4667.953320466752, 5740.971295143498, 5796.739146738322, 5822.912656310244, 5837.09525023622, 5934.050551911846, 6034.849128771816, 6042.335343112342, 6043.879122417439, 6059.6364218147055, 5128.666636668605, 5524.116141417354, 5830.271216097988, 5831.300243970751, 5833.6208146472045, 5426.8371948841595, 5522.144067669517, 5733.369329373849, 5809.0, 5824.242848429677, 5340.839774806762, 5629.437056294338, 5715.771369145187, 5757.769689212552, 5762.279715035621, 5798.159266906359, 5819.388964158702, 5823.0, 5828.825135245949, 5829.446202610745, 5521.944780552143, 5578.804741834017, 5682.77268909239, 5693.601447898773, 5703.714814259353], "value": 5476.494754698794, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [456.5480174627118, 478.1512814753809, 499.0991260774302, 499.8875253068058, 506.6554742774913, 27062.594061089076, 38246.0, 39464.60535394645, 39469.0, 39538.2092358153, 40397.394039089435, 40601.59398406015, 40649.59350406495, 40754.0, 40959.180816383654, 8240.50556966581, 9187.95406022969, 9381.577828997712, 9389.953050234773, 9411.95294023529, 8260.173982601751, 10885.83671244932, 11067.225294229407, 11183.44082795857, 11191.216614836965, 10962.616308429206, 10984.95642913922, 11023.228374013823, 11119.666410007712, 11240.943795281011, 11070.28043177191, 11136.777264454666, 11141.94151555601, 11173.050290725298, 11178.888211117866, 10958.671239862817, 11019.228653994223, 11064.502097405637, 11069.225154239206, 11072.0, 37987.43018854692, 38261.617383826364, 38364.23271534582, 38683.8065809672, 38693.0, 3209.0, 3267.493538501536, 3267.950980735291, 3297.48888922215, 3328.484084966834, 26488.0, 28570.571441428394, 28915.746370517434, 29007.274818129503, 29296.267593310124, 3024.228821650487, 3092.5361195820583, 3108.8601012954246, 3153.684631536828, 3159.352332771776, 421276.0, 498427.0, 498538.9999999964, 499289.0, 501288.0, 32232.677673222966, 57433.42566574364, 57977.13034304489, 58026.7098664504, 58106.0, 33240.002799916045, 33954.83022584871, 33996.830015849766, 34288.31423371568, 34364.969050928514, 13892.583222503341, 14026.719465610631, 14037.368318425695, 14041.0, 14050.929745351412, 10829.133669306437, 11086.113110951104, 11107.666769996913, 11858.347790871585, 12029.03767698582, 6027.758889644453, 7198.964005180045, 7280.599567023864, 7505.549667019996, 7760.301572858357, 3156.37446714942, 3200.2959348943195, 3211.951820722691, 3233.3533293341425, 3241.0438920518504, 848.3806821020689, 866.6966561703354, 886.5124181700039, 953.9713808585753, 976.770458942148, 27463.0, 28211.71788282091, 28341.29146771306, 29186.978455753946, 29245.561316580268, 1018.6078359831519, 1025.4411345816548, 1041.9010194031555, 1088.276296262992, 1109.4563663804677, 14332.928335358465, 14850.554483365517, 14908.956373054, 15162.31769570372, 15301.770473442908, 642573.0, 646667.0, 648165.0, 649006.0, 649136.0, 54248.728756355966, 54829.72585137049, 54864.45135548594, 54988.72505637446, 55083.0, 1212.563477148226, 1257.082329899197, 1331.4274861809515, 1350.2843492948557, 1371.9314034298445, 27249.9587576299, 29618.0, 29936.80252789864, 29971.0, 30501.847490762404, 46709.766451168885, 47052.29421558748, 47165.764171178926, 47227.0, 47237.055258895314, 545223.0, 547827.0, 547944.0, 548042.0, 548428.0, 25918.96324147013, 26822.463550729266, 26837.0, 27468.90124395082, 27507.58738618882, 35215.647843521234, 44079.33880991724, 44168.33747493855, 44699.55300446954, 45251.77374113108, 29273.85363073171, 36211.45682814707, 36619.81690091533, 36795.0, 37616.87149385523, 34208.97373078712, 34377.0, 36617.81691091527, 36996.81501592582, 37936.24127517379, 36918.0, 36951.0, 36973.26053478861, 37850.810745946095, 37862.24275514529, 31387.529187062668, 32669.836650816593, 33600.327993440485, 33638.83180584081, 33655.32689346249, 19753.0, 24684.38289042788, 25503.6174457387, 25901.48197036011, 25943.870280648476], "value": 18156.668330439257, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.26863586468403, 348.7966515521451, 370.49242537723325, 379.51043154330904, 400.24353970994827, 14296.142231466107, 14772.335244913978, 15086.547403577883, 17933.0, 19246.03769811511, 17197.656046879056, 17376.218070186842, 18840.057997100157, 19582.433405327574, 19632.901835490837, 5461.481159289871, 5797.507211886995, 5799.420057994188, 5802.100674395476, 5807.854803629922, 5504.394516603171, 5661.0, 5662.037453632872, 5704.058830292996, 5762.366139724627, 5638.718064096779, 5643.576731745115, 5646.124850648135, 5648.378678345399, 5651.802186923458, 5385.272988146608, 5567.246317410003, 5623.718814059281, 5627.774889004435, 5670.149477578376, 4947.084789313988, 4953.727544985024, 4959.925601115986, 4961.18140506816, 4965.677230979974, 23153.421164470856, 23896.402589935216, 24002.87998559996, 24050.0, 24289.514209715708, 2424.9272521824373, 2435.2085572189108, 2445.3030886197525, 2690.8654567271756, 2729.2221716810614, 14759.92620036893, 14946.476873309382, 15006.804959394587, 15168.848311516964, 15534.223288835514, 1775.9822401775946, 1798.3885478937093, 1805.702059160237, 1880.069365664005, 1903.0389653225034, 101327.0, 111432.0, 113730.0, 118600.0, 118624.40687796506, 8421.536815475207, 10047.296689231829, 10498.212634052477, 10600.628977985734, 10654.094401975764, 13015.804762928563, 13052.934735326262, 13056.34718264083, 13087.934560327138, 13120.212787232796, 8111.432199746079, 8127.309178719756, 8242.464239824392, 8243.83512329762, 8291.626876790559, 6754.932450675528, 8186.304164145995, 8311.127331630212, 8321.334293256525, 8336.333093352518, 6098.1157732128595, 6234.470070044006, 6238.563300569007, 6245.656488893154, 6254.468370188494, 1691.9407820726215, 1727.6783260805494, 1803.5220666523383, 1835.8990255536073, 1836.2287839107607, 755.47494491328, 811.2293321344746, 872.5942436766934, 872.6691795012656, 887.2148148888151, 20069.0, 20480.283190088572, 20738.896305518378, 20806.895965520074, 20840.58318833615, 815.311062152492, 900.257287737606, 905.5110240470204, 916.3906002508257, 947.4457442396138, 10355.482225888825, 11017.173711971634, 11100.833487497694, 11116.49975751077, 11144.387058711687, 606049.0, 609970.0, 611583.0, 619004.0, 627809.0, 86287.56856215678, 94706.0, 101900.49049754853, 102185.0, 106106.0, 937.1237892570468, 963.8409662405764, 984.2077127912099, 985.571276494727, 1007.1690855044618, 17602.29590816353, 17925.551861203574, 18435.53911152232, 18653.626927461646, 18887.90556047211, 48396.75801620969, 55287.723561381936, 55624.72187639036, 56484.71757641186, 57332.14001789893, 64409.67795160994, 77797.0, 77902.6104869472, 78013.43973120619, 79463.60268198622, 7867.0, 7894.210578942289, 7979.202079791974, 8072.757817265491, 8136.87794683092, 8750.993635731718, 11895.643130706092, 12623.495060197489, 12650.557230496888, 12700.36498175106, 10900.127989760958, 10974.561017559208, 11284.097272218367, 11315.830262545904, 11449.713757155805, 10901.236913416302, 10978.890211098107, 10984.615538456115, 11380.487878045342, 11625.534978600761, 6308.684565771785, 10787.784244315228, 11085.27945683528, 11139.217725163935, 11724.941375293069, 7867.190546174526, 7967.083785364757, 8104.83790324202, 8288.668453262044, 8383.790405239917, 10736.73158171052, 10741.677749667522, 10826.566937322734, 10922.508487118257, 11229.71925701864], "value": 9327.324707140673, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1288.4717265920974, 1425.486824743092, 1462.3857979648549, 1583.2558697412212, 1797.5865550923288, 7320.633968301584, 7614.771556853297, 8086.71696490623, 8240.876386854203, 8346.499210047401, 8014.278714915657, 8089.393295502831, 8096.230858068474, 8104.6758129674745, 8119.106898241203, 1899.9145038473303, 2256.7404748453932, 2286.1541229745017, 2289.050044231642, 2289.69089172962, 2259.5706815705043, 2310.0, 2325.430269583954, 2326.0346956013236, 2327.9418014549688, 2259.2318611672026, 2268.1721171772265, 2290.1755368067484, 2293.025464177718, 2297.6094064009158, 1849.6670599292122, 2243.4615692233747, 2286.942826429336, 2288.439332363582, 2294.5066810635626, 1882.6611209982198, 2296.0, 2313.8148948084113, 2317.3627252505526, 2319.8028167605776, 8016.438849280553, 8209.0, 8209.220124088202, 8212.507249564986, 8215.301699355561, 4947.826826061107, 5114.465660301932, 5131.358580177478, 5142.07442660321, 5152.304438900738, 3343.782654127498, 3496.1434448560267, 3541.4864844597646, 3564.518789963348, 3572.821358932043, 1043.7808060307345, 1067.9466026698633, 1094.162965331522, 1098.1269890437122, 1108.695108845074, 10489.685309440729, 10593.25847190697, 10597.364158150536, 10607.893921060844, 10619.203559732974, 4175.060611362419, 4347.891302717458, 4411.51473337939, 4418.690691651618, 4439.933400998988, 8570.057293697688, 8576.099509551408, 8583.527905965107, 8590.398672092893, 8590.78523036929, 5051.06555287273, 5055.292259083732, 5060.620453466005, 5068.214426763858, 5071.290019397287, 3986.3223252047223, 4252.255855225323, 4384.583464570861, 4669.34629151919, 4742.786574604151, 3594.2452085061905, 3638.6543278388513, 3681.7422780405113, 3693.575238847567, 3694.2057457646733, 1129.9040280280126, 1134.325076579434, 1139.2594813371375, 1143.9370834604176, 1144.6566030190909, 733.614852202598, 823.7075838077396, 866.6576702202594, 882.6337070115958, 897.2463130970017, 4400.823967041347, 5489.64317319373, 5505.752241149158, 5521.558275338044, 5545.085060964976, 427.9336702811069, 444.88210624184615, 451.24867096284316, 452.34635951051075, 457.95129154236423, 3723.553173619183, 3751.3060083884025, 3765.5104836371097, 3766.5856755756845, 3817.8854634361014, 9566.856497152548, 9844.114029737359, 9925.454100024568, 9952.452615106095, 9997.250206234561, 9890.159336456481, 9899.901000990041, 9908.851367229498, 9914.20686345105, 9934.403935763878, 7516.398688105048, 7523.736669216661, 7574.356179724675, 7635.503692259986, 7746.49647772893, 5318.8936221276135, 5376.381716102542, 5417.241586178017, 5498.835034948958, 5658.688772117408, 9007.729768106967, 9222.0, 9228.215601673664, 9230.630774769203, 9251.583678734607, 8518.48889066658, 8966.910330896868, 9168.174864262248, 9234.676786312446, 9253.259739220981, 7103.786886393416, 7328.120625524758, 7345.0, 7358.1538123116525, 7367.77896663101, 7276.527025743309, 7459.142198647225, 7629.656665450179, 7689.3848492121615, 7697.923020769721, 6851.3491218334175, 6907.378335949788, 6925.965370173117, 6940.305969403221, 6977.651117444006, 5763.308402991668, 6760.966195168992, 6829.863402732017, 6836.897446538405, 7038.436925046087, 6448.161738973903, 6529.118568993315, 6561.376669216417, 6623.536352455282, 6685.6322902240845, 4026.3960405939342, 4559.908801824011, 4624.260118381043, 4732.5030871759345, 4868.878278042936, 4653.255479123324, 4821.445533763662, 4870.732109733999, 4893.853184404474, 4903.33804936329], "value": 4209.033721872825, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [248.7823154739603, 269.6413769686316, 299.35489021159407, 308.2094427792712, 314.2834337710019, 4946.035523072993, 5049.495050494948, 5060.898782024362, 5137.511936366049, 5150.665206761562, 3641.7814931104153, 3972.9801350993203, 5029.396472423306, 5035.899282014358, 5096.541311281984, 3920.3730451862443, 3931.2923673738715, 3942.7831469269177, 3945.3490174121234, 3951.6443520083117, 3264.689854463822, 3595.0293420776507, 3840.5391353037535, 3943.8028098594955, 3948.3090459169675, 3261.7879837810465, 3893.396523538842, 3907.7655340679507, 3925.3326934421216, 3946.842126314944, 3906.7460615060213, 3908.065507573741, 3910.0, 3922.313595120857, 3928.5874983126646, 3039.8176109433507, 3443.3299849572936, 3447.9482807757904, 3478.808665523395, 3481.791092534456, 4875.013688265946, 4899.902001959977, 4924.729139897304, 5015.890148108197, 5041.420236672756, 3749.575161438663, 3882.9226363895464, 4453.977730111329, 4464.665150113722, 4485.416895803524, 5290.894182116336, 5295.338082739658, 5300.893982120336, 5316.680999140025, 5336.945276068703, 3453.3438646657055, 3794.582595914447, 3805.154500067465, 3820.808959552039, 3838.673712734421, 4652.464966528859, 4701.64737644678, 5492.780288788444, 5522.66863988154, 5616.803411880605, 3153.0, 3748.6438788315068, 3772.3775577030033, 3780.8676696315497, 3788.962110378916, 3808.790516521618, 3822.694184465236, 3835.2577906796773, 3844.500214972036, 3844.884653460401, 3657.4513822926774, 4264.957350426456, 4345.217860785026, 4400.73595584266, 4445.310976798602, 4641.605463535638, 5354.491323324278, 5376.7042812644895, 5384.542313903362, 5387.905192054067, 1063.5267306048734, 1139.5869122288302, 1157.4964890272759, 1163.2729544034978, 1191.3745283725939, 756.856197322507, 791.9128895821455, 840.3949156607233, 843.4854738609444, 846.627483907079, 4239.152169566038, 4893.681910675866, 4893.9021219576125, 4901.901961960741, 4906.975465122723, 4540.091981603692, 4576.832907608538, 4670.813167473331, 4981.302617633534, 5310.734463276821, 4557.567031132037, 4822.95177048225, 4854.854354369375, 4856.63575231852, 4911.214205727067, 2536.5814640584467, 2562.846718976468, 2687.314734742667, 2714.5520989036586, 2749.931251718723, 4242.17277630859, 5221.347331583552, 5223.582113430916, 5432.483914028161, 5432.483914028161, 4529.705569137996, 4897.681650692693, 5279.937636171012, 5283.656562323514, 5300.681959082467, 5400.945990540123, 5404.43253458397, 5436.238926550286, 5458.781648734006, 5461.535769459561, 4207.074443622399, 4838.758032118419, 5114.693118412907, 5145.305383773143, 5158.664686795346, 4462.48681401643, 4840.491748366371, 4965.801367945386, 5092.337996060489, 5165.251038599307, 4802.941514420309, 5083.237514372874, 5368.516833485005, 5429.67421954684, 5493.835184944458, 3323.1858194742445, 3469.3928562501965, 3553.253816698419, 3568.7145028398186, 3570.0, 4408.553252944646, 4970.0, 4996.226339649372, 5205.397301349325, 5469.453054694499, 4443.4445694288215, 5347.866303342292, 5353.384360798558, 5353.705546194997, 5359.758810853443, 5044.0, 5308.787648494017, 5331.200319952039, 5332.693490094952, 5345.465453454779, 4116.196163409698, 4728.808054563225, 5210.244514545294, 5239.030779305841, 5256.448072952285, 5203.375594928632, 5321.893562128813, 5324.0, 5327.467253274642, 5443.237946687388, 4136.544980052132, 4513.774311284488, 5013.523715247045, 5270.631055826056, 5323.840284791462], "value": 3825.370109874063, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.8300424893777, 688.8587839492903, 743.6653505922336, 766.9501482403647, 774.2180397798222, 13668.521601743942, 14091.365888535014, 14369.35337909794, 14371.496997605085, 14736.336864841081, 12728.236305821658, 13043.80434293484, 13046.412911418985, 13049.086563940504, 13090.280034598094, 642.8682120165355, 799.3165843204049, 814.1736137820118, 814.4298990706529, 816.2694388522269, 650.9153810004693, 786.1548835002367, 807.3379828540569, 807.8424707182102, 813.7029984055806, 858.0175698824874, 858.7252079334615, 859.70769938221, 862.1852349529697, 862.6678728689468, 842.8819965204846, 852.075498084579, 853.3216093205916, 853.5390888919976, 854.1159899503983, 501.5536172806169, 513.322414412975, 617.4905702795212, 631.0880777276836, 639.4500729372736, 13878.028538002343, 13909.79135312971, 13921.791173132411, 13927.930360348235, 13954.790678139836, 12449.128561000734, 12537.498500059986, 12638.431270592846, 12665.36673166338, 12961.935190324082, 10481.685549433529, 10817.242793004492, 11089.889101109047, 11177.552897884076, 11341.0, 4278.358246263055, 4284.892877678083, 4320.740755554677, 4340.891477713051, 4487.439070116236, 83384.0, 83427.0, 86657.70013449804, 88818.0, 92143.0, 10247.795044099077, 10384.0, 10408.219383546264, 10484.528196231187, 10573.0, 19736.70394944077, 19782.0, 19819.0, 20077.096530656156, 20111.095000725003, 7331.083614548182, 7514.511556748794, 7662.73180438682, 7685.615719214017, 7788.8052798679355, 3602.171500554887, 4152.522459917088, 4292.806823692941, 4300.913981720348, 4424.13729322779, 3328.2012317043736, 3407.063057659114, 3407.386670399302, 3447.775894566845, 3452.9482057769155, 558.770903929387, 582.8834233153386, 593.8040446652594, 636.7484843486797, 637.486823107403, 445.578927913119, 640.1997503121098, 661.2362721057186, 663.0650782396798, 671.5701950751522, 8658.497017495669, 8877.378583499092, 8972.41679290844, 9125.133112354315, 9141.725748227564, 570.7888081409822, 638.7125793392992, 646.7445359083135, 652.787843950718, 673.1165395058817, 3681.815909204529, 4425.756583387944, 4435.223835828717, 4481.0, 4559.202139625552, 73433.0, 80859.0, 100581.0, 103988.0, 108103.0, 84914.0, 87855.56072219598, 88133.0, 88616.0, 89375.55312223398, 85814.71277930836, 87795.56102219576, 88371.11628883629, 90218.0, 95823.0, 9228.123328283802, 9391.201747851235, 9460.195883349992, 9629.229661626912, 9633.807323853343, 37646.62353376431, 42921.141577168906, 43993.12013759643, 48836.511634883194, 52529.47470525246, 63782.36217637764, 65030.37424064247, 75036.12409689801, 76229.61885190761, 78507.21492784999, 10102.69691909244, 12630.242185468902, 12647.241165530098, 12855.814511533248, 13189.0, 11682.357470338873, 13033.608991730263, 14105.224212668401, 14152.363143658766, 14754.704905901606, 13075.280859552817, 13203.0, 13358.799618005543, 13535.458581656623, 13791.172529648253, 10758.623448179276, 12521.499140034297, 13103.541376051793, 13196.340182991004, 13344.866551334362, 11772.70568235801, 12161.756764864831, 12162.695932601755, 12539.498420063095, 13222.008349374028, 11188.440577971232, 11221.382823944761, 11819.527218911491, 11861.001710625647, 11870.940645296718, 10472.424016679155, 11282.379469129277, 11380.54477820878, 11521.251118677259, 11616.94191529037], "value": 7135.716495757357, "unit": "score"}], "name": "dromaeo_css", "value": 6885.567095544489, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 114.0, 115.0, 113.0, 113.0, 115.0, 114.0, 115.0, 114.0, 114.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 138.0, 139.0, 146.0, 110.0, 109.0, 111.0, 109.0, 147.0, 137.0], "value": 130.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 183.0, 182.0, 186.0, 182.0, 202.0, 218.0, 182.0, 185.0, 185.0], "value": 188.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 71.0, 60.0, 89.0, 62.0, 61.0, 69.0, 82.0, 110.0, 84.0], "value": 77.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 95.0, 71.0, 90.0, 98.0, 100.0, 68.0, 69.0, 92.0], "value": 84.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 85.0, 87.0, 84.0, 90.0, 86.0, 87.0, 82.0], "value": 86.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 94.0, 94.0, 94.0, 92.0, 94.0, 94.0, 94.0], "value": 93.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 80.0, 83.0, 79.0, 82.0, 83.0, 85.0, 86.0, 83.0, 81.0], "value": 82.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 54.0, 52.0, 54.0, 55.0, 59.0, 59.0, 56.0, 56.0, 57.0], "value": 56.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 74.0, 79.0, 77.0, 75.0, 74.0, 75.0, 74.0, 74.0, 74.0], "value": 74.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 81.0, 84.0, 83.0, 86.0, 91.0, 101.0, 102.0, 79.0, 82.0], "value": 87.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 154.0, 142.0, 139.0, 163.0, 153.0, 155.0, 147.0, 195.0, 157.0], "value": 156.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 162.0, 165.0, 166.0, 164.0, 164.0, 164.0, 165.0, 163.0], "value": 164.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 71.0, 69.0, 68.0, 68.0, 67.0, 67.0, 66.0, 67.0], "value": 67.7, "unit": "score"}], "name": "kraken", "value": 1462.7000000000003, "alertThreshold": 2.0}]} 09:06:57 INFO - Return code: 0 09:06:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:06:57 INFO - # TBPL SUCCESS # 09:06:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:06:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:06:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:06:57 INFO - 2016-12-21 09:06:57.856 screenresolution[34363:380231] starting screenresolution argv=screenresolution get 09:06:57 INFO - 2016-12-21 09:06:57.858 screenresolution[34363:380231] Display 0: 1600x1200x32@60 09:06:57 INFO - 2016-12-21 09:06:57.864 screenresolution[34364:380234] starting screenresolution argv=screenresolution list 09:06:57 INFO - Available Modes on Display 0 09:06:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:06:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:06:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:06:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:06:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:06:58 INFO - Intel Iris: 09:06:58 INFO - Chipset Model: Intel Iris 09:06:58 INFO - Type: GPU 09:06:58 INFO - Bus: Built-In 09:06:58 INFO - VRAM (Dynamic, Max): 1536 MB 09:06:58 INFO - Vendor: Intel (0x8086) 09:06:58 INFO - Device ID: 0x0a2e 09:06:58 INFO - Revision ID: 0x0009 09:06:58 INFO - Displays: 09:06:58 INFO - Display: 09:06:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:06:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:06:58 INFO - Display Serial Number: 1600x1200 60 09:06:58 INFO - Main Display: Yes 09:06:58 INFO - Mirror: Off 09:06:58 INFO - Online: Yes 09:06:58 INFO - Rotation: Supported 09:06:58 INFO - Return code: 0 09:06:58 INFO - Running post-action listener: _resource_record_post_action 09:06:58 INFO - [mozharness: 2016-12-21 17:06:58.100216Z] Finished run-tests step (success) 09:06:58 INFO - Running post-run listener: _resource_record_post_run 09:06:58 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 1469440; Write bytes: 658564608; Read time: 19; Write time: 1914 09:06:58 INFO - TinderboxPrint: CPU usage
53.4% 09:06:58 INFO - TinderboxPrint: I/O read bytes / time
1,469,440 / 19 09:06:58 INFO - TinderboxPrint: I/O write bytes / time
658,564,608 / 1,914 09:06:58 INFO - TinderboxPrint: CPU idle
2,259.1 (46.6%) 09:06:58 INFO - TinderboxPrint: CPU system
975.9 (20.1%) 09:06:58 INFO - TinderboxPrint: CPU user
1,614.7 (33.3%) 09:06:58 INFO - TinderboxPrint: Swap in / out
350,724,096 / 0 09:06:58 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 345684480; Write bytes: 345970176; Read time: 26787; Write time: 738 09:06:58 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 1432576; Write bytes: 305393664; Read time: 19; Write time: 1141 09:06:58 INFO - Running post-run listener: _upload_blobber_files 09:06:58 INFO - Blob upload gear active. 09:06:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:06:58 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 09:06:58 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'] 09:06:58 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 09:06:58 INFO - (blobuploader) - INFO - Open directory for files ... 09:06:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:06:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:06:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:07:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:07:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:07:00 INFO - (blobuploader) - INFO - Done attempting. 09:07:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:07:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:07:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:07:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:07:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:07:01 INFO - (blobuploader) - INFO - Done attempting. 09:07:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:07:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:07:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:07:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:07:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:07:03 INFO - (blobuploader) - INFO - Done attempting. 09:07:03 INFO - (blobuploader) - INFO - Iteration through files over. 09:07:03 INFO - Return code: 0 09:07:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:07:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:07:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7f01f633a292485782fb28530090fd5ed8b437f378b075ec8265638e3b0c0da08c4fe18985cee2ea7feec753332a056ff6abd3eeb524b3a04c6c73276bbc05ba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/295afc94facae963430a210e6988daba1b5b1c1701ebbf7cea70e08a28dd0b44fb197ad5cbc3c76f88dfafbd53522cb01c0d5b7abfe833f78bad57aa5e9fb3b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2d79aa9f2e9ecff020809288c13601aef567e9ea9befe90916c9476c3bb3104c4ffcce277dbe52fedba9835781ea8a7271416dd0e31f71b9f6b5b1ce33233f33"} 09:07:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:07:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:07:03 INFO - Contents: 09:07:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7f01f633a292485782fb28530090fd5ed8b437f378b075ec8265638e3b0c0da08c4fe18985cee2ea7feec753332a056ff6abd3eeb524b3a04c6c73276bbc05ba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/295afc94facae963430a210e6988daba1b5b1c1701ebbf7cea70e08a28dd0b44fb197ad5cbc3c76f88dfafbd53522cb01c0d5b7abfe833f78bad57aa5e9fb3b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2d79aa9f2e9ecff020809288c13601aef567e9ea9befe90916c9476c3bb3104c4ffcce277dbe52fedba9835781ea8a7271416dd0e31f71b9f6b5b1ce33233f33"} 09:07:03 INFO - Running post-run listener: copy_logs_to_upload_dir 09:07:03 INFO - Copying logs to upload dir... 09:07:03 INFO - mkdir: /builds/slave/test/build/upload/logs 09:07:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.016000 ========= master_lag: 0.89 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-12-21 09:07:04.272207) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 09:07:04.279573) ========= 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.cXMxEeesbM/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.8LwyT9la9s/Listeners TMPDIR=/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7f01f633a292485782fb28530090fd5ed8b437f378b075ec8265638e3b0c0da08c4fe18985cee2ea7feec753332a056ff6abd3eeb524b3a04c6c73276bbc05ba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/295afc94facae963430a210e6988daba1b5b1c1701ebbf7cea70e08a28dd0b44fb197ad5cbc3c76f88dfafbd53522cb01c0d5b7abfe833f78bad57aa5e9fb3b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2d79aa9f2e9ecff020809288c13601aef567e9ea9befe90916c9476c3bb3104c4ffcce277dbe52fedba9835781ea8a7271416dd0e31f71b9f6b5b1ce33233f33"} build_url:https://queue.taskcluster.net/v1/task/NGVcbQd6QmGqqrY3aMYZ2g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011438 build_url: 'https://queue.taskcluster.net/v1/task/NGVcbQd6QmGqqrY3aMYZ2g/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7f01f633a292485782fb28530090fd5ed8b437f378b075ec8265638e3b0c0da08c4fe18985cee2ea7feec753332a056ff6abd3eeb524b3a04c6c73276bbc05ba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/295afc94facae963430a210e6988daba1b5b1c1701ebbf7cea70e08a28dd0b44fb197ad5cbc3c76f88dfafbd53522cb01c0d5b7abfe833f78bad57aa5e9fb3b5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2d79aa9f2e9ecff020809288c13601aef567e9ea9befe90916c9476c3bb3104c4ffcce277dbe52fedba9835781ea8a7271416dd0e31f71b9f6b5b1ce33233f33"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 09:07:04.319213) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-12-21 09:07:04.319556) ========= 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.cXMxEeesbM/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.8LwyT9la9s/Listeners TMPDIR=/var/folders/xt/zmqpr3490qxfv07nrs0f850r00000w/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.005409 ========= master_lag: 1.34 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-12-21 09:07:05.659977) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 09:07:05.660332) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 09:07:05.660642) ========= ========= Total master_lag: 6.79 =========