builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0364 starttime: 1481762504.72 results: success (0) buildid: 20161214142101 builduid: 12ceb0d0f9a745cda173a023d0081fe8 revision: 0644964811f1423a2c0958e141fa92ee41695279 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-14 16:41:44.724678) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-14 16:41:44.725885) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-14 16:41:44.726274) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-14 16:41:44.754080) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 16:41:44.754543) ========= 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.rEfBiaEJtB/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.hPcES89Pt1/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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-14 16:41:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 43.3M=0s 2016-12-14 16:41:44 (43.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.133513 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 16:41:44.913481) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 16:41:44.914228) ========= 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.rEfBiaEJtB/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.hPcES89Pt1/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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.024939 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 16:41:44.966893) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-14 16:41:44.967443) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0644964811f1423a2c0958e141fa92ee41695279 --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 0644964811f1423a2c0958e141fa92ee41695279 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rEfBiaEJtB/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.hPcES89Pt1/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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-14 16:41:45,069 truncating revision to first 12 chars 2016-12-14 16:41:45,069 Setting DEBUG logging. 2016-12-14 16:41:45,069 attempt 1/10 2016-12-14 16:41:45,070 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/0644964811f1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-14 16:41:45,794 unpacking tar archive at: mozilla-aurora-0644964811f1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.985681 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-14 16:41:45.978761) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-14 16:41:45.979260) ========= script_repo_revision: 0644964811f1423a2c0958e141fa92ee41695279 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-14 16:41:45.979770) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-14 16:41:45.980036) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-14 16:41:46.000971) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-12-14 16:41:46.001547) ========= /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.rEfBiaEJtB/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.hPcES89Pt1/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 16:41:46 INFO - MultiFileLogger online at 20161214 16:41:46 in /builds/slave/test 16:41:46 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 16:41:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:41:46 INFO - {'append_to_log': False, 16:41:46 INFO - 'base_work_dir': '/builds/slave/test', 16:41:46 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 16:41:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:41:46 INFO - 'branch': 'Mozilla-Aurora', 16:41:46 INFO - 'buildbot_json_path': 'buildprops.json', 16:41:46 INFO - 'config_files': ('talos/mac_config.py',), 16:41:46 INFO - 'default_actions': ('clobber', 16:41:46 INFO - 'read-buildbot-config', 16:41:46 INFO - 'download-and-extract', 16:41:46 INFO - 'populate-webroot', 16:41:46 INFO - 'create-virtualenv', 16:41:46 INFO - 'install', 16:41:46 INFO - 'run-tests'), 16:41:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:41:46 INFO - 'download_minidump_stackwalk': True, 16:41:46 INFO - 'download_symbols': 'ondemand', 16:41:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:41:46 INFO - 'tooltool.py': '/tools/tooltool.py', 16:41:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:41:46 INFO - '/tools/misc-python/virtualenv.py')}, 16:41:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:41:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:41:46 INFO - 'installer_path': 'installer.exe', 16:41:46 INFO - 'log_level': 'info', 16:41:46 INFO - 'log_name': 'talos', 16:41:46 INFO - 'log_to_console': True, 16:41:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:41:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:41:46 INFO - 'opt_config_files': (), 16:41:46 INFO - 'pip_index': False, 16:41:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:41:46 INFO - 'cmd': ('bash', 16:41:46 INFO - '-c', 16:41:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:41:46 INFO - 'enabled': True, 16:41:46 INFO - 'halt_on_failure': False, 16:41:46 INFO - 'name': 'check_screen_resolution'},), 16:41:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:41:46 INFO - 'cmd': ('bash', 16:41:46 INFO - '-c', 16:41:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:41:46 INFO - 'enabled': True, 16:41:46 INFO - 'halt_on_failure': False, 16:41:46 INFO - 'name': 'check_screen_resolution'},), 16:41:46 INFO - 'run_cmd_checks_enabled': True, 16:41:46 INFO - 'sps_profile': False, 16:41:46 INFO - 'sps_profile_interval': 0, 16:41:46 INFO - 'suite': 'dromaeojs', 16:41:46 INFO - 'system_bits': '32', 16:41:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:41:46 INFO - 'title': 't-yosemite-r7-0364', 16:41:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:41:46 INFO - 'use_talos_json': True, 16:41:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:41:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:41:46 INFO - 'work_dir': 'build'} 16:41:46 INFO - [mozharness: 2016-12-15 00:41:46.211099Z] Running clobber step. 16:41:46 INFO - Running pre-action listener: _resource_record_pre_action 16:41:46 INFO - Running main action method: clobber 16:41:46 INFO - rmtree: /builds/slave/test/build 16:41:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:41:48 INFO - Running post-action listener: _resource_record_post_action 16:41:48 INFO - [mozharness: 2016-12-15 00:41:48.496392Z] Finished clobber step (success) 16:41:48 INFO - [mozharness: 2016-12-15 00:41:48.496518Z] Running read-buildbot-config step. 16:41:48 INFO - Running pre-action listener: _resource_record_pre_action 16:41:48 INFO - Running main action method: read_buildbot_config 16:41:48 INFO - Using buildbot properties: 16:41:48 INFO - { 16:41:48 INFO - "project": "", 16:41:48 INFO - "product": "firefox", 16:41:48 INFO - "script_repo_revision": "production", 16:41:48 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 16:41:48 INFO - "repository": "", 16:41:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 16:41:48 INFO - "buildid": "20161214142101", 16:41:48 INFO - "pgo_build": "False", 16:41:48 INFO - "basedir": "/builds/slave/test", 16:41:48 INFO - "buildnumber": 7, 16:41:48 INFO - "slavename": "t-yosemite-r7-0364", 16:41:48 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 16:41:48 INFO - "platform": "yosemite_r7", 16:41:48 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 16:41:48 INFO - "branch": "mozilla-aurora", 16:41:48 INFO - "repo_path": "releases/mozilla-aurora", 16:41:48 INFO - "revision": "0644964811f1423a2c0958e141fa92ee41695279", 16:41:48 INFO - "stage_platform": "macosx64", 16:41:48 INFO - "builduid": "12ceb0d0f9a745cda173a023d0081fe8", 16:41:48 INFO - "slavebuilddir": "test" 16:41:48 INFO - } 16:41:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/X1afLUzfTBGIlMG0B6Rhkg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 16:41:48 INFO - Running post-action listener: _resource_record_post_action 16:41:48 INFO - [mozharness: 2016-12-15 00:41:48.499972Z] Finished read-buildbot-config step (success) 16:41:48 INFO - [mozharness: 2016-12-15 00:41:48.500060Z] Running download-and-extract step. 16:41:48 INFO - Running pre-action listener: _resource_record_pre_action 16:41:48 INFO - Running main action method: download_and_extract 16:41:48 INFO - mkdir: /builds/slave/test/build/tests 16:41:48 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')]} 16:41:48 INFO - trying https://queue.taskcluster.net/v1/task/X1afLUzfTBGIlMG0B6Rhkg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 16:41:48 INFO - Downloading https://queue.taskcluster.net/v1/task/X1afLUzfTBGIlMG0B6Rhkg/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 16:41:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X1afLUzfTBGIlMG0B6Rhkg/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 16:41:49 INFO - Downloaded 1199 bytes. 16:41:49 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 16:41:49 INFO - Using the following test package requirements: 16:41:49 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 16:41:49 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:41:49 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 16:41:49 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:41:49 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 16:41:49 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:41:49 INFO - u'jsshell-mac.zip'], 16:41:49 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:41:49 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 16:41:49 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 16:41:49 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:41:49 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 16:41:49 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:41:49 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 16:41:49 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:41:49 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 16:41:49 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:41:49 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 16:41:49 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 16:41:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X1afLUzfTBGIlMG0B6Rhkg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 16:41:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X1afLUzfTBGIlMG0B6Rhkg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 16:41:49 INFO - Fetch https://queue.taskcluster.net/v1/task/X1afLUzfTBGIlMG0B6Rhkg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 16:41:50 INFO - Http code: 200 16:41:50 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 16:41:50 INFO - x-amz-cf-id: jyErZv1gHdzKokGawUZ-Pa4fhA7Mo8poOJ20Gf1WSI-xa-ve101HiA== 16:41:50 INFO - x-amz-version-id: O8o5AmFrPS78WATvmSRx8on7yskCLRZT 16:41:50 INFO - Expected file size: 16281022 16:41:50 INFO - Obtained file size: 16281022 16:41:57 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 16:41:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X1afLUzfTBGIlMG0B6Rhkg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 16:41:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X1afLUzfTBGIlMG0B6Rhkg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 16:41:57 INFO - Fetch https://queue.taskcluster.net/v1/task/X1afLUzfTBGIlMG0B6Rhkg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 16:41:57 INFO - Http code: 200 16:41:57 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 16:41:57 INFO - x-amz-cf-id: 3WlBYvK6tfjfoo5tKIa1E2Roai5KOL9APYazWK8meXHPNvru5DoBOg== 16:41:57 INFO - x-amz-version-id: O8o5AmFrPS78WATvmSRx8on7yskCLRZT 16:41:58 INFO - Expected file size: 16281022 16:41:58 INFO - Obtained file size: 16281022 16:42:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X1afLUzfTBGIlMG0B6Rhkg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 16:42:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X1afLUzfTBGIlMG0B6Rhkg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 16:42:04 INFO - Fetch https://queue.taskcluster.net/v1/task/X1afLUzfTBGIlMG0B6Rhkg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 16:42:05 INFO - Http code: 200 16:42:05 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 16:42:05 INFO - x-amz-cf-id: e2R6amplCrq1pKLvdF7Nv2rf4j007Ma5_BkxdGRMCoZJXe9UOUnt_Q== 16:42:05 INFO - x-amz-version-id: UAG9nzhv7kzMAaTuz2LZIFRFfPfVIEbn 16:42:05 INFO - Expected file size: 14409038 16:42:05 INFO - Obtained file size: 14409038 16:42:06 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')]} 16:42:06 INFO - trying https://queue.taskcluster.net/v1/task/X1afLUzfTBGIlMG0B6Rhkg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 16:42:06 INFO - Downloading https://queue.taskcluster.net/v1/task/X1afLUzfTBGIlMG0B6Rhkg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 16:42:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X1afLUzfTBGIlMG0B6Rhkg/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 16:42:08 INFO - Downloaded 109769916 bytes. 16:42:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/X1afLUzfTBGIlMG0B6Rhkg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 16:42:08 INFO - mkdir: /builds/slave/test/properties 16:42:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:42:08 INFO - Writing to file /builds/slave/test/properties/build_url 16:42:08 INFO - Contents: 16:42:08 INFO - build_url:https://queue.taskcluster.net/v1/task/X1afLUzfTBGIlMG0B6Rhkg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 16:42:09 INFO - Running post-action listener: _resource_record_post_action 16:42:09 INFO - Running post-action listener: set_extra_try_arguments 16:42:09 INFO - [mozharness: 2016-12-15 00:42:09.534686Z] Finished download-and-extract step (success) 16:42:09 INFO - [mozharness: 2016-12-15 00:42:09.534833Z] Running populate-webroot step. 16:42:09 INFO - Running pre-action listener: _resource_record_pre_action 16:42:09 INFO - Running main action method: populate_webroot 16:42:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:42:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:42:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:42:09 INFO - '--tppagecycles', 16:42:09 INFO - '7'], 16:42:09 INFO - 'tests': ['tsvgm']}}, 16:42:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:42:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:42:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:42:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:42:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:42:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:42:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:42:09 INFO - 'talos_options': ['--disable-e10s'], 16:42:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:42:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:42:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:42:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:42:09 INFO - 'talos_options': ['--disable-e10s'], 16:42:09 INFO - 'tests': ['damp', 'tps']}, 16:42:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:42:09 INFO - 'tests': ['damp', 'tps']}, 16:42:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:42:09 INFO - 'tests': ['dromaeo_dom']}, 16:42:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:42:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:42:09 INFO - 'tests': ['basic_compositor_video']}, 16:42:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:42:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:42:09 INFO - 'tests': ['a11yr', 16:42:09 INFO - 'ts_paint', 16:42:09 INFO - 'tpaint', 16:42:09 INFO - 'sessionrestore', 16:42:09 INFO - 'sessionrestore_no_auto_restore', 16:42:09 INFO - 'tabpaint']}, 16:42:09 INFO - 'other-e10s': {'tests': ['a11yr', 16:42:09 INFO - 'ts_paint', 16:42:09 INFO - 'tpaint', 16:42:09 INFO - 'sessionrestore', 16:42:09 INFO - 'sessionrestore_no_auto_restore', 16:42:09 INFO - 'tabpaint']}, 16:42:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:42:09 INFO - 'ts_paint', 16:42:09 INFO - 'tpaint', 16:42:09 INFO - 'sessionrestore', 16:42:09 INFO - 'sessionrestore_no_auto_restore', 16:42:09 INFO - 'tabpaint']}, 16:42:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:42:09 INFO - 'ts_paint', 16:42:09 INFO - 'tpaint', 16:42:09 INFO - 'sessionrestore', 16:42:09 INFO - 'sessionrestore_no_auto_restore', 16:42:09 INFO - 'tabpaint']}, 16:42:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:42:09 INFO - 'tests': ['a11yr', 16:42:09 INFO - 'ts_paint', 16:42:09 INFO - 'tpaint', 16:42:09 INFO - 'sessionrestore', 16:42:09 INFO - 'sessionrestore_no_auto_restore', 16:42:09 INFO - 'tabpaint']}, 16:42:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:42:09 INFO - 'tests': ['a11yr', 16:42:09 INFO - 'ts_paint', 16:42:09 INFO - 'tpaint', 16:42:09 INFO - 'sessionrestore', 16:42:09 INFO - 'sessionrestore_no_auto_restore', 16:42:09 INFO - 'tabpaint']}, 16:42:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:42:09 INFO - 'tests': ['tsvgx', 16:42:09 INFO - 'tsvgr_opacity', 16:42:09 INFO - 'tart', 16:42:09 INFO - 'tscrollx', 16:42:09 INFO - 'cart']}, 16:42:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:42:09 INFO - 'tsvgr_opacity', 16:42:09 INFO - 'tart', 16:42:09 INFO - 'tscrollx', 16:42:09 INFO - 'cart']}, 16:42:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:42:09 INFO - 'talos_options': ['--disable-e10s'], 16:42:09 INFO - 'tests': ['tp5o']}, 16:42:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:42:09 INFO - 'tests': ['tp5o']}, 16:42:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:42:09 INFO - 'talos_options': ['--disable-e10s', 16:42:09 INFO - '--xperf_path', 16:42:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:42:09 INFO - 'tests': ['tp5n']}, 16:42:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:42:09 INFO - 'talos_options': ['--xperf_path', 16:42:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:42:09 INFO - 'tests': ['tp5n']}}, 16:42:09 INFO - 'talos.zip': {'path': '', 16:42:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:42:09 INFO - Running post-action listener: _resource_record_post_action 16:42:09 INFO - [mozharness: 2016-12-15 00:42:09.553704Z] Finished populate-webroot step (success) 16:42:09 INFO - [mozharness: 2016-12-15 00:42:09.553835Z] Running create-virtualenv step. 16:42:09 INFO - Running pre-action listener: _resource_record_pre_action 16:42:09 INFO - Running main action method: create_virtualenv 16:42:09 INFO - Creating virtualenv /builds/slave/test/build/venv 16:42:09 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 16:42:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:42:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:42:09 INFO - Using real prefix '/tools/python27' 16:42:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:42:10 INFO - Installing distribute.............................................................................................................................................................................................done. 16:42:13 INFO - Installing pip.................done. 16:42:13 INFO - Return code: 0 16:42:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:42:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:42:13 INFO - Reading from file tmpfile_stdout 16:42:13 INFO - Output received: 16:42:13 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) 16:42:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:42:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:42:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:42:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:42:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:42:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:13 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 0x101328490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10129e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe22c67590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10113cd30>, '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 0x100fa2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101134b20>, '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.rEfBiaEJtB/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.hPcES89Pt1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 16:42:13 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 16:42:13 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 16:42:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rEfBiaEJtB/Render', 16:42:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:42:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:42:13 INFO - 'HOME': '/Users/cltbld', 16:42:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:42:13 INFO - 'LOGNAME': 'cltbld', 16:42:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:42:13 INFO - 'MOZ_NO_REMOTE': '1', 16:42:13 INFO - 'NO_EM_RESTART': '1', 16:42:13 INFO - 'PAGER': '/bin/cat', 16:42:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:42:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:42:13 INFO - 'PWD': '/builds/slave/test', 16:42:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:42:13 INFO - 'SHELL': '/bin/bash', 16:42:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hPcES89Pt1/Listeners', 16:42:13 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 16:42:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:42:13 INFO - 'USER': 'cltbld', 16:42:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:42:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:42:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:42:13 INFO - 'XPC_FLAGS': '0x0', 16:42:13 INFO - 'XPC_SERVICE_NAME': '0', 16:42:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:42:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:42:13 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 16:42:13 INFO - Cleaning up... 16:42:13 INFO - Return code: 0 16:42:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:42:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:42:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:42:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:42:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:42:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:13 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 0x101328490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10129e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe22c67590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10113cd30>, '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 0x100fa2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101134b20>, '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.rEfBiaEJtB/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.hPcES89Pt1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 16:42:13 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 16:42:13 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 16:42:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rEfBiaEJtB/Render', 16:42:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:42:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:42:13 INFO - 'HOME': '/Users/cltbld', 16:42:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:42:13 INFO - 'LOGNAME': 'cltbld', 16:42:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:42:13 INFO - 'MOZ_NO_REMOTE': '1', 16:42:13 INFO - 'NO_EM_RESTART': '1', 16:42:13 INFO - 'PAGER': '/bin/cat', 16:42:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:42:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:42:13 INFO - 'PWD': '/builds/slave/test', 16:42:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:42:13 INFO - 'SHELL': '/bin/bash', 16:42:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hPcES89Pt1/Listeners', 16:42:13 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 16:42:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:42:13 INFO - 'USER': 'cltbld', 16:42:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:42:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:42:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:42:13 INFO - 'XPC_FLAGS': '0x0', 16:42:13 INFO - 'XPC_SERVICE_NAME': '0', 16:42:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:42:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:42:14 INFO - Downloading/unpacking psutil>=3.1.1 16:42:14 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 16:42:14 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 16:42:14 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 16:42:14 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 16:42:14 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 16:42:14 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 16:42:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:42:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:42:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:42:17 INFO - Installing collected packages: psutil 16:42:17 INFO - Running setup.py install for psutil 16:42:17 INFO - building 'psutil._psutil_osx' extension 16:42:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 16:42:18 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 16:42:18 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 16:42:18 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 16:42:18 INFO - building 'psutil._psutil_posix' extension 16:42:18 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 16:42:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:42:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:42:18 INFO - ^ 16:42:18 INFO - 1 warning generated. 16:42:18 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 16:42:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:42:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:42:18 INFO - Successfully installed psutil 16:42:18 INFO - Cleaning up... 16:42:18 INFO - Return code: 0 16:42:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:42:18 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')]} 16:42:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:42:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:18 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')]} 16:42:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:42:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:18 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 0x101328490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10129e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe22c67590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10113cd30>, '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 0x100fa2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101134b20>, '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.rEfBiaEJtB/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.hPcES89Pt1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 16:42:18 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 16:42:18 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 16:42:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rEfBiaEJtB/Render', 16:42:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:42:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:42:18 INFO - 'HOME': '/Users/cltbld', 16:42:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:42:18 INFO - 'LOGNAME': 'cltbld', 16:42:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:42:18 INFO - 'MOZ_NO_REMOTE': '1', 16:42:18 INFO - 'NO_EM_RESTART': '1', 16:42:18 INFO - 'PAGER': '/bin/cat', 16:42:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:42:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:42:18 INFO - 'PWD': '/builds/slave/test', 16:42:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:42:18 INFO - 'SHELL': '/bin/bash', 16:42:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hPcES89Pt1/Listeners', 16:42:18 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 16:42:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:42:18 INFO - 'USER': 'cltbld', 16:42:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:42:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:42:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:42:18 INFO - 'XPC_FLAGS': '0x0', 16:42:18 INFO - 'XPC_SERVICE_NAME': '0', 16:42:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:42:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:42:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:42:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:42:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:42:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:42:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:42:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:42:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:42:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:42:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:42:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:42:21 INFO - Installing collected packages: mozsystemmonitor 16:42:21 INFO - Running setup.py install for mozsystemmonitor 16:42:21 INFO - Successfully installed mozsystemmonitor 16:42:21 INFO - Cleaning up... 16:42:21 INFO - Return code: 0 16:42:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:42: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')]} 16:42:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:42:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42: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')]} 16:42:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:42:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42: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', '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 0x101328490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10129e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe22c67590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10113cd30>, '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 0x100fa2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101134b20>, '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.rEfBiaEJtB/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.hPcES89Pt1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 16:42: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:42: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 jsonschema==2.5.1 16:42:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rEfBiaEJtB/Render', 16:42:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:42:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:42:21 INFO - 'HOME': '/Users/cltbld', 16:42:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:42:21 INFO - 'LOGNAME': 'cltbld', 16:42:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:42:21 INFO - 'MOZ_NO_REMOTE': '1', 16:42:21 INFO - 'NO_EM_RESTART': '1', 16:42:21 INFO - 'PAGER': '/bin/cat', 16:42:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:42:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:42:21 INFO - 'PWD': '/builds/slave/test', 16:42:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:42:21 INFO - 'SHELL': '/bin/bash', 16:42:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hPcES89Pt1/Listeners', 16:42:21 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 16:42:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:42:21 INFO - 'USER': 'cltbld', 16:42:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:42:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:42:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:42:21 INFO - 'XPC_FLAGS': '0x0', 16:42:21 INFO - 'XPC_SERVICE_NAME': '0', 16:42:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:42:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:42:22 INFO - Downloading/unpacking jsonschema==2.5.1 16:42:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:42:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:42:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:42:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:42:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:42:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:42:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:42:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:42:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:42:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:42:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:42:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:42:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:42:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:42:25 INFO - Downloading functools32-3.2.3-2.tar.gz 16:42:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:42:25 INFO - warning: no files found matching '*.txt' 16:42:25 INFO - no previously-included directories found matching 'build' 16:42:25 INFO - no previously-included directories found matching 'dist' 16:42:25 INFO - no previously-included directories found matching '.git*' 16:42:25 INFO - Installing collected packages: jsonschema, functools32 16:42:25 INFO - Running setup.py install for functools32 16:42:25 INFO - warning: no files found matching '*.txt' 16:42:25 INFO - no previously-included directories found matching 'build' 16:42:25 INFO - no previously-included directories found matching 'dist' 16:42:25 INFO - no previously-included directories found matching '.git*' 16:42:25 INFO - Successfully installed jsonschema functools32 16:42:25 INFO - Cleaning up... 16:42:25 INFO - Return code: 0 16:42:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:42:25 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')]} 16:42:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:42:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:25 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')]} 16:42:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:42:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:25 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 0x101328490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10129e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe22c67590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10113cd30>, '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 0x100fa2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101134b20>, '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.rEfBiaEJtB/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.hPcES89Pt1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 16:42:25 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 16:42:25 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 16:42:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rEfBiaEJtB/Render', 16:42:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:42:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:42:25 INFO - 'HOME': '/Users/cltbld', 16:42:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:42:25 INFO - 'LOGNAME': 'cltbld', 16:42:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:42:25 INFO - 'MOZ_NO_REMOTE': '1', 16:42:25 INFO - 'NO_EM_RESTART': '1', 16:42:25 INFO - 'PAGER': '/bin/cat', 16:42:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:42:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:42:25 INFO - 'PWD': '/builds/slave/test', 16:42:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:42:25 INFO - 'SHELL': '/bin/bash', 16:42:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hPcES89Pt1/Listeners', 16:42:25 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 16:42:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:42:25 INFO - 'USER': 'cltbld', 16:42:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:42:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:42:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:42:25 INFO - 'XPC_FLAGS': '0x0', 16:42:25 INFO - 'XPC_SERVICE_NAME': '0', 16:42:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:42:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:42:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:42:25 INFO - Cleaning up... 16:42:25 INFO - Return code: 0 16:42:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:42:25 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')]} 16:42:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:42:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:25 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')]} 16:42:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:42:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:25 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 0x101328490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10129e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe22c67590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10113cd30>, '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 0x100fa2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101134b20>, '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.rEfBiaEJtB/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.hPcES89Pt1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 16:42:25 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 16:42:25 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 16:42:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rEfBiaEJtB/Render', 16:42:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:42:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:42:25 INFO - 'HOME': '/Users/cltbld', 16:42:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:42:25 INFO - 'LOGNAME': 'cltbld', 16:42:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:42:25 INFO - 'MOZ_NO_REMOTE': '1', 16:42:25 INFO - 'NO_EM_RESTART': '1', 16:42:25 INFO - 'PAGER': '/bin/cat', 16:42:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:42:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:42:25 INFO - 'PWD': '/builds/slave/test', 16:42:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:42:25 INFO - 'SHELL': '/bin/bash', 16:42:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hPcES89Pt1/Listeners', 16:42:25 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 16:42:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:42:25 INFO - 'USER': 'cltbld', 16:42:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:42:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:42:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:42:25 INFO - 'XPC_FLAGS': '0x0', 16:42:25 INFO - 'XPC_SERVICE_NAME': '0', 16:42:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:42:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:42:26 INFO - Downloading/unpacking blobuploader==1.2.4 16:42:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:42:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:42:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:42:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:42:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:42:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:42:28 INFO - Downloading blobuploader-1.2.4.tar.gz 16:42:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:42:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:42:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:42:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:42:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:42:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:42:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:42:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:42:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:42:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:42: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 16:42: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 16:42: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 16:42: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 16:42: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 16:42: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 16:42:29 INFO - Downloading docopt-0.6.1.tar.gz 16:42:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:42:29 INFO - Installing collected packages: blobuploader, requests, docopt 16:42:29 INFO - Running setup.py install for blobuploader 16:42:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:42:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:42:30 INFO - Running setup.py install for requests 16:42:30 INFO - Running setup.py install for docopt 16:42:30 INFO - Successfully installed blobuploader requests docopt 16:42:30 INFO - Cleaning up... 16:42:30 INFO - Return code: 0 16:42:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:42:30 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')]} 16:42:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:42:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:30 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')]} 16:42:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:42:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:30 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 0x101328490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10129e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe22c67590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10113cd30>, '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 0x100fa2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101134b20>, '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.rEfBiaEJtB/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.hPcES89Pt1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 16:42:30 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 16:42:30 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 16:42:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rEfBiaEJtB/Render', 16:42:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:42:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:42:30 INFO - 'HOME': '/Users/cltbld', 16:42:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:42:30 INFO - 'LOGNAME': 'cltbld', 16:42:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:42:30 INFO - 'MOZ_NO_REMOTE': '1', 16:42:30 INFO - 'NO_EM_RESTART': '1', 16:42:30 INFO - 'PAGER': '/bin/cat', 16:42:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:42:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:42:30 INFO - 'PWD': '/builds/slave/test', 16:42:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:42:30 INFO - 'SHELL': '/bin/bash', 16:42:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hPcES89Pt1/Listeners', 16:42:30 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 16:42:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:42:30 INFO - 'USER': 'cltbld', 16:42:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:42:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:42:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:42:30 INFO - 'XPC_FLAGS': '0x0', 16:42:30 INFO - 'XPC_SERVICE_NAME': '0', 16:42:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:42:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:42:31 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-2gMsw3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:42:31 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-yMZzpd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:42:31 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-Nx59tb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:42:31 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-QjoXtg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:42:31 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-FsoHo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:42:31 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-IfJ0Ef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:42:31 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-3qnT7J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:42:31 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-N7fYvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:42:31 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-KNAhx_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:42:32 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-0hZRoi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:42:32 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-UHbtOg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:42:32 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-C6nO5x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:42:32 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-jByqSV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:42:32 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-FGo0KC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:42:32 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-GqJDei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:42:32 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-_DKUCn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:42:32 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-9IZ9Dc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:42:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:42:32 INFO - Running setup.py install for manifestparser 16:42:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:42:33 INFO - Running setup.py install for mozcrash 16:42:33 INFO - Running setup.py install for mozdebug 16:42:33 INFO - Running setup.py install for mozdevice 16:42:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:42:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:42:33 INFO - Running setup.py install for mozfile 16:42:33 INFO - Running setup.py install for mozhttpd 16:42:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:42:33 INFO - Running setup.py install for mozinfo 16:42:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:42:33 INFO - Running setup.py install for mozInstall 16:42:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:42:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:42:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:42:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:42:34 INFO - Running setup.py install for mozleak 16:42:34 INFO - Running setup.py install for mozlog 16:42:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:42:34 INFO - Running setup.py install for moznetwork 16:42:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:42:34 INFO - Running setup.py install for mozprocess 16:42:34 INFO - Running setup.py install for mozprofile 16:42:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:42:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:42:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:42:34 INFO - Running setup.py install for mozrunner 16:42:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:42:35 INFO - Running setup.py install for mozscreenshot 16:42:35 INFO - Running setup.py install for moztest 16:42:35 INFO - Running setup.py install for mozversion 16:42:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:42:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:42:35 INFO - Cleaning up... 16:42:35 INFO - Return code: 0 16:42:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:42:35 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')]} 16:42:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:42:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:35 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')]} 16:42:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:42:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:35 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 0x101328490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10129e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe22c67590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10113cd30>, '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 0x100fa2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101134b20>, '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.rEfBiaEJtB/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.hPcES89Pt1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 16:42:35 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 16:42:35 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 16:42:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rEfBiaEJtB/Render', 16:42:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:42:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:42:35 INFO - 'HOME': '/Users/cltbld', 16:42:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:42:35 INFO - 'LOGNAME': 'cltbld', 16:42:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:42:35 INFO - 'MOZ_NO_REMOTE': '1', 16:42:35 INFO - 'NO_EM_RESTART': '1', 16:42:35 INFO - 'PAGER': '/bin/cat', 16:42:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:42:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:42:35 INFO - 'PWD': '/builds/slave/test', 16:42:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:42:35 INFO - 'SHELL': '/bin/bash', 16:42:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hPcES89Pt1/Listeners', 16:42:35 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 16:42:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:42:35 INFO - 'USER': 'cltbld', 16:42:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:42:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:42:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:42:35 INFO - 'XPC_FLAGS': '0x0', 16:42:35 INFO - 'XPC_SERVICE_NAME': '0', 16:42:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:42:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:42:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:42:35 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-0QQiTz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:42:35 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)) 16:42:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:42:35 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-P80PQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:42:35 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)) 16:42:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:42:35 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-4qNLeW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:42:36 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)) 16:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:42:36 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-Ji6jVQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:42:36 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)) 16:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:42:36 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-ddobf8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:42:36 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)) 16:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:42:36 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-WdMz4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:42:36 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)) 16:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:42:36 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-MaDNd2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:42:36 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)) 16:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:42:36 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-u4BTgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:42:36 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)) 16:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:42:36 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-oJ9dF2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:42:36 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)) 16:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:42:36 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-pvPjJK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:42:36 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)) 16:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:42:36 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-gbxeWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:42:36 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)) 16:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:42:36 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-X67AVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:42:37 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)) 16:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:42:37 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-HDAERA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:42:37 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)) 16:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:42:37 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-tEge6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:42:37 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)) 16:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:42:37 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-uJemzf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:42:37 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)) 16:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:42:37 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-QjtCIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:42:37 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)) 16:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:42:37 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-nJuYre-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:42:37 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)) 16:42:37 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)) 16:42:37 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)) 16:42:37 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)) 16:42:37 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)) 16:42:37 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)) 16:42:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:42:37 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 16:42:37 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 16:42:37 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 16:42:37 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 16:42:37 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 16:42:37 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 16:42:38 INFO - Downloading blessings-1.6.tar.gz 16:42:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:42:39 INFO - Installing collected packages: blessings 16:42:39 INFO - Running setup.py install for blessings 16:42:39 INFO - Successfully installed blessings 16:42:39 INFO - Cleaning up... 16:42:39 INFO - Return code: 0 16:42:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:42:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:42:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:42:39 INFO - Reading from file tmpfile_stdout 16:42:39 INFO - Current package versions: 16:42:39 INFO - blessings == 1.6 16:42:39 INFO - blobuploader == 1.2.4 16:42:39 INFO - docopt == 0.6.1 16:42:39 INFO - functools32 == 3.2.3-2 16:42:39 INFO - jsonschema == 2.5.1 16:42:39 INFO - manifestparser == 1.1 16:42:39 INFO - mozInstall == 1.12 16:42:39 INFO - mozcrash == 1.0 16:42:39 INFO - mozdebug == 0.1 16:42:39 INFO - mozdevice == 0.48 16:42:39 INFO - mozfile == 1.2 16:42:39 INFO - mozhttpd == 0.7 16:42:39 INFO - mozinfo == 0.9 16:42:39 INFO - mozleak == 0.1 16:42:39 INFO - mozlog == 3.4 16:42:39 INFO - moznetwork == 0.27 16:42:39 INFO - mozprocess == 0.23 16:42:39 INFO - mozprofile == 0.28 16:42:39 INFO - mozrunner == 6.13 16:42:39 INFO - mozscreenshot == 0.1 16:42:39 INFO - mozsystemmonitor == 0.3 16:42:39 INFO - moztest == 0.7 16:42:39 INFO - mozversion == 1.4 16:42:39 INFO - psutil == 3.1.1 16:42:39 INFO - requests == 1.2.3 16:42:39 INFO - wsgiref == 0.1.2 16:42:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:42: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')]} 16:42:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:42:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42: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')]} 16:42:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:42:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101328490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10129e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe22c67590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10113cd30>, '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 0x100fa2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101134b20>, '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.rEfBiaEJtB/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.hPcES89Pt1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 16:42:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:42:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:42:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rEfBiaEJtB/Render', 16:42:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:42:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:42:39 INFO - 'HOME': '/Users/cltbld', 16:42:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:42:39 INFO - 'LOGNAME': 'cltbld', 16:42:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:42:39 INFO - 'MOZ_NO_REMOTE': '1', 16:42:39 INFO - 'NO_EM_RESTART': '1', 16:42:39 INFO - 'PAGER': '/bin/cat', 16:42:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:42:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:42:39 INFO - 'PWD': '/builds/slave/test', 16:42:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:42:39 INFO - 'SHELL': '/bin/bash', 16:42:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hPcES89Pt1/Listeners', 16:42:39 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 16:42:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:42:39 INFO - 'USER': 'cltbld', 16:42:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:42:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:42:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:42:39 INFO - 'XPC_FLAGS': '0x0', 16:42:39 INFO - 'XPC_SERVICE_NAME': '0', 16:42:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:42:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:42:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:42:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:42:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:42:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:42:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:42:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:42:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:42:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:42:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:42:39 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:42:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:42:39 INFO - Cleaning up... 16:42:39 INFO - Return code: 0 16:42:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:42: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')]} 16:42:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:42:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42: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')]} 16:42:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:42:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:42:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101328490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10129e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe22c67590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10113cd30>, '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 0x100fa2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101134b20>, '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.rEfBiaEJtB/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.hPcES89Pt1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 16:42:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:42:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:42:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rEfBiaEJtB/Render', 16:42:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:42:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:42:39 INFO - 'HOME': '/Users/cltbld', 16:42:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:42:39 INFO - 'LOGNAME': 'cltbld', 16:42:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:42:39 INFO - 'MOZ_NO_REMOTE': '1', 16:42:39 INFO - 'NO_EM_RESTART': '1', 16:42:39 INFO - 'PAGER': '/bin/cat', 16:42:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:42:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:42:39 INFO - 'PWD': '/builds/slave/test', 16:42:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:42:39 INFO - 'SHELL': '/bin/bash', 16:42:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hPcES89Pt1/Listeners', 16:42:39 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 16:42:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:42:39 INFO - 'USER': 'cltbld', 16:42:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:42:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:42:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:42:39 INFO - 'XPC_FLAGS': '0x0', 16:42:39 INFO - 'XPC_SERVICE_NAME': '0', 16:42:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:42:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:42:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:42:40 INFO - Cleaning up... 16:42:40 INFO - Return code: 0 16:42:40 INFO - Running post-action listener: _resource_record_post_action 16:42:40 INFO - Running post-action listener: _start_resource_monitoring 16:42:40 INFO - Starting resource monitoring. 16:42:40 INFO - [mozharness: 2016-12-15 00:42:40.059228Z] Finished create-virtualenv step (success) 16:42:40 INFO - [mozharness: 2016-12-15 00:42:40.060260Z] Running install step. 16:42:40 INFO - Running pre-action listener: _resource_record_pre_action 16:42:40 INFO - Running main action method: install 16:42:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:42:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:42:40 INFO - Reading from file tmpfile_stdout 16:42:40 INFO - Detecting whether we're running mozinstall >=1.0... 16:42:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:42:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:42:40 INFO - Reading from file tmpfile_stdout 16:42:40 INFO - Output received: 16:42:40 INFO - Usage: mozinstall [options] installer 16:42:40 INFO - Options: 16:42:40 INFO - -h, --help show this help message and exit 16:42:40 INFO - -d DEST, --destination=DEST 16:42:40 INFO - Directory to install application into. [default: 16:42:40 INFO - "/builds/slave/test"] 16:42:40 INFO - --app=APP Application being installed. [default: firefox] 16:42:40 INFO - mkdir: /builds/slave/test/build/application 16:42:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:42:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 16:43:12 INFO - Reading from file tmpfile_stdout 16:43:12 INFO - Output received: 16:43:12 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 16:43:12 INFO - Running post-action listener: _resource_record_post_action 16:43:12 INFO - [mozharness: 2016-12-15 00:43:12.015375Z] Finished install step (success) 16:43:12 INFO - [mozharness: 2016-12-15 00:43:12.015559Z] Running run-tests step. 16:43:12 INFO - Running pre-action listener: _resource_record_pre_action 16:43:12 INFO - Running main action method: run_tests 16:43:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:43:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:43:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:43:12 INFO - 2016-12-14 16:43:12.030 screenresolution[1626:7312] starting screenresolution argv=screenresolution get 16:43:12 INFO - 2016-12-14 16:43:12.032 screenresolution[1626:7312] Display 0: 1600x1200x32@60 16:43:12 INFO - 2016-12-14 16:43:12.038 screenresolution[1627:7315] starting screenresolution argv=screenresolution list 16:43:12 INFO - Available Modes on Display 0 16:43:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:43:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:43:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:43:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:43:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:43:12 INFO - Intel Iris: 16:43:12 INFO - Chipset Model: Intel Iris 16:43:12 INFO - Type: GPU 16:43:12 INFO - Bus: Built-In 16:43:12 INFO - VRAM (Dynamic, Max): 1536 MB 16:43:12 INFO - Vendor: Intel (0x8086) 16:43:12 INFO - Device ID: 0x0a2e 16:43:12 INFO - Revision ID: 0x0009 16:43:12 INFO - Displays: 16:43:12 INFO - Display: 16:43:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:43:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:43:12 INFO - Display Serial Number: 1600x1200 60 16:43:12 INFO - Main Display: Yes 16:43:12 INFO - Mirror: Off 16:43:12 INFO - Online: Yes 16:43:12 INFO - Rotation: Supported 16:43:12 INFO - Return code: 0 16:43:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:43:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:43:12 INFO - Python 2.7.3 16:43:12 INFO - Return code: 0 16:43:12 INFO - grabbing minidump binary from tooltool 16:43:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:43:12 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10113cd30>, '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 0x100fa2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101134b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:43:12 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:43:12 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 16:43:12 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:43:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:43:12 INFO - Return code: 0 16:43:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:43:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:43:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:43:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:43:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:43:12 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/X1afLUzfTBGIlMG0B6Rhkg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0364', '--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 16:43:12 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/X1afLUzfTBGIlMG0B6Rhkg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0364 --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 16:43:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rEfBiaEJtB/Render', 16:43:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:43:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:43:12 INFO - 'HOME': '/Users/cltbld', 16:43:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:43:12 INFO - 'LOGNAME': 'cltbld', 16:43:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:43:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:43:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:43:12 INFO - 'MOZ_NO_REMOTE': '1', 16:43:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:43:12 INFO - 'NO_EM_RESTART': '1', 16:43:12 INFO - 'PAGER': '/bin/cat', 16:43:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:43:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:43:12 INFO - 'PWD': '/builds/slave/test', 16:43:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:43:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:43:12 INFO - 'SHELL': '/bin/bash', 16:43:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hPcES89Pt1/Listeners', 16:43:12 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 16:43:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:43:12 INFO - 'USER': 'cltbld', 16:43:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:43:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:43:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:43:12 INFO - 'XPC_FLAGS': '0x0', 16:43:12 INFO - 'XPC_SERVICE_NAME': '0', 16:43:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:43:12 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/X1afLUzfTBGIlMG0B6Rhkg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0364', '--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 16:43:12 INFO - mozversion application_buildid: 20161214142101 16:43:12 INFO - mozversion application_changeset: 0644964811f1423a2c0958e141fa92ee41695279 16:43:12 INFO - mozversion application_display_name: FirefoxDeveloperEdition 16:43:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:43:12 INFO - mozversion application_name: Firefox 16:43:12 INFO - mozversion application_remotingname: firefox-dev 16:43:12 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:43:12 INFO - mozversion application_vendor: Mozilla 16:43:12 INFO - mozversion application_version: 52.0a2 16:43:12 INFO - mozversion platform_buildid: 20161214142101 16:43:12 INFO - mozversion platform_changeset: 0644964811f1423a2c0958e141fa92ee41695279 16:43:12 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:43:12 INFO - mozversion platform_version: 52.0a2 16:43:12 INFO - using testdate: 1481762592 16:43:12 INFO - actual date: 1481762592 16:43:12 INFO - starting webserver on 'localhost:49237' 16:43:12 INFO - SUITE-START | Running 2 tests 16:43:12 INFO - TEST-START | dromaeo_css 16:43:12 INFO - operating with platform_type : mac_ 16:43:12 INFO - Initialising browser for dromaeo_css test... 16:43:12 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/tmpXMDWl9/profile http://localhost:49237/getInfo.html) 16:43:15 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 16:43:15 INFO - PROCESS | 1637 | colorDepth:24 16:43:15 INFO - PROCESS | 1637 | Browser inner width/height: 1024/689 16:43:15 INFO - PROCESS | 1637 | __metrics 16:43:15 INFO - PROCESS | 1637 | 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" 16:43:15 INFO - PROCESS | 1637 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:43:16 INFO - TEST-INFO | 1637: exit 0 16:43:16 INFO - Browser initialized. 16:43:16 INFO - Running cycle 1/1 for dromaeo_css test... 16:43:16 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/tmpXMDWl9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:43:28 INFO - PROCESS | 1639 | RSS: Main: 222404608 16:43:28 INFO - PROCESS | 1639 | 16:44:20 INFO - PROCESS | 1639 | 1481762660282 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] 16:46:34 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49237/tests/dromaeo/cssquery-ext.html) 16:46:35 INFO - PROCESS | 1639 | RSS: Main: 350597120 16:46:35 INFO - PROCESS | 1639 | 16:49:38 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-ext.html (next: http://localhost:49237/tests/dromaeo/cssquery-jquery.html) 16:49:38 INFO - PROCESS | 1639 | RSS: Main: 353538048 16:49:38 INFO - PROCESS | 1639 | 16:52:41 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49237/tests/dromaeo/cssquery-mootools.html) 16:52:41 INFO - PROCESS | 1639 | RSS: Main: 315502592 16:52:41 INFO - PROCESS | 1639 | 16:55:44 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49237/tests/dromaeo/cssquery-prototype.html) 16:55:44 INFO - PROCESS | 1639 | RSS: Main: 313729024 16:55:44 INFO - PROCESS | 1639 | 16:58:48 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49237/tests/dromaeo/cssquery-yui.html) 16:58:48 INFO - PROCESS | 1639 | RSS: Main: 309223424 16:58:48 INFO - PROCESS | 1639 | 17:01:51 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-yui.html (next: http://localhost:49237/tests/dromaeo/cssquery-dojo.html) 17:01:52 INFO - PROCESS | 1639 | RSS: Main: 399323136 17:01:52 INFO - PROCESS | 1639 | 17:01:52 INFO - PROCESS | 1639 | __start_tp_report 17:01:52 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 17:01:52 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 17:01:52 INFO - PROCESS | 1639 | |i|pagename|runs| 17:01:52 INFO - PROCESS | 1639 | |0;dojo.html;2728.7680547153495;3285.3593549257903;3287.589051368579;3309.966900330999;3338.482535207042;4221.620054195122;5458.781648734051;5468.6718796872165;5515.393306736256;5564.387917329091;5445.782168713246;5478.808241711541;5498.835034948958;5511.614187006911;5519.530839878616;5035.723035233061;5052.418971818232;5111.463296353885;5130.153524668425;5135.845924622267;5035.1188542005;5050.747462626872;5054.3176671149295;5074.340335756347;5189.221616757498;4266.2320782259185;4893.04585605807;5047.066292735837;5057.9494205057845;5067.695938243699;5037.672551284173;5083.11045567028;5109.156989096795;5137.332146820927;5174.896502069975;4926.3842019747535;5004.3494345734825;5025.045241404122;5040.294358789735;5094.235864620299;5315.149576067811;5348.277982472356;5370.275012873251;5424.186372044225;5472.315960504937;5770.884582308372;5827.650340979512;5840.591158618899;5911.142884281755;5911.645301281937;6037.426444487811;6043.8186854394435;6045.3047899491685;6053.848653783691;6064;5435.755391007454;5519.282493275929;5521.503064724194;5531.917021244684;5587.189857470644;6169.907451388232;6212.782552610637;6216.409441103088;6232.594881332699;6234.9688251558455;5556.472135147154;5875;5891.23413956183;5891.322497912709;5929.14027466015;5843.795467158629;5916.940830591639;5918.171455996164;5928.229330187048;6067.666278354733;5869.61847479921;5876.236089308448;5877.118432235199;5879.176915231871;5881.05903055509;5835.533157347402;5864.8533786654825;5886.293644762656;5892.410758924159;5910.852228694231;4685.625149987993;4744.478107408182;4757.333973243749;4772.6420518461255;4780.995990841892;4329.1341731653165;4338.522762496123;4349.108432771274;4353.651707863363;4361.280388735823;5563.082091454865;5566.443355664482;5580.469855363734;5581.6371935824045;5622.297212848394;5798.449147330997;5856.0630299151935;6063.818085457443;6076.75692972285;6087.756489740361;5506.614536982453;5509.090999985037;5519.668819870821;5521.116621340566;5536.197251398606;5178.896422071537;5187.533122019036;5192.636515443883;5194.506521880491;5202.453742357074;6146.678464130234;6149.077638354193;6154.446099851035;6161.784337548164;6183.4125758053815;5476.233327334096;5639.097744360883;6010.459058684739;6117.021276595723;6119.4798442132915;4761.785719642694;6292.653904035322;6304.243490780952;6323.525735569759;6330.430261276507;5694.345150307768;5744.023516002206;5819.039858423313;5953.642781433128;5964.761409543569;5817.854553636023;5849.268841394825;5849.64902105875;5859.179714839841;5960.850978725566;5710.65736055838;5727.140928860705;5729.197912292199;5730.283714535683;5732.914006289992;5648.15277708347;5689.800856969986;5700.287464066992;5726.9713651431475;5729.914051289151;5115.848933989822;5206.817761378334;5602.5517958562405;5610.0743377343915;5658.377578466283;5732.541396688338;5751.281089863767;5753.05074662676;5764.13537969308;5766.3080430346945;5621.044422448275;5622.044252477007;5638.548916086621;5639.295088113986;5665.858353541194 17:01:52 INFO - PROCESS | 1639 | |1;ext.html;491.22631854828654;500.23214365948263;510.4181233393934;527.4119356917038;567.8438429431903;29770.9580164694;31314;31513.84243078784;39695.60304396955;40071.79784606466;40756.79621601895;41171.58828411723;41186.58813411865;41187.38218926711;41255;9201.58592863321;9332.860007099865;9336.673216437426;9344.112309330603;9344.53277336134;9174.54127293636;9283.442993420416;9284.16442520173;9305.627774888997;9319.254459643213;9234.492102934337;9286.953565232165;9308.162265396113;9310;9336.813263734755;9230.400023998418;9290.024547422558;9293.442393456347;9295.116963888488;9333.579988900516;7026.43788496919;9181.449113053239;9241.49171795551;9244.168024877727;9246.075392460767;31966.200844979063;38352.42471362932;38548.807255963824;38591.80704096489;38661;3224.2906560556876;3283.589551306087;3329.067860998924;3479.3215323012023;3674.4672022556847;27999.30001749952;28250.15249542517;28464.146075617766;28661.426771464456;28837.56743648847;2655.3892604701027;3131.295458521837;3153.0225630054756;3178.1260153457984;3272.640009598942;490879;492004;493102;495435;500002;32341.676583234337;33244.83377583145;55575.444245557024;56070.71964640151;56116.87766244734;33926.66073339235;34450;34459.31081378408;34480.13799654978;34514;14095.083819551697;14229.501967431297;14250.429982800571;14253.78619320711;14314.9979501436;11337.603183888525;11853.170278080452;11930.522779088738;11961.64115076549;11969.401529923469;7657.349125324298;7673.693052277847;7736.7292144774665;7741.729039483591;7749.302562769378;2719.7008329083783;3114.6418161911215;3182.204448887778;3192.265778870826;3229.725473334746;945.8628498867625;977.1645243316862;995.6515219673201;1037;1049.884512703602;23644;27993.58009629857;28842.423151536852;29574;29639.259018524277;1028.5600159776222;1029.315505189041;1032.02989190161;1149.6033868315417;1168.1063986050679;14673.92663036678;14685.706285874223;14968;15079.321430535652;15421.383144674088;659175;665190;665251;666514;667357;55697.721511392185;56058.43941560614;56110.71944640251;56197;56346.7182664084;1363.1412210307442;1375.1061809823696;1398.2030242761732;1399.9230042347565;1401.663600735816;28283.575746364233;28876.566851497664;28927.842886284314;29247;29544.704552955056;47268.29097563608;47460.7626961863;47476.05047899092;47543.76228118837;47835.76082119567;524736;533405;537246;538713;539098;25493.235202943943;25500.872495637403;25923;25979.61030584505;26051.218463446126;44344;44662.55337446714;44780;45224.773876130406;45595;37497.4375384364;37555.81222093872;37732.43401349037;37786;38421.231575367776;36687.63312366842;36768.26463470662;37344.43983340198;38242.808785955895;38383.23233535258;31408.685913140576;31519.842400788766;32066.839665801523;36822.44766328561;38733.2253354937;28727.856360718062;32817.67182328241;32870;32890.67109328972;32895.342093157524;24242.817303327403;24652.876735616923;24880.25359239226;25090.62364064577;25253.747462525138 17:01:52 INFO - PROCESS | 1639 | |2;jquery.html;322.5226664536486;346.69664043961535;364.52065533823037;373.3354628760806;400.2894861620625;15057.472988445406;15456.45902393415;17405.390811321624;19275;20372.69440958387;16717.498475045737;19350.808990336067;19744.506387340323;19793.60412791743;19987.600247995033;5482.122860342346;5573.164025396192;5670.518005969498;5720.742566584514;5729.28383952006;4344;5658.405867383927;5679.346875109353;5850.707464626773;5890.3226128995075;5913.172155898178;5932.436418540232;5934.436228558302;5935;5956.047032474805;5625.465580769841;5761;5796.101604251347;5872.5889187756875;5931.466168044875;5123.3339665843205;5461.453854614507;5686.772529098831;5699.316082070137;5699.601027928046;24683;24696.629550556758;25124.748752512605;26090.608640870403;26213.73786262132;2339.3332900123382;2391.7727815857465;2414.8068154547595;2818.422223444189;3020.4865172920654;12950.417231224617;16014.119223442705;16196.83803161965;16885.831141688548;16922.66154676912;1915.3200613782185;1922.7500424944667;1983.2562788954142;1989.4628450318332;1999.3302243748217;131994.34002829925;137706;140399;144935;147159;17803.73294400585;20753.169873205206;20772.272970445963;21007.369778906657;21014.684779728315;28406;30138.69861301403;30148;31948.840255798572;33159.502607460905;9509.809803804024;9834.262430317754;9875;9888;9903.950480247553;8027.117017128111;8038.638261278256;8065.838683226302;8128.593570321578;8196.590170491452;7426.071741032371;8857.689980850639;9380.390274632127;9438.292128090421;9505.19401313762;3145.4338219120777;3567.970130479481;3635.818209089535;3649.4934574698195;3753.774773513598;377.1156637684625;780.5550836023413;875.8438860703938;883.0860059838037;931.6366617019368;11217.270877392943;11711.88288117108;11775.470103845348;12146.878531214752;12148.331841748612;347.67145047929466;385.189176782868;393.26066994051;420.5437100745725;436.7161345125694;5886.735096920649;6050.969745151246;6125.356837532084;6174.197354343996;6178.382161783876;536822;576327;577247;589823;603087;69739.65130174317;74526.62736686281;107225.46387268013;123633;136075;926.8841394825647;937.5921474158763;979.6816034788723;993.5905429333692;998.116666749917;16033.797465189924;17901.19444625021;19105.426837194907;19188.8081119187;19221.615567688288;34575.481367780005;39404;43599.78200108979;48120.27819582779;52963.4703652974;64525.677371612845;75384.49231015239;77290.22709773055;79354.6032269835;81524.59237703774;7732.536047837148;7787.688492460238;7994.84010319802;8103.756887293391;8136.22705842944;11398.145139114766;12281.754364912473;12347.073969452507;12434.813477798021;12472.812907806208;10649.361038337414;10692.14462842955;10837.13302935779;10990.670279891616;11005.61480348216;10600.0989915858;10655.57377704883;10829.945850270698;10835.945820270848;11048.723781905517;10877.347359158475;10904.141706156466;11068.446577670924;11185.832212516656;11260.380679062402;8011.83976320482;8032.236937490929;8147.429679922586;8177.754667359989;8233.88842506254;9610.231181505602;10913.945430272797;10936.890631093906;11038.613648522263;11378.601748938747 17:01:52 INFO - PROCESS | 1639 | |3;mootools.html;1454.7963285140079;1477.7931089647448;1616.7736516887637;1770.7697999260095;1818.9818101818978;7971.88042179367;8415.70545030924;8420.200080992296;8420.494770313786;8445.451045682034;7416.925830741691;7605.429592780536;7632.824644976183;7987.640556174972;8210.630521626526;2285.371522831218;2346.3312955807596;2346.9530609387803;2348.6377900817483;2349.729781075181;2329.114936324203;2353.211674089174;2359.0681680736097;2377.476955069882;2379.7501262367464;2297.781710737477;2306.746257911628;2314.432963923841;2328.0571368595747;2333.8132949364012;2300.7814257645496;2301.3326135420707;2318.953620927589;2321.0019691532634;2331.5103828196097;2328.2433209207074;2354.8233882458885;2355.305184970438;2359.1624973134462;2361.669366288721;7084.504084714072;8245.175482451767;8297.004359476841;8302;8306.83386332276;4985.526374994406;5156.767945442464;5157.329547158845;5160.483951604847;5182.792688292464;3239.7732158748895;3308.4210263203845;3531.2231309111953;3584.4802503636824;3597.0647631615966;986.1716158426884;1051.9316244444162;1056.862607860981;1078.3691540448847;1101.0971003776906;10733.78532429347;10766.946165269203;10875.728106797234;10885.673429797118;10917.290376125526;4523.36672865799;4524.027334123113;4715.717056976592;4726.480087190406;4753.83361582343;9346.11211934865;9351.812963740687;9368.063193680713;9378.202852757591;9397.859032114524;5191.091558977239;5196.45437229093;5198.532132108128;5201.323827902348;5204.8959020819375;3941.881743547698;4128.256913755493;4706.623470122382;4763.166445872028;4780.760961951889;3275.279438523521;3776.5656949450618;3779.905502362408;3884.6698030667144;3895.376739721631;1249.287905893632;1252.5803855708439;1260.672225221449;1261.6341261034197;1267.73377590707;877.6884206106868;882.7748924024332;886.9157430044136;887.2857349833446;906.2659246010752;5400.405955344909;5505.229267902497;5514.283143191359;5574.637648552831;5608.85977850557;949.264320151874;958.8178246133212;970.8009857979098;990.7671697151163;1012.5949620151998;3599.532060832075;3656.6708996189936;3719.9212228453443;3789.886303410902;3849.2301539692176;9632.32573719847;9953.25350598708;9960.701178964642;10023.398596084258;10047.145992590711;7678.500897441649;9931.50342482873;9952.552135153936;9957.10386065243;9960.402375857328;7483.4013278937555;7685.4620176587105;7695.961520192363;7699.730509432143;7734.961325193338;5504.256925315191;5525.143602741581;5526.917096243479;5630.549556035589;5701.0023245932625;9327.253819694273;9410.38832475887;9412.576434060347;9463.668771593237;9488.715338539854;9274.258059355101;9435;9584.37701549397;9622.566984485855;9630.807383852423;7059.329363710439;7073.787786366417;7140.285971402818;7256.854862902606;7286.234945330664;7789.182135875878;7878.290953814184;7879.803004924923;7880.605969701606;7889.684412623431;6946.097007389007;6946.9652651736415;7128.964355178191;7202.207757146814;7238.6380680966795;6931.480138989495;7010.228874823867;7017.052697885924;7114.644267786486;7142.285771423024;6548.770793022221;6550.7707230246715;6620.304867988792;6636.767713130017;6671.799846004627;4757.619390448686;4766.499517550607;4777.259524773665;4790.401199850019;4851.830185943475;4623.815047398201;4655.092257009986;4680.274557443602;4840.636952228527;4923.778429970732 17:01:52 INFO - PROCESS | 1639 | |4;prototype.html;295.17940126448474;299.9235195025268;304.4535059568076;310.878757284659;311.88460269700215;4992.226204938232;5010.874728131798;5235.973820130899;5251.343582052244;5273;5131.255967884655;5133.666311689747;5150.871228219296;5155.448053213655;5188.2995795567585;3474.3746125697367;3725.646063623965;3789.450529673196;3869.090763670535;3980.2238563479987;3334.5498357721763;3354.6749034131503;3669.266146770643;4004.9799751001206;4022.7787471689044;3924.3721004639265;3946.4672269243715;3965.5439624443125;3980.800959952005;4001.5998400160042;3037.498812695914;3603.6036036036016;3756.380197267448;3928.9803550982206;3966.980165099185;3420.4698271767916;3426.674465925733;3437.5874895012757;3441.82790860456;3443.6384179661277;5059.721715305656;5092.618053645954;5095.643304968654;5143.408508021551;5155.458676838915;3954.5595332061002;4033.878983630496;4306.1172459645695;4342.934855977162;4468.927457410231;5490.862728431781;5504.504594586467;5507.339119305669;5507.394186639466;5527.889442211133;3181.88404710867;3223.839417809587;3783.148791521913;3786.734928555002;3814.7138964577766;5240.633155679104;5475.094347078692;5646.717664116778;5654.236678048452;5756.366799652035;3814.3324918139215;3820.522434695663;3828.904277393032;3834.3673293906195;3840.731148819556;3352.3798097351582;3885.786281754474;3887.8056097195026;3894.299026175259;3911.78485183312;4518.548145185521;4528.8677830542365;4534.478534968455;4543.54564543543;4559.3844830948065;5149.948500515022;5418.160185171305;5475.986942415666;5534.916976245359;5593.916091258634;1059.6609085092696;1214.5445457953267;1217.5677634439646;1225.9570915017932;1252.2111070025826;736.4255880413286;828.3207769628847;842.7429633961578;849.515776007682;857.3526987124806;4369.257226271541;4617.422822147231;4782.736949467812;4816.566509014136;4833.9274910875665;4511.5788526614015;4525.388518237362;5247.763850626731;5260.29095016067;5286.466924591825;4162.91674166521;4680.361873344303;4741.881452963704;4747.525247475294;4788.281757736368;2584.9353766155996;2637.2725864558743;2639.930828014657;2783.620004895785;2783.9025634103114;4750.8337208197545;4955.653104282737;5319.946800532022;5399.730013499309;5412.891742165135;4442.844500442534;4490.136593313803;5006.74966251686;5312.282841816383;5378.97310513445;4677.836275730333;4872.783962584677;5020;5210.505002024802;5444.8366549003595;5064.215046667772;5205.323307970091;5231.189165679326;5238.371395432572;5254.842354729364;4685.461171965268;4759.571638552547;5045.899082018413;5062.7888983673665;5063.810004648978;4810.39870016248;4814.037192561572;5434.771743585111;5474.972625136849;5519.586031047767;3155.936881262316;3361.562996810399;3403.3533628610485;3540.9468857967668;3581.779296835874;4782.760861956958;4911.4106307243355;5284.709340986283;5319.813806516753;5320.796570709251;5072.0870243355075;5081.314022607048;5098.495943696693;5098.666133786252;5131.332926719502;4448.218165472195;4560.475545312332;4928.704277743347;4954.9752251238515;5163.624732623036;4054.8378064877074;4471.306947423154;4545.727256364628;4554.772261386984;4560.840370587013;4546.681732278841;4819.6867203631655;5374.811881584125;5482.847362766345;5582.637128586629;4754.477007529238;5237.319148510669;5385.649932754359;5388.218708287355;5401.945980540301 17:01:52 INFO - PROCESS | 1639 | |5;yui.html;609.7195290166524;766.2873428064652;766.5852564422066;780.3180914512923;780.5706861226328;12733.745325093492;13217.735645287088;13908.095973761698;14322.570322890304;14495.782563261546;11530.79184604809;12202;12292.877071229284;12392.31842248676;15137.772933406008;806.2985202873508;835.1106072033265;837.4347315562002;837.928776054033;838.3712215838121;830.2195935820309;832.4755404095412;832.592029905345;833.4291010657688;835.0063424524797;687.3401534526835;708.479267738212;709.2623671381782;710.6375748368343;712.8859382498802;847.639753104928;877.3113106211657;878.6134100995605;879.7888506758396;880.7093659092484;651.2510612795276;651.9771807986698;652.26620052441;652.3378770547897;653.8953767397194;13633;13932.651683707887;13977.72044559103;13990.020698551105;14007.229602371866;13020.036936275004;13043.934780326132;13063.34683265833;13066.934665326708;13215.933920330433;11592.188546801728;11627.593034243846;11829.585964491287;12022.939885300604;12243.816342754866;4306.440162778818;4322.027543802619;4324.113556720896;4472.485664148632;4505.617022553087;59473;76342.85485717663;76667.6166619169;82798;85881.57059214727;9209.539523023823;10532;10806.243562950518;10869.305392322818;11261.887381126247;20769.376918692462;20773.480662983246;20922.953852307324;21167.15331386727;21355.35933921985;7587.92248223668;7746.65140068687;7823.552642761108;7843.098043725045;7930.881036784453;4219.198352313051;4232.851850185229;4303.07483890965;4470.441194850644;4477.932831007537;3417.470292104728;3500.317438099546;3547.3082748863726;3547.840347184383;3553.1117220694828;631.4222486424961;638.4126603524768;650.1125963060389;662.7680311890896;664.7772996046378;568.2328856044307;663.6880666086931;667.2983891077732;677.2685499660355;680.6392611915693;8303.584820758937;8475.830483390298;8833.55832208387;8994.640214391482;9078.86381704275;589.7788329376484;591.2225423567994;626.6960524145786;663.7212370804177;670.1455644053871;4395.384646149541;4544.272916333371;4551.203539380595;4566.292224705176;4600.196155550388;109902.45048774705;111127;112504.43747781208;112783.4360828207;113714;88239;92601;93047.0695293052;94121.0587894126;94830.52584737031;83266.16733832705;83861.58069209737;85352;86509.5674521636;88841;8582.270507006975;8818;8869.866951995855;8876.733697989072;9062.048484909254;53962.46037539731;54999;56573;56942.7152864233;56994.86010279854;75773;78645;81100.59449702714;81225;81888;11008.834867476835;11176.608818691308;11304.434778261219;11399.487023084146;11545.884541154817;12051.276923384625;12061.336626485627;12177;12424.56514022005;12839.679008024874;11247.437628118398;11269.380184089627;11499.195056345976;11783.410829458664;11924.0460763137;11867.643970680549;12218.511259549874;12307;12327.013838893046;13221;12390.690232743893;12782;13114.803277951029;13373.866261337262;13392.598222053353;10578.576856925636;10713.464326783474;10809.513571889127;11112.777744445228;11222;8990;11058.004779569877;11360.659180224606;11363.943180284046;11411.771764564495 17:01:52 INFO - PROCESS | 1639 | __end_tp_report 17:01:52 INFO - PROCESS | 1639 | __start_cc_report 17:01:52 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,1357 17:01:52 INFO - PROCESS | 1639 | __end_cc_report 17:01:52 INFO - PROCESS | 1639 | __startTimestamp1481763712167__endTimestamp 17:01:52 INFO - PROCESS | 1639 | 17:01:52 INFO - PROCESS | 1639 | ------- Summary: start ------- 17:01:52 INFO - PROCESS | 1639 | Number of tests: 6 17:01:52 INFO - PROCESS | 1639 | 17:01:52 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5484.43 Median:5622.04 stddev:617.91 (11.0%) stddev-sans-first:580.76 17:01:52 INFO - PROCESS | 1639 | Values: 2728.8 3285.4 3287.6 3310.0 3338.5 4221.6 5458.8 5468.7 5515.4 5564.4 5445.8 5478.8 5498.8 5511.6 5519.5 5035.7 5052.4 5111.5 5130.2 5135.8 5035.1 5050.7 5054.3 5074.3 5189.2 4266.2 4893.0 5047.1 5057.9 5067.7 5037.7 5083.1 5109.2 5137.3 5174.9 4926.4 5004.3 5025.0 5040.3 5094.2 5315.1 5348.3 5370.3 5424.2 5472.3 5770.9 5827.7 5840.6 5911.1 5911.6 6037.4 6043.8 6045.3 6053.8 6064.0 5435.8 5519.3 5521.5 5531.9 5587.2 6169.9 6212.8 6216.4 6232.6 6235.0 5556.5 5875.0 5891.2 5891.3 5929.1 5843.8 5916.9 5918.2 5928.2 6067.7 5869.6 5876.2 5877.1 5879.2 5881.1 5835.5 5864.9 5886.3 5892.4 5910.9 4685.6 4744.5 4757.3 4772.6 4781.0 4329.1 4338.5 4349.1 4353.7 4361.3 5563.1 5566.4 5580.5 5581.6 5622.3 5798.4 5856.1 6063.8 6076.8 6087.8 5506.6 5509.1 5519.7 5521.1 5536.2 5178.9 5187.5 5192.6 5194.5 5202.5 6146.7 6149.1 6154.4 6161.8 6183.4 5476.2 5639.1 6010.5 6117.0 6119.5 4761.8 6292.7 6304.2 6323.5 6330.4 5694.3 5744.0 5819.0 5953.6 5964.8 5817.9 5849.3 5849.6 5859.2 5960.9 5710.7 5727.1 5729.2 5730.3 5732.9 5648.2 5689.8 5700.3 5727.0 5729.9 5115.8 5206.8 5602.6 5610.1 5658.4 5732.5 5751.3 5753.1 5764.1 5766.3 5621.0 5622.0 5638.5 5639.3 5665.9 17:01:52 INFO - PROCESS | 1639 | 17:01:52 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:67267.19 Median:37526.62 stddev:152645.89 (406.8%) stddev-sans-first:152991.77 17:01:52 INFO - PROCESS | 1639 | Values: 491.2 500.2 510.4 527.4 567.8 29771.0 31314.0 31513.8 39695.6 40071.8 40756.8 41171.6 41186.6 41187.4 41255.0 9201.6 9332.9 9336.7 9344.1 9344.5 9174.5 9283.4 9284.2 9305.6 9319.3 9234.5 9287.0 9308.2 9310.0 9336.8 9230.4 9290.0 9293.4 9295.1 9333.6 7026.4 9181.4 9241.5 9244.2 9246.1 31966.2 38352.4 38548.8 38591.8 38661.0 3224.3 3283.6 3329.1 3479.3 3674.5 27999.3 28250.2 28464.1 28661.4 28837.6 2655.4 3131.3 3153.0 3178.1 3272.6 490879.0 492004.0 493102.0 495435.0 500002.0 32341.7 33244.8 55575.4 56070.7 56116.9 33926.7 34450.0 34459.3 34480.1 34514.0 14095.1 14229.5 14250.4 14253.8 14315.0 11337.6 11853.2 11930.5 11961.6 11969.4 7657.3 7673.7 7736.7 7741.7 7749.3 2719.7 3114.6 3182.2 3192.3 3229.7 945.9 977.2 995.7 1037.0 1049.9 23644.0 27993.6 28842.4 29574.0 29639.3 1028.6 1029.3 1032.0 1149.6 1168.1 14673.9 14685.7 14968.0 15079.3 15421.4 659175.0 665190.0 665251.0 666514.0 667357.0 55697.7 56058.4 56110.7 56197.0 56346.7 1363.1 1375.1 1398.2 1399.9 1401.7 28283.6 28876.6 28927.8 29247.0 29544.7 47268.3 47460.8 47476.1 47543.8 47835.8 524736.0 533405.0 537246.0 538713.0 539098.0 25493.2 25500.9 25923.0 25979.6 26051.2 44344.0 44662.6 44780.0 45224.8 45595.0 37497.4 37555.8 37732.4 37786.0 38421.2 36687.6 36768.3 37344.4 38242.8 38383.2 31408.7 31519.8 32066.8 36822.4 38733.2 28727.9 32817.7 32870.0 32890.7 32895.3 24242.8 24652.9 24880.3 25090.6 25253.7 17:01:52 INFO - PROCESS | 1639 | 17:01:52 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:34477.98 Median:2011.41 stddev:96512.34 (4798.2%) stddev-sans-first:96749.01 17:01:52 INFO - PROCESS | 1639 | Values: 322.5 346.7 364.5 373.3 400.3 15057.5 15456.5 17405.4 19275.0 20372.7 16717.5 19350.8 19744.5 19793.6 19987.6 5482.1 5573.2 5670.5 5720.7 5729.3 4344.0 5658.4 5679.3 5850.7 5890.3 5913.2 5932.4 5934.4 5935.0 5956.0 5625.5 5761.0 5796.1 5872.6 5931.5 5123.3 5461.5 5686.8 5699.3 5699.6 24683.0 24696.6 25124.7 26090.6 26213.7 2339.3 2391.8 2414.8 2818.4 3020.5 12950.4 16014.1 16196.8 16885.8 16922.7 1915.3 1922.8 1983.3 1989.5 1999.3 131994.3 137706.0 140399.0 144935.0 147159.0 17803.7 20753.2 20772.3 21007.4 21014.7 28406.0 30138.7 30148.0 31948.8 33159.5 9509.8 9834.3 9875.0 9888.0 9904.0 8027.1 8038.6 8065.8 8128.6 8196.6 7426.1 8857.7 9380.4 9438.3 9505.2 3145.4 3568.0 3635.8 3649.5 3753.8 377.1 780.6 875.8 883.1 931.6 11217.3 11711.9 11775.5 12146.9 12148.3 347.7 385.2 393.3 420.5 436.7 5886.7 6051.0 6125.4 6174.2 6178.4 536822.0 576327.0 577247.0 589823.0 603087.0 69739.7 74526.6 107225.5 123633.0 136075.0 926.9 937.6 979.7 993.6 998.1 16033.8 17901.2 19105.4 19188.8 19221.6 34575.5 39404.0 43599.8 48120.3 52963.5 64525.7 75384.5 77290.2 79354.6 81524.6 7732.5 7787.7 7994.8 8103.8 8136.2 11398.1 12281.8 12347.1 12434.8 12472.8 10649.4 10692.1 10837.1 10990.7 11005.6 10600.1 10655.6 10829.9 10835.9 11048.7 10877.3 10904.1 11068.4 11185.8 11260.4 8011.8 8032.2 8147.4 8177.8 8233.9 9610.2 10913.9 10936.9 11038.6 11378.6 17:01:52 INFO - PROCESS | 1639 | 17:01:52 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:5405.19 Median:5203.11 stddev:2936.18 (56.4%) stddev-sans-first:2929.41 17:01:52 INFO - PROCESS | 1639 | Values: 1454.8 1477.8 1616.8 1770.8 1819.0 7971.9 8415.7 8420.2 8420.5 8445.5 7416.9 7605.4 7632.8 7987.6 8210.6 2285.4 2346.3 2347.0 2348.6 2349.7 2329.1 2353.2 2359.1 2377.5 2379.8 2297.8 2306.7 2314.4 2328.1 2333.8 2300.8 2301.3 2319.0 2321.0 2331.5 2328.2 2354.8 2355.3 2359.2 2361.7 7084.5 8245.2 8297.0 8302.0 8306.8 4985.5 5156.8 5157.3 5160.5 5182.8 3239.8 3308.4 3531.2 3584.5 3597.1 986.2 1051.9 1056.9 1078.4 1101.1 10733.8 10766.9 10875.7 10885.7 10917.3 4523.4 4524.0 4715.7 4726.5 4753.8 9346.1 9351.8 9368.1 9378.2 9397.9 5191.1 5196.5 5198.5 5201.3 5204.9 3941.9 4128.3 4706.6 4763.2 4780.8 3275.3 3776.6 3779.9 3884.7 3895.4 1249.3 1252.6 1260.7 1261.6 1267.7 877.7 882.8 886.9 887.3 906.3 5400.4 5505.2 5514.3 5574.6 5608.9 949.3 958.8 970.8 990.8 1012.6 3599.5 3656.7 3719.9 3789.9 3849.2 9632.3 9953.3 9960.7 10023.4 10047.1 7678.5 9931.5 9952.6 9957.1 9960.4 7483.4 7685.5 7696.0 7699.7 7735.0 5504.3 5525.1 5526.9 5630.5 5701.0 9327.3 9410.4 9412.6 9463.7 9488.7 9274.3 9435.0 9584.4 9622.6 9630.8 7059.3 7073.8 7140.3 7256.9 7286.2 7789.2 7878.3 7879.8 7880.6 7889.7 6946.1 6947.0 7129.0 7202.2 7238.6 6931.5 7010.2 7017.1 7114.6 7142.3 6548.8 6550.8 6620.3 6636.8 6671.8 4757.6 4766.5 4777.3 4790.4 4851.8 4623.8 4655.1 4680.3 4840.6 4923.8 17:01:52 INFO - PROCESS | 1639 | 17:01:52 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4260.26 Median:4752.66 stddev:1291.38 (27.2%) stddev-sans-first:1260.24 17:01:52 INFO - PROCESS | 1639 | Values: 295.2 299.9 304.5 310.9 311.9 4992.2 5010.9 5236.0 5251.3 5273.0 5131.3 5133.7 5150.9 5155.4 5188.3 3474.4 3725.6 3789.5 3869.1 3980.2 3334.5 3354.7 3669.3 4005.0 4022.8 3924.4 3946.5 3965.5 3980.8 4001.6 3037.5 3603.6 3756.4 3929.0 3967.0 3420.5 3426.7 3437.6 3441.8 3443.6 5059.7 5092.6 5095.6 5143.4 5155.5 3954.6 4033.9 4306.1 4342.9 4468.9 5490.9 5504.5 5507.3 5507.4 5527.9 3181.9 3223.8 3783.1 3786.7 3814.7 5240.6 5475.1 5646.7 5654.2 5756.4 3814.3 3820.5 3828.9 3834.4 3840.7 3352.4 3885.8 3887.8 3894.3 3911.8 4518.5 4528.9 4534.5 4543.5 4559.4 5149.9 5418.2 5476.0 5534.9 5593.9 1059.7 1214.5 1217.6 1226.0 1252.2 736.4 828.3 842.7 849.5 857.4 4369.3 4617.4 4782.7 4816.6 4833.9 4511.6 4525.4 5247.8 5260.3 5286.5 4162.9 4680.4 4741.9 4747.5 4788.3 2584.9 2637.3 2639.9 2783.6 2783.9 4750.8 4955.7 5319.9 5399.7 5412.9 4442.8 4490.1 5006.7 5312.3 5379.0 4677.8 4872.8 5020.0 5210.5 5444.8 5064.2 5205.3 5231.2 5238.4 5254.8 4685.5 4759.6 5045.9 5062.8 5063.8 4810.4 4814.0 5434.8 5475.0 5519.6 3155.9 3361.6 3403.4 3540.9 3581.8 4782.8 4911.4 5284.7 5319.8 5320.8 5072.1 5081.3 5098.5 5098.7 5131.3 4448.2 4560.5 4928.7 4955.0 5163.6 4054.8 4471.3 4545.7 4554.8 4560.8 4546.7 4819.7 5374.8 5482.8 5582.6 4754.5 5237.3 5385.6 5388.2 5401.9 17:01:52 INFO - PROCESS | 1639 | 17:01:52 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:20272.06 Median:4558.75 stddev:29760.65 (652.8%) stddev-sans-first:29807.52 17:01:52 INFO - PROCESS | 1639 | Values: 609.7 766.3 766.6 780.3 780.6 12733.7 13217.7 13908.1 14322.6 14495.8 11530.8 12202.0 12292.9 12392.3 15137.8 806.3 835.1 837.4 837.9 838.4 830.2 832.5 832.6 833.4 835.0 687.3 708.5 709.3 710.6 712.9 847.6 877.3 878.6 879.8 880.7 651.3 652.0 652.3 652.3 653.9 13633.0 13932.7 13977.7 13990.0 14007.2 13020.0 13043.9 13063.3 13066.9 13215.9 11592.2 11627.6 11829.6 12022.9 12243.8 4306.4 4322.0 4324.1 4472.5 4505.6 59473.0 76342.9 76667.6 82798.0 85881.6 9209.5 10532.0 10806.2 10869.3 11261.9 20769.4 20773.5 20923.0 21167.2 21355.4 7587.9 7746.7 7823.6 7843.1 7930.9 4219.2 4232.9 4303.1 4470.4 4477.9 3417.5 3500.3 3547.3 3547.8 3553.1 631.4 638.4 650.1 662.8 664.8 568.2 663.7 667.3 677.3 680.6 8303.6 8475.8 8833.6 8994.6 9078.9 589.8 591.2 626.7 663.7 670.1 4395.4 4544.3 4551.2 4566.3 4600.2 109902.5 111127.0 112504.4 112783.4 113714.0 88239.0 92601.0 93047.1 94121.1 94830.5 83266.2 83861.6 85352.0 86509.6 88841.0 8582.3 8818.0 8869.9 8876.7 9062.0 53962.5 54999.0 56573.0 56942.7 56994.9 75773.0 78645.0 81100.6 81225.0 81888.0 11008.8 11176.6 11304.4 11399.5 11545.9 12051.3 12061.3 12177.0 12424.6 12839.7 11247.4 11269.4 11499.2 11783.4 11924.0 11867.6 12218.5 12307.0 12327.0 13221.0 12390.7 12782.0 13114.8 13373.9 13392.6 10578.6 10713.5 10809.5 11112.8 11222.0 8990.0 11058.0 11360.7 11363.9 11411.8 17:01:52 INFO - PROCESS | 1639 | -------- Summary: end -------- 17:01:52 INFO - PROCESS | 1639 | 17:01:52 INFO - TEST-INFO | 1639: exit 0 17:01:52 INFO - TEST-OK | dromaeo_css | took 1120181ms 17:01:52 INFO - TEST-START | kraken 17:01:52 INFO - operating with platform_type : mac_ 17:01:52 INFO - Initialising browser for kraken test... 17:01:52 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/tmp9HFaZa/profile http://localhost:49237/getInfo.html) 17:01:55 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 17:01:55 INFO - PROCESS | 1648 | colorDepth:24 17:01:55 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 17:01:55 INFO - PROCESS | 1648 | __metrics 17:01:55 INFO - PROCESS | 1648 | 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" 17:01:55 INFO - PROCESS | 1648 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:01:56 INFO - TEST-INFO | 1648: exit 0 17:01:56 INFO - Browser initialized. 17:01:56 INFO - Running cycle 1/1 for kraken test... 17:01:56 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/tmp9HFaZa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:02:08 INFO - PROCESS | 1649 | RSS: Main: 222535680 17:02:08 INFO - PROCESS | 1649 | 17:02:11 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection) 17:02:12 INFO - PROCESS | 1649 | RSS: Main: 271929344 17:02:12 INFO - PROCESS | 1649 | 17:02:15 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-dft) 17:02:15 INFO - PROCESS | 1649 | RSS: Main: 284307456 17:02:15 INFO - PROCESS | 1649 | 17:02:18 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-fft) 17:02:19 INFO - PROCESS | 1649 | RSS: Main: 379547648 17:02:19 INFO - PROCESS | 1649 | 17:02:21 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator) 17:02:21 INFO - PROCESS | 1649 | RSS: Main: 281927680 17:02:21 INFO - PROCESS | 1649 | 17:02:23 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:02:24 INFO - PROCESS | 1649 | RSS: Main: 284110848 17:02:24 INFO - PROCESS | 1649 | 17:02:27 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom) 17:02:27 INFO - PROCESS | 1649 | RSS: Main: 324853760 17:02:27 INFO - PROCESS | 1649 | 17:02:30 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate) 17:02:31 INFO - PROCESS | 1649 | RSS: Main: 325320704 17:02:31 INFO - PROCESS | 1649 | 17:02:34 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial) 17:02:34 INFO - PROCESS | 1649 | RSS: Main: 322027520 17:02:34 INFO - PROCESS | 1649 | 17:02:36 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:02:37 INFO - PROCESS | 1649 | RSS: Main: 298741760 17:02:37 INFO - PROCESS | 1649 | 17:02:38 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:02:39 INFO - PROCESS | 1649 | RSS: Main: 298954752 17:02:39 INFO - PROCESS | 1649 | 17:02:41 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:02:42 INFO - PROCESS | 1649 | RSS: Main: 302469120 17:02:42 INFO - PROCESS | 1649 | 17:02:45 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:02:45 INFO - PROCESS | 1649 | RSS: Main: 320372736 17:02:45 INFO - PROCESS | 1649 | 17:02:49 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:02:49 INFO - PROCESS | 1649 | RSS: Main: 313909248 17:02:49 INFO - PROCESS | 1649 | 17:02:51 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49237/tests/kraken/driver.html?testName=ai-astar) 17:02:51 INFO - PROCESS | 1649 | RSS: Main: 357994496 17:02:51 INFO - PROCESS | 1649 | 17:02:51 INFO - PROCESS | 1649 | __start_tp_report 17:02:51 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 17:02:51 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 17:02:51 INFO - PROCESS | 1649 | |i|pagename|runs| 17:02:51 INFO - PROCESS | 1649 | |0;ai-astar;115;114;114;115;115;115;116;115;115;115 17:02:51 INFO - PROCESS | 1649 | |1;audio-beat-detection;145;107;136;175;140;105;110;113;141;113 17:02:51 INFO - PROCESS | 1649 | |2;audio-dft;187;184;182;185;184;184;184;183;183;182 17:02:51 INFO - PROCESS | 1649 | |3;audio-fft;82;59;58;88;60;58;58;59;81;57 17:02:51 INFO - PROCESS | 1649 | |4;audio-oscillator;89;71;94;70;68;69;89;67;68;92 17:02:51 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;88;86;89;85;88;85;89;85;89;84 17:02:51 INFO - PROCESS | 1649 | |6;imaging-darkroom;94;94;95;94;95;102;149;135;94;94 17:02:51 INFO - PROCESS | 1649 | |7;imaging-desaturate;83;80;82;79;79;83;83;84;82;82 17:02:51 INFO - PROCESS | 1649 | |8;json-parse-financial;55;54;53;54;57;58;58;56;57;57 17:02:51 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;64;72;71;75;72;70;70;69;70;71 17:02:51 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;83;81;83;83;84;83;84;86;84;83 17:02:51 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;142;147;144;127;144;144;156;157;191;138 17:02:51 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;173;231;188;164;223;210;166;165;165;165 17:02:51 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;68;68;68;68;66;68;67;67;66;67 17:02:51 INFO - PROCESS | 1649 | __end_tp_report 17:02:51 INFO - PROCESS | 1649 | __start_cc_report 17:02:51 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,2003 17:02:51 INFO - PROCESS | 1649 | __end_cc_report 17:02:51 INFO - PROCESS | 1649 | __startTimestamp1481763771639__endTimestamp 17:02:51 INFO - PROCESS | 1649 | 17:02:51 INFO - PROCESS | 1649 | ------- Summary: start ------- 17:02:51 INFO - PROCESS | 1649 | Number of tests: 14 17:02:51 INFO - PROCESS | 1649 | 17:02:51 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:114.90 Median:115.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 17:02:51 INFO - PROCESS | 1649 | Values: 115.0 114.0 114.0 115.0 115.0 115.0 116.0 115.0 115.0 115.0 17:02:51 INFO - PROCESS | 1649 | 17:02:51 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:128.50 Median:138.00 stddev:22.65 (16.4%) stddev-sans-first:23.22 17:02:51 INFO - PROCESS | 1649 | Values: 145.0 107.0 136.0 175.0 140.0 105.0 110.0 113.0 141.0 113.0 17:02:51 INFO - PROCESS | 1649 | 17:02:51 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:183.80 Median:184.00 stddev:1.48 (0.8%) stddev-sans-first:1.01 17:02:51 INFO - PROCESS | 1649 | Values: 187.0 184.0 182.0 185.0 184.0 184.0 184.0 183.0 183.0 182.0 17:02:51 INFO - PROCESS | 1649 | 17:02:51 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:66.00 Median:59.50 stddev:12.35 (20.8%) stddev-sans-first:11.66 17:02:51 INFO - PROCESS | 1649 | Values: 82.0 59.0 58.0 88.0 60.0 58.0 58.0 59.0 81.0 57.0 17:02:51 INFO - PROCESS | 1649 | 17:02:51 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:80.00 stddev:11.59 (14.5%) stddev-sans-first:11.54 17:02:51 INFO - PROCESS | 1649 | Values: 89.0 71.0 94.0 70.0 68.0 69.0 89.0 67.0 68.0 92.0 17:02:51 INFO - PROCESS | 1649 | 17:02:51 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:86.80 Median:88.00 stddev:1.99 (2.3%) stddev-sans-first:2.06 17:02:51 INFO - PROCESS | 1649 | Values: 88.0 86.0 89.0 85.0 88.0 85.0 89.0 85.0 89.0 84.0 17:02:51 INFO - PROCESS | 1649 | 17:02:51 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:104.60 Median:94.00 stddev:20.13 (21.4%) stddev-sans-first:20.99 17:02:51 INFO - PROCESS | 1649 | Values: 94.0 94.0 95.0 94.0 95.0 102.0 149.0 135.0 94.0 94.0 17:02:51 INFO - PROCESS | 1649 | 17:02:51 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:81.70 Median:82.50 stddev:1.77 (2.1%) stddev-sans-first:1.81 17:02:51 INFO - PROCESS | 1649 | Values: 83.0 80.0 82.0 79.0 79.0 83.0 83.0 84.0 82.0 82.0 17:02:51 INFO - PROCESS | 1649 | 17:02:51 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:55.90 Median:57.00 stddev:1.79 (3.1%) stddev-sans-first:1.87 17:02:51 INFO - PROCESS | 1649 | Values: 55.0 54.0 53.0 54.0 57.0 58.0 58.0 56.0 57.0 57.0 17:02:51 INFO - PROCESS | 1649 | 17:02:51 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:71.00 stddev:2.80 (3.9%) stddev-sans-first:1.76 17:02:51 INFO - PROCESS | 1649 | Values: 64.0 72.0 71.0 75.0 72.0 70.0 70.0 69.0 70.0 71.0 17:02:51 INFO - PROCESS | 1649 | 17:02:51 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:83.40 Median:83.50 stddev:1.26 (1.5%) stddev-sans-first:1.33 17:02:51 INFO - PROCESS | 1649 | Values: 83.0 81.0 83.0 83.0 84.0 83.0 84.0 86.0 84.0 83.0 17:02:51 INFO - PROCESS | 1649 | 17:02:51 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:149.00 Median:145.50 stddev:17.03 (11.7%) stddev-sans-first:17.87 17:02:51 INFO - PROCESS | 1649 | Values: 142.0 147.0 144.0 127.0 144.0 144.0 156.0 157.0 191.0 138.0 17:02:51 INFO - PROCESS | 1649 | 17:02:51 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.00 Median:180.50 stddev:26.54 (14.7%) stddev-sans-first:27.79 17:02:51 INFO - PROCESS | 1649 | Values: 173.0 231.0 188.0 164.0 223.0 210.0 166.0 165.0 165.0 165.0 17:02:51 INFO - PROCESS | 1649 | 17:02:51 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:68.00 stddev:0.82 (1.2%) stddev-sans-first:0.83 17:02:51 INFO - PROCESS | 1649 | Values: 68.0 68.0 68.0 68.0 66.0 68.0 67.0 67.0 66.0 67.0 17:02:51 INFO - PROCESS | 1649 | -------- Summary: end -------- 17:02:51 INFO - PROCESS | 1649 | 17:02:52 INFO - TEST-INFO | 1649: exit 0 17:02:52 INFO - TEST-OK | kraken | took 59931ms 17:02:52 INFO - SUITE-END | took 1180s 17:02:52 INFO - Completed test suite (00:19:40) 17:02:52 INFO - javascript benchmark 17:02:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2728.7680547153495, 3285.3593549257903, 3287.589051368579, 3309.966900330999, 3338.482535207042, 4221.620054195122, 5458.781648734051, 5468.6718796872165, 5515.393306736256, 5564.387917329091, 5445.782168713246, 5478.808241711541, 5498.835034948958, 5511.614187006911, 5519.530839878616, 5035.723035233061, 5052.418971818232, 5111.463296353885, 5130.153524668425, 5135.845924622267, 5035.1188542005, 5050.747462626872, 5054.3176671149295, 5074.340335756347, 5189.221616757498, 4266.2320782259185, 4893.04585605807, 5047.066292735837, 5057.9494205057845, 5067.695938243699, 5037.672551284173, 5083.11045567028, 5109.156989096795, 5137.332146820927, 5174.896502069975, 4926.3842019747535, 5004.3494345734825, 5025.045241404122, 5040.294358789735, 5094.235864620299, 5315.149576067811, 5348.277982472356, 5370.275012873251, 5424.186372044225, 5472.315960504937, 5770.884582308372, 5827.650340979512, 5840.591158618899, 5911.142884281755, 5911.645301281937, 6037.426444487811, 6043.8186854394435, 6045.3047899491685, 6053.848653783691, 6064.0, 5435.755391007454, 5519.282493275929, 5521.503064724194, 5531.917021244684, 5587.189857470644, 6169.907451388232, 6212.782552610637, 6216.409441103088, 6232.594881332699, 6234.9688251558455, 5556.472135147154, 5875.0, 5891.23413956183, 5891.322497912709, 5929.14027466015, 5843.795467158629, 5916.940830591639, 5918.171455996164, 5928.229330187048, 6067.666278354733, 5869.61847479921, 5876.236089308448, 5877.118432235199, 5879.176915231871, 5881.05903055509, 5835.533157347402, 5864.8533786654825, 5886.293644762656, 5892.410758924159, 5910.852228694231, 4685.625149987993, 4744.478107408182, 4757.333973243749, 4772.6420518461255, 4780.995990841892, 4329.1341731653165, 4338.522762496123, 4349.108432771274, 4353.651707863363, 4361.280388735823, 5563.082091454865, 5566.443355664482, 5580.469855363734, 5581.6371935824045, 5622.297212848394, 5798.449147330997, 5856.0630299151935, 6063.818085457443, 6076.75692972285, 6087.756489740361, 5506.614536982453, 5509.090999985037, 5519.668819870821, 5521.116621340566, 5536.197251398606, 5178.896422071537, 5187.533122019036, 5192.636515443883, 5194.506521880491, 5202.453742357074, 6146.678464130234, 6149.077638354193, 6154.446099851035, 6161.784337548164, 6183.4125758053815, 5476.233327334096, 5639.097744360883, 6010.459058684739, 6117.021276595723, 6119.4798442132915, 4761.785719642694, 6292.653904035322, 6304.243490780952, 6323.525735569759, 6330.430261276507, 5694.345150307768, 5744.023516002206, 5819.039858423313, 5953.642781433128, 5964.761409543569, 5817.854553636023, 5849.268841394825, 5849.64902105875, 5859.179714839841, 5960.850978725566, 5710.65736055838, 5727.140928860705, 5729.197912292199, 5730.283714535683, 5732.914006289992, 5648.15277708347, 5689.800856969986, 5700.287464066992, 5726.9713651431475, 5729.914051289151, 5115.848933989822, 5206.817761378334, 5602.5517958562405, 5610.0743377343915, 5658.377578466283, 5732.541396688338, 5751.281089863767, 5753.05074662676, 5764.13537969308, 5766.3080430346945, 5621.044422448275, 5622.044252477007, 5638.548916086621, 5639.295088113986, 5665.858353541194], "value": 5446.31943440969, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [491.22631854828654, 500.23214365948263, 510.4181233393934, 527.4119356917038, 567.8438429431903, 29770.9580164694, 31314.0, 31513.84243078784, 39695.60304396955, 40071.79784606466, 40756.79621601895, 41171.58828411723, 41186.58813411865, 41187.38218926711, 41255.0, 9201.58592863321, 9332.860007099865, 9336.673216437426, 9344.112309330603, 9344.53277336134, 9174.54127293636, 9283.442993420416, 9284.16442520173, 9305.627774888997, 9319.254459643213, 9234.492102934337, 9286.953565232165, 9308.162265396113, 9310.0, 9336.813263734755, 9230.400023998418, 9290.024547422558, 9293.442393456347, 9295.116963888488, 9333.579988900516, 7026.43788496919, 9181.449113053239, 9241.49171795551, 9244.168024877727, 9246.075392460767, 31966.200844979063, 38352.42471362932, 38548.807255963824, 38591.80704096489, 38661.0, 3224.2906560556876, 3283.589551306087, 3329.067860998924, 3479.3215323012023, 3674.4672022556847, 27999.30001749952, 28250.15249542517, 28464.146075617766, 28661.426771464456, 28837.56743648847, 2655.3892604701027, 3131.295458521837, 3153.0225630054756, 3178.1260153457984, 3272.640009598942, 490879.0, 492004.0, 493102.0, 495435.0, 500002.0, 32341.676583234337, 33244.83377583145, 55575.444245557024, 56070.71964640151, 56116.87766244734, 33926.66073339235, 34450.0, 34459.31081378408, 34480.13799654978, 34514.0, 14095.083819551697, 14229.501967431297, 14250.429982800571, 14253.78619320711, 14314.9979501436, 11337.603183888525, 11853.170278080452, 11930.522779088738, 11961.64115076549, 11969.401529923469, 7657.349125324298, 7673.693052277847, 7736.7292144774665, 7741.729039483591, 7749.302562769378, 2719.7008329083783, 3114.6418161911215, 3182.204448887778, 3192.265778870826, 3229.725473334746, 945.8628498867625, 977.1645243316862, 995.6515219673201, 1037.0, 1049.884512703602, 23644.0, 27993.58009629857, 28842.423151536852, 29574.0, 29639.259018524277, 1028.5600159776222, 1029.315505189041, 1032.02989190161, 1149.6033868315417, 1168.1063986050679, 14673.92663036678, 14685.706285874223, 14968.0, 15079.321430535652, 15421.383144674088, 659175.0, 665190.0, 665251.0, 666514.0, 667357.0, 55697.721511392185, 56058.43941560614, 56110.71944640251, 56197.0, 56346.7182664084, 1363.1412210307442, 1375.1061809823696, 1398.2030242761732, 1399.9230042347565, 1401.663600735816, 28283.575746364233, 28876.566851497664, 28927.842886284314, 29247.0, 29544.704552955056, 47268.29097563608, 47460.7626961863, 47476.05047899092, 47543.76228118837, 47835.76082119567, 524736.0, 533405.0, 537246.0, 538713.0, 539098.0, 25493.235202943943, 25500.872495637403, 25923.0, 25979.61030584505, 26051.218463446126, 44344.0, 44662.55337446714, 44780.0, 45224.773876130406, 45595.0, 37497.4375384364, 37555.81222093872, 37732.43401349037, 37786.0, 38421.231575367776, 36687.63312366842, 36768.26463470662, 37344.43983340198, 38242.808785955895, 38383.23233535258, 31408.685913140576, 31519.842400788766, 32066.839665801523, 36822.44766328561, 38733.2253354937, 28727.856360718062, 32817.67182328241, 32870.0, 32890.67109328972, 32895.342093157524, 24242.817303327403, 24652.876735616923, 24880.25359239226, 25090.62364064577, 25253.747462525138], "value": 17915.590012910976, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.5226664536486, 346.69664043961535, 364.52065533823037, 373.3354628760806, 400.2894861620625, 15057.472988445406, 15456.45902393415, 17405.390811321624, 19275.0, 20372.69440958387, 16717.498475045737, 19350.808990336067, 19744.506387340323, 19793.60412791743, 19987.600247995033, 5482.122860342346, 5573.164025396192, 5670.518005969498, 5720.742566584514, 5729.28383952006, 4344.0, 5658.405867383927, 5679.346875109353, 5850.707464626773, 5890.3226128995075, 5913.172155898178, 5932.436418540232, 5934.436228558302, 5935.0, 5956.047032474805, 5625.465580769841, 5761.0, 5796.101604251347, 5872.5889187756875, 5931.466168044875, 5123.3339665843205, 5461.453854614507, 5686.772529098831, 5699.316082070137, 5699.601027928046, 24683.0, 24696.629550556758, 25124.748752512605, 26090.608640870403, 26213.73786262132, 2339.3332900123382, 2391.7727815857465, 2414.8068154547595, 2818.422223444189, 3020.4865172920654, 12950.417231224617, 16014.119223442705, 16196.83803161965, 16885.831141688548, 16922.66154676912, 1915.3200613782185, 1922.7500424944667, 1983.2562788954142, 1989.4628450318332, 1999.3302243748217, 131994.34002829925, 137706.0, 140399.0, 144935.0, 147159.0, 17803.73294400585, 20753.169873205206, 20772.272970445963, 21007.369778906657, 21014.684779728315, 28406.0, 30138.69861301403, 30148.0, 31948.840255798572, 33159.502607460905, 9509.809803804024, 9834.262430317754, 9875.0, 9888.0, 9903.950480247553, 8027.117017128111, 8038.638261278256, 8065.838683226302, 8128.593570321578, 8196.590170491452, 7426.071741032371, 8857.689980850639, 9380.390274632127, 9438.292128090421, 9505.19401313762, 3145.4338219120777, 3567.970130479481, 3635.818209089535, 3649.4934574698195, 3753.774773513598, 377.1156637684625, 780.5550836023413, 875.8438860703938, 883.0860059838037, 931.6366617019368, 11217.270877392943, 11711.88288117108, 11775.470103845348, 12146.878531214752, 12148.331841748612, 347.67145047929466, 385.189176782868, 393.26066994051, 420.5437100745725, 436.7161345125694, 5886.735096920649, 6050.969745151246, 6125.356837532084, 6174.197354343996, 6178.382161783876, 536822.0, 576327.0, 577247.0, 589823.0, 603087.0, 69739.65130174317, 74526.62736686281, 107225.46387268013, 123633.0, 136075.0, 926.8841394825647, 937.5921474158763, 979.6816034788723, 993.5905429333692, 998.116666749917, 16033.797465189924, 17901.19444625021, 19105.426837194907, 19188.8081119187, 19221.615567688288, 34575.481367780005, 39404.0, 43599.78200108979, 48120.27819582779, 52963.4703652974, 64525.677371612845, 75384.49231015239, 77290.22709773055, 79354.6032269835, 81524.59237703774, 7732.536047837148, 7787.688492460238, 7994.84010319802, 8103.756887293391, 8136.22705842944, 11398.145139114766, 12281.754364912473, 12347.073969452507, 12434.813477798021, 12472.812907806208, 10649.361038337414, 10692.14462842955, 10837.13302935779, 10990.670279891616, 11005.61480348216, 10600.0989915858, 10655.57377704883, 10829.945850270698, 10835.945820270848, 11048.723781905517, 10877.347359158475, 10904.141706156466, 11068.446577670924, 11185.832212516656, 11260.380679062402, 8011.83976320482, 8032.236937490929, 8147.429679922586, 8177.754667359989, 8233.88842506254, 9610.231181505602, 10913.945430272797, 10936.890631093906, 11038.613648522263, 11378.601748938747], "value": 9639.929634843744, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1454.7963285140079, 1477.7931089647448, 1616.7736516887637, 1770.7697999260095, 1818.9818101818978, 7971.88042179367, 8415.70545030924, 8420.200080992296, 8420.494770313786, 8445.451045682034, 7416.925830741691, 7605.429592780536, 7632.824644976183, 7987.640556174972, 8210.630521626526, 2285.371522831218, 2346.3312955807596, 2346.9530609387803, 2348.6377900817483, 2349.729781075181, 2329.114936324203, 2353.211674089174, 2359.0681680736097, 2377.476955069882, 2379.7501262367464, 2297.781710737477, 2306.746257911628, 2314.432963923841, 2328.0571368595747, 2333.8132949364012, 2300.7814257645496, 2301.3326135420707, 2318.953620927589, 2321.0019691532634, 2331.5103828196097, 2328.2433209207074, 2354.8233882458885, 2355.305184970438, 2359.1624973134462, 2361.669366288721, 7084.504084714072, 8245.175482451767, 8297.004359476841, 8302.0, 8306.83386332276, 4985.526374994406, 5156.767945442464, 5157.329547158845, 5160.483951604847, 5182.792688292464, 3239.7732158748895, 3308.4210263203845, 3531.2231309111953, 3584.4802503636824, 3597.0647631615966, 986.1716158426884, 1051.9316244444162, 1056.862607860981, 1078.3691540448847, 1101.0971003776906, 10733.78532429347, 10766.946165269203, 10875.728106797234, 10885.673429797118, 10917.290376125526, 4523.36672865799, 4524.027334123113, 4715.717056976592, 4726.480087190406, 4753.83361582343, 9346.11211934865, 9351.812963740687, 9368.063193680713, 9378.202852757591, 9397.859032114524, 5191.091558977239, 5196.45437229093, 5198.532132108128, 5201.323827902348, 5204.8959020819375, 3941.881743547698, 4128.256913755493, 4706.623470122382, 4763.166445872028, 4780.760961951889, 3275.279438523521, 3776.5656949450618, 3779.905502362408, 3884.6698030667144, 3895.376739721631, 1249.287905893632, 1252.5803855708439, 1260.672225221449, 1261.6341261034197, 1267.73377590707, 877.6884206106868, 882.7748924024332, 886.9157430044136, 887.2857349833446, 906.2659246010752, 5400.405955344909, 5505.229267902497, 5514.283143191359, 5574.637648552831, 5608.85977850557, 949.264320151874, 958.8178246133212, 970.8009857979098, 990.7671697151163, 1012.5949620151998, 3599.532060832075, 3656.6708996189936, 3719.9212228453443, 3789.886303410902, 3849.2301539692176, 9632.32573719847, 9953.25350598708, 9960.701178964642, 10023.398596084258, 10047.145992590711, 7678.500897441649, 9931.50342482873, 9952.552135153936, 9957.10386065243, 9960.402375857328, 7483.4013278937555, 7685.4620176587105, 7695.961520192363, 7699.730509432143, 7734.961325193338, 5504.256925315191, 5525.143602741581, 5526.917096243479, 5630.549556035589, 5701.0023245932625, 9327.253819694273, 9410.38832475887, 9412.576434060347, 9463.668771593237, 9488.715338539854, 9274.258059355101, 9435.0, 9584.37701549397, 9622.566984485855, 9630.807383852423, 7059.329363710439, 7073.787786366417, 7140.285971402818, 7256.854862902606, 7286.234945330664, 7789.182135875878, 7878.290953814184, 7879.803004924923, 7880.605969701606, 7889.684412623431, 6946.097007389007, 6946.9652651736415, 7128.964355178191, 7202.207757146814, 7238.6380680966795, 6931.480138989495, 7010.228874823867, 7017.052697885924, 7114.644267786486, 7142.285771423024, 6548.770793022221, 6550.7707230246715, 6620.304867988792, 6636.767713130017, 6671.799846004627, 4757.619390448686, 4766.499517550607, 4777.259524773665, 4790.401199850019, 4851.830185943475, 4623.815047398201, 4655.092257009986, 4680.274557443602, 4840.636952228527, 4923.778429970732], "value": 4406.358600038682, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.17940126448474, 299.9235195025268, 304.4535059568076, 310.878757284659, 311.88460269700215, 4992.226204938232, 5010.874728131798, 5235.973820130899, 5251.343582052244, 5273.0, 5131.255967884655, 5133.666311689747, 5150.871228219296, 5155.448053213655, 5188.2995795567585, 3474.3746125697367, 3725.646063623965, 3789.450529673196, 3869.090763670535, 3980.2238563479987, 3334.5498357721763, 3354.6749034131503, 3669.266146770643, 4004.9799751001206, 4022.7787471689044, 3924.3721004639265, 3946.4672269243715, 3965.5439624443125, 3980.800959952005, 4001.5998400160042, 3037.498812695914, 3603.6036036036016, 3756.380197267448, 3928.9803550982206, 3966.980165099185, 3420.4698271767916, 3426.674465925733, 3437.5874895012757, 3441.82790860456, 3443.6384179661277, 5059.721715305656, 5092.618053645954, 5095.643304968654, 5143.408508021551, 5155.458676838915, 3954.5595332061002, 4033.878983630496, 4306.1172459645695, 4342.934855977162, 4468.927457410231, 5490.862728431781, 5504.504594586467, 5507.339119305669, 5507.394186639466, 5527.889442211133, 3181.88404710867, 3223.839417809587, 3783.148791521913, 3786.734928555002, 3814.7138964577766, 5240.633155679104, 5475.094347078692, 5646.717664116778, 5654.236678048452, 5756.366799652035, 3814.3324918139215, 3820.522434695663, 3828.904277393032, 3834.3673293906195, 3840.731148819556, 3352.3798097351582, 3885.786281754474, 3887.8056097195026, 3894.299026175259, 3911.78485183312, 4518.548145185521, 4528.8677830542365, 4534.478534968455, 4543.54564543543, 4559.3844830948065, 5149.948500515022, 5418.160185171305, 5475.986942415666, 5534.916976245359, 5593.916091258634, 1059.6609085092696, 1214.5445457953267, 1217.5677634439646, 1225.9570915017932, 1252.2111070025826, 736.4255880413286, 828.3207769628847, 842.7429633961578, 849.515776007682, 857.3526987124806, 4369.257226271541, 4617.422822147231, 4782.736949467812, 4816.566509014136, 4833.9274910875665, 4511.5788526614015, 4525.388518237362, 5247.763850626731, 5260.29095016067, 5286.466924591825, 4162.91674166521, 4680.361873344303, 4741.881452963704, 4747.525247475294, 4788.281757736368, 2584.9353766155996, 2637.2725864558743, 2639.930828014657, 2783.620004895785, 2783.9025634103114, 4750.8337208197545, 4955.653104282737, 5319.946800532022, 5399.730013499309, 5412.891742165135, 4442.844500442534, 4490.136593313803, 5006.74966251686, 5312.282841816383, 5378.97310513445, 4677.836275730333, 4872.783962584677, 5020.0, 5210.505002024802, 5444.8366549003595, 5064.215046667772, 5205.323307970091, 5231.189165679326, 5238.371395432572, 5254.842354729364, 4685.461171965268, 4759.571638552547, 5045.899082018413, 5062.7888983673665, 5063.810004648978, 4810.39870016248, 4814.037192561572, 5434.771743585111, 5474.972625136849, 5519.586031047767, 3155.936881262316, 3361.562996810399, 3403.3533628610485, 3540.9468857967668, 3581.779296835874, 4782.760861956958, 4911.4106307243355, 5284.709340986283, 5319.813806516753, 5320.796570709251, 5072.0870243355075, 5081.314022607048, 5098.495943696693, 5098.666133786252, 5131.332926719502, 4448.218165472195, 4560.475545312332, 4928.704277743347, 4954.9752251238515, 5163.624732623036, 4054.8378064877074, 4471.306947423154, 4545.727256364628, 4554.772261386984, 4560.840370587013, 4546.681732278841, 4819.6867203631655, 5374.811881584125, 5482.847362766345, 5582.637128586629, 4754.477007529238, 5237.319148510669, 5385.649932754359, 5388.218708287355, 5401.945980540301], "value": 3848.106468588937, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [609.7195290166524, 766.2873428064652, 766.5852564422066, 780.3180914512923, 780.5706861226328, 12733.745325093492, 13217.735645287088, 13908.095973761698, 14322.570322890304, 14495.782563261546, 11530.79184604809, 12202.0, 12292.877071229284, 12392.31842248676, 15137.772933406008, 806.2985202873508, 835.1106072033265, 837.4347315562002, 837.928776054033, 838.3712215838121, 830.2195935820309, 832.4755404095412, 832.592029905345, 833.4291010657688, 835.0063424524797, 687.3401534526835, 708.479267738212, 709.2623671381782, 710.6375748368343, 712.8859382498802, 847.639753104928, 877.3113106211657, 878.6134100995605, 879.7888506758396, 880.7093659092484, 651.2510612795276, 651.9771807986698, 652.26620052441, 652.3378770547897, 653.8953767397194, 13633.0, 13932.651683707887, 13977.72044559103, 13990.020698551105, 14007.229602371866, 13020.036936275004, 13043.934780326132, 13063.34683265833, 13066.934665326708, 13215.933920330433, 11592.188546801728, 11627.593034243846, 11829.585964491287, 12022.939885300604, 12243.816342754866, 4306.440162778818, 4322.027543802619, 4324.113556720896, 4472.485664148632, 4505.617022553087, 59473.0, 76342.85485717663, 76667.6166619169, 82798.0, 85881.57059214727, 9209.539523023823, 10532.0, 10806.243562950518, 10869.305392322818, 11261.887381126247, 20769.376918692462, 20773.480662983246, 20922.953852307324, 21167.15331386727, 21355.35933921985, 7587.92248223668, 7746.65140068687, 7823.552642761108, 7843.098043725045, 7930.881036784453, 4219.198352313051, 4232.851850185229, 4303.07483890965, 4470.441194850644, 4477.932831007537, 3417.470292104728, 3500.317438099546, 3547.3082748863726, 3547.840347184383, 3553.1117220694828, 631.4222486424961, 638.4126603524768, 650.1125963060389, 662.7680311890896, 664.7772996046378, 568.2328856044307, 663.6880666086931, 667.2983891077732, 677.2685499660355, 680.6392611915693, 8303.584820758937, 8475.830483390298, 8833.55832208387, 8994.640214391482, 9078.86381704275, 589.7788329376484, 591.2225423567994, 626.6960524145786, 663.7212370804177, 670.1455644053871, 4395.384646149541, 4544.272916333371, 4551.203539380595, 4566.292224705176, 4600.196155550388, 109902.45048774705, 111127.0, 112504.43747781208, 112783.4360828207, 113714.0, 88239.0, 92601.0, 93047.0695293052, 94121.0587894126, 94830.52584737031, 83266.16733832705, 83861.58069209737, 85352.0, 86509.5674521636, 88841.0, 8582.270507006975, 8818.0, 8869.866951995855, 8876.733697989072, 9062.048484909254, 53962.46037539731, 54999.0, 56573.0, 56942.7152864233, 56994.86010279854, 75773.0, 78645.0, 81100.59449702714, 81225.0, 81888.0, 11008.834867476835, 11176.608818691308, 11304.434778261219, 11399.487023084146, 11545.884541154817, 12051.276923384625, 12061.336626485627, 12177.0, 12424.56514022005, 12839.679008024874, 11247.437628118398, 11269.380184089627, 11499.195056345976, 11783.410829458664, 11924.0460763137, 11867.643970680549, 12218.511259549874, 12307.0, 12327.013838893046, 13221.0, 12390.690232743893, 12782.0, 13114.803277951029, 13373.866261337262, 13392.598222053353, 10578.576856925636, 10713.464326783474, 10809.513571889127, 11112.777744445228, 11222.0, 8990.0, 11058.004779569877, 11360.659180224606, 11363.943180284046, 11411.771764564495], "value": 7211.135739580201, "unit": "score"}], "name": "dromaeo_css", "value": 6973.716388947658, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 114.0, 115.0, 115.0, 115.0, 116.0, 115.0, 115.0, 115.0], "value": 114.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 107.0, 136.0, 175.0, 140.0, 105.0, 110.0, 113.0, 141.0, 113.0], "value": 128.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 184.0, 182.0, 185.0, 184.0, 184.0, 184.0, 183.0, 183.0, 182.0], "value": 183.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 58.0, 88.0, 60.0, 58.0, 58.0, 59.0, 81.0, 57.0], "value": 66.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 94.0, 70.0, 68.0, 69.0, 89.0, 67.0, 68.0, 92.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 85.0, 88.0, 85.0, 89.0, 85.0, 89.0, 84.0], "value": 86.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 95.0, 102.0, 149.0, 135.0, 94.0, 94.0], "value": 104.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 80.0, 82.0, 79.0, 79.0, 83.0, 83.0, 84.0, 82.0, 82.0], "value": 81.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 53.0, 54.0, 57.0, 58.0, 58.0, 56.0, 57.0, 57.0], "value": 55.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 72.0, 71.0, 75.0, 72.0, 70.0, 70.0, 69.0, 70.0, 71.0], "value": 70.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 83.0, 83.0, 84.0, 83.0, 84.0, 86.0, 84.0, 83.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 147.0, 144.0, 127.0, 144.0, 144.0, 156.0, 157.0, 191.0, 138.0], "value": 149.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 231.0, 188.0, 164.0, 223.0, 210.0, 166.0, 165.0, 165.0, 165.0], "value": 185.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 68.0, 66.0, 68.0, 67.0, 67.0, 66.0, 67.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1455.0, "alertThreshold": 2.0}]} 17:02:52 INFO - Return code: 0 17:02:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:02:52 INFO - # TBPL SUCCESS # 17:02:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:02:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:02:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:02:52 INFO - 2016-12-14 17:02:52.940 screenresolution[1653:9774] starting screenresolution argv=screenresolution get 17:02:52 INFO - 2016-12-14 17:02:52.943 screenresolution[1653:9774] Display 0: 1600x1200x32@60 17:02:52 INFO - 2016-12-14 17:02:52.949 screenresolution[1654:9777] starting screenresolution argv=screenresolution list 17:02:52 INFO - Available Modes on Display 0 17:02:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:02:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:02:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:02:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:02:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:02:53 INFO - Intel Iris: 17:02:53 INFO - Chipset Model: Intel Iris 17:02:53 INFO - Type: GPU 17:02:53 INFO - Bus: Built-In 17:02:53 INFO - VRAM (Dynamic, Max): 1536 MB 17:02:53 INFO - Vendor: Intel (0x8086) 17:02:53 INFO - Device ID: 0x0a2e 17:02:53 INFO - Revision ID: 0x0009 17:02:53 INFO - Displays: 17:02:53 INFO - Display: 17:02:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:02:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:02:53 INFO - Display Serial Number: 1600x1200 60 17:02:53 INFO - Main Display: Yes 17:02:53 INFO - Mirror: Off 17:02:53 INFO - Online: Yes 17:02:53 INFO - Rotation: Supported 17:02:53 INFO - Return code: 0 17:02:53 INFO - Running post-action listener: _resource_record_post_action 17:02:53 INFO - [mozharness: 2016-12-15 01:02:53.199750Z] Finished run-tests step (success) 17:02:53 INFO - Running post-run listener: _resource_record_post_run 17:02:53 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 37598208; Write bytes: 654381056; Read time: 240; Write time: 1899 17:02:53 INFO - TinderboxPrint: CPU usage
53.6% 17:02:53 INFO - TinderboxPrint: I/O read bytes / time
37,598,208 / 240 17:02:53 INFO - TinderboxPrint: I/O write bytes / time
654,381,056 / 1,899 17:02:53 INFO - TinderboxPrint: CPU idle
2,251.5 (46.4%) 17:02:53 INFO - TinderboxPrint: CPU system
970.6 (20.0%) 17:02:53 INFO - TinderboxPrint: CPU user
1,627.6 (33.6%) 17:02:53 INFO - TinderboxPrint: Swap in / out
386,105,344 / 0 17:02:53 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 340437504; Write bytes: 334833152; Read time: 26811; Write time: 705 17:02:53 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 36676608; Write bytes: 302860800; Read time: 229; Write time: 1141 17:02:53 INFO - Running post-run listener: _upload_blobber_files 17:02:53 INFO - Blob upload gear active. 17:02:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:02:53 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:02:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:02:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:02:54 INFO - (blobuploader) - INFO - Open directory for files ... 17:02:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:02:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:02:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:02:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:02:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:02:55 INFO - (blobuploader) - INFO - Done attempting. 17:02:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:02:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:02:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:02:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:02:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:02:56 INFO - (blobuploader) - INFO - Done attempting. 17:02:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:02:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:02:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:02:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:02:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:02:58 INFO - (blobuploader) - INFO - Done attempting. 17:02:58 INFO - (blobuploader) - INFO - Iteration through files over. 17:02:58 INFO - Return code: 0 17:02:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:02:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:02:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9d93278cecb07a18c5abc9cef885b5f8e3a45f4d626c70d576579c6328249ae566b9df7d8e778038ee908ab0ac0c71e58a05db0ee5703cebed3baeb435ac5675", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6dc7e9aa6a41071b8519415009ffcf1887fb62acdfaf346c6c8d7795754ace9f015c6c723b4aa47d9ce07d78bf39ab3e6eb6c271ce7f363f70781abde553a59a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4b96116b1b85dd7b96e3aa7e6466dec2bd7ccc66bed59d75bca3fe1bd299c98e25ba3fee6e6eaa8e87fb3dfd041e4ad8b5e1ade1d7deafdba0e3545920c78fb5"} 17:02:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:02:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:02:58 INFO - Contents: 17:02:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9d93278cecb07a18c5abc9cef885b5f8e3a45f4d626c70d576579c6328249ae566b9df7d8e778038ee908ab0ac0c71e58a05db0ee5703cebed3baeb435ac5675", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6dc7e9aa6a41071b8519415009ffcf1887fb62acdfaf346c6c8d7795754ace9f015c6c723b4aa47d9ce07d78bf39ab3e6eb6c271ce7f363f70781abde553a59a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4b96116b1b85dd7b96e3aa7e6466dec2bd7ccc66bed59d75bca3fe1bd299c98e25ba3fee6e6eaa8e87fb3dfd041e4ad8b5e1ade1d7deafdba0e3545920c78fb5"} 17:02:58 INFO - Running post-run listener: copy_logs_to_upload_dir 17:02:58 INFO - Copying logs to upload dir... 17:02:58 INFO - mkdir: /builds/slave/test/build/upload/logs 17:02:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.540821 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-12-14 17:02:58.590193) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-14 17:02:58.595523) ========= 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.rEfBiaEJtB/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.hPcES89Pt1/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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/9d93278cecb07a18c5abc9cef885b5f8e3a45f4d626c70d576579c6328249ae566b9df7d8e778038ee908ab0ac0c71e58a05db0ee5703cebed3baeb435ac5675", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6dc7e9aa6a41071b8519415009ffcf1887fb62acdfaf346c6c8d7795754ace9f015c6c723b4aa47d9ce07d78bf39ab3e6eb6c271ce7f363f70781abde553a59a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4b96116b1b85dd7b96e3aa7e6466dec2bd7ccc66bed59d75bca3fe1bd299c98e25ba3fee6e6eaa8e87fb3dfd041e4ad8b5e1ade1d7deafdba0e3545920c78fb5"} build_url:https://queue.taskcluster.net/v1/task/X1afLUzfTBGIlMG0B6Rhkg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010990 build_url: 'https://queue.taskcluster.net/v1/task/X1afLUzfTBGIlMG0B6Rhkg/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/9d93278cecb07a18c5abc9cef885b5f8e3a45f4d626c70d576579c6328249ae566b9df7d8e778038ee908ab0ac0c71e58a05db0ee5703cebed3baeb435ac5675", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6dc7e9aa6a41071b8519415009ffcf1887fb62acdfaf346c6c8d7795754ace9f015c6c723b4aa47d9ce07d78bf39ab3e6eb6c271ce7f363f70781abde553a59a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4b96116b1b85dd7b96e3aa7e6466dec2bd7ccc66bed59d75bca3fe1bd299c98e25ba3fee6e6eaa8e87fb3dfd041e4ad8b5e1ade1d7deafdba0e3545920c78fb5"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-14 17:02:58.633595) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 17:02:58.633918) ========= 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.rEfBiaEJtB/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.hPcES89Pt1/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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.005106 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-14 17:02:58.683377) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-14 17:02:58.683728) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-14 17:02:58.684089) ========= ========= Total master_lag: 0.20 =========