builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0152 starttime: 1479880858.06 results: success (0) buildid: 20161122200337 builduid: d2826da50f784ee283523fc1fbd67c96 revision: 0ddfec7126ec503b54df9c4b7c3b988906f6c882 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 22:00:58.055776) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 22:00:58.056182) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 22:00:58.056554) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 22:00:58.375400) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 22:00:58.375776) ========= 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.Rj74ifhysJ/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.VcR7roVL2d/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-22 22:00:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.31M=0.001s 2016-11-22 22:00:58 (9.31 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.446984 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 22:00:58.848660) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 22:00:58.849022) ========= 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.Rj74ifhysJ/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.VcR7roVL2d/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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.023763 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 22:00:58.897591) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-22 22:00:58.897940) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0ddfec7126ec503b54df9c4b7c3b988906f6c882 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 0ddfec7126ec503b54df9c4b7c3b988906f6c882 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Rj74ifhysJ/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.VcR7roVL2d/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-22 22:00:58,980 truncating revision to first 12 chars 2016-11-22 22:00:58,980 Setting DEBUG logging. 2016-11-22 22:00:58,981 attempt 1/10 2016-11-22 22:00:58,981 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0ddfec7126ec?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-22 22:00:59,824 unpacking tar archive at: mozilla-central-0ddfec7126ec/testing/mozharness/ program finished with exit code 0 elapsedTime=1.115110 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-22 22:01:00.037121) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 22:01:00.037451) ========= script_repo_revision: 0ddfec7126ec503b54df9c4b7c3b988906f6c882 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 22:01:00.037902) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 22:01:00.038454) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 22:01:00.059303) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-22 22:01:00.059817) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Rj74ifhysJ/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.VcR7roVL2d/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 22:01:00 INFO - MultiFileLogger online at 20161122 22:01:00 in /builds/slave/test 22:01:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 22:01:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:01:00 INFO - {'append_to_log': False, 22:01:00 INFO - 'base_work_dir': '/builds/slave/test', 22:01:00 INFO - 'blob_upload_branch': 'Firefox', 22:01:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:01:00 INFO - 'branch': 'Firefox', 22:01:00 INFO - 'buildbot_json_path': 'buildprops.json', 22:01:00 INFO - 'config_files': ('talos/mac_config.py',), 22:01:00 INFO - 'default_actions': ('clobber', 22:01:00 INFO - 'read-buildbot-config', 22:01:00 INFO - 'download-and-extract', 22:01:00 INFO - 'populate-webroot', 22:01:00 INFO - 'create-virtualenv', 22:01:00 INFO - 'install', 22:01:00 INFO - 'run-tests'), 22:01:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:01:00 INFO - 'download_minidump_stackwalk': True, 22:01:00 INFO - 'download_symbols': 'ondemand', 22:01:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:01:00 INFO - 'tooltool.py': '/tools/tooltool.py', 22:01:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:01:00 INFO - '/tools/misc-python/virtualenv.py')}, 22:01:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:01:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:01:00 INFO - 'installer_path': 'installer.exe', 22:01:00 INFO - 'log_level': 'info', 22:01:00 INFO - 'log_name': 'talos', 22:01:00 INFO - 'log_to_console': True, 22:01:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:01:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:01:00 INFO - 'opt_config_files': (), 22:01:00 INFO - 'pip_index': False, 22:01:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:01:00 INFO - 'cmd': ('bash', 22:01:00 INFO - '-c', 22:01:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:01:00 INFO - 'enabled': True, 22:01:00 INFO - 'halt_on_failure': False, 22:01:00 INFO - 'name': 'check_screen_resolution'},), 22:01:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:01:00 INFO - 'cmd': ('bash', 22:01:00 INFO - '-c', 22:01:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:01:00 INFO - 'enabled': True, 22:01:00 INFO - 'halt_on_failure': False, 22:01:00 INFO - 'name': 'check_screen_resolution'},), 22:01:00 INFO - 'run_cmd_checks_enabled': True, 22:01:00 INFO - 'sps_profile': False, 22:01:00 INFO - 'sps_profile_interval': 0, 22:01:00 INFO - 'suite': 'dromaeojs', 22:01:00 INFO - 'system_bits': '32', 22:01:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:01:00 INFO - 'title': 't-yosemite-r7-0152', 22:01:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:01:00 INFO - 'use_talos_json': True, 22:01:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:01:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:01:00 INFO - 'work_dir': 'build'} 22:01:00 INFO - [mozharness: 2016-11-23 06:01:00.264392Z] Running clobber step. 22:01:00 INFO - Running pre-action listener: _resource_record_pre_action 22:01:00 INFO - Running main action method: clobber 22:01:00 INFO - rmtree: /builds/slave/test/build 22:01:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:01:02 INFO - Running post-action listener: _resource_record_post_action 22:01:02 INFO - [mozharness: 2016-11-23 06:01:02.716555Z] Finished clobber step (success) 22:01:02 INFO - [mozharness: 2016-11-23 06:01:02.716706Z] Running read-buildbot-config step. 22:01:02 INFO - Running pre-action listener: _resource_record_pre_action 22:01:02 INFO - Running main action method: read_buildbot_config 22:01:02 INFO - Using buildbot properties: 22:01:02 INFO - { 22:01:02 INFO - "project": "", 22:01:02 INFO - "product": "firefox", 22:01:02 INFO - "script_repo_revision": "production", 22:01:02 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 22:01:02 INFO - "repository": "", 22:01:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 22:01:02 INFO - "buildid": "20161122200337", 22:01:02 INFO - "pgo_build": "False", 22:01:02 INFO - "basedir": "/builds/slave/test", 22:01:02 INFO - "buildnumber": 1, 22:01:02 INFO - "slavename": "t-yosemite-r7-0152", 22:01:02 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 22:01:02 INFO - "platform": "yosemite_r7", 22:01:02 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 22:01:02 INFO - "branch": "mozilla-central", 22:01:02 INFO - "repo_path": "mozilla-central", 22:01:02 INFO - "revision": "0ddfec7126ec503b54df9c4b7c3b988906f6c882", 22:01:02 INFO - "stage_platform": "macosx64", 22:01:02 INFO - "builduid": "d2826da50f784ee283523fc1fbd67c96", 22:01:02 INFO - "slavebuilddir": "test" 22:01:02 INFO - } 22:01:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 22:01:02 INFO - Running post-action listener: _resource_record_post_action 22:01:02 INFO - [mozharness: 2016-11-23 06:01:02.719963Z] Finished read-buildbot-config step (success) 22:01:02 INFO - [mozharness: 2016-11-23 06:01:02.720107Z] Running download-and-extract step. 22:01:02 INFO - Running pre-action listener: _resource_record_pre_action 22:01:02 INFO - Running main action method: download_and_extract 22:01:02 INFO - mkdir: /builds/slave/test/build/tests 22:01:02 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')]} 22:01:02 INFO - trying https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 22:01:02 INFO - Downloading https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 22:01:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 22:01:04 INFO - Downloaded 1199 bytes. 22:01:04 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 22:01:04 INFO - Using the following test package requirements: 22:01:04 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 22:01:04 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:01:04 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 22:01:04 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:01:04 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 22:01:04 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:01:04 INFO - u'jsshell-mac.zip'], 22:01:04 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:01:04 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 22:01:04 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 22:01:04 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:01:04 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 22:01:04 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:01:04 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 22:01:04 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:01:04 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 22:01:04 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 22:01:04 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 22:01:04 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:01:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 22:01:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:01:04 INFO - Fetch https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 22:01:04 INFO - Http code: 200 22:01:04 INFO - via: 1.1 f1a40337a32137e1c23ceffead6a50d5.cloudfront.net (CloudFront) 22:01:04 INFO - x-amz-cf-id: -hoiwXiGxODAWsMS444PGUQrzOUEtA_NiEKPtaW83GoNhpRphagm3A== 22:01:04 INFO - x-amz-version-id: lZJOTkZxWTXC5y7DfHxxmg_sNgt7SsjX 22:01:05 INFO - Expected file size: 16267007 22:01:05 INFO - Obtained file size: 16267007 22:01:11 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 22:01:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 22:01:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:01:11 INFO - Fetch https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 22:01:12 INFO - Http code: 200 22:01:12 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 22:01:12 INFO - x-amz-cf-id: awq_llLAUra-aJKUFHN_PkSb4Vxd9mKVxY-6jVAonL7_-UnXHiRDbQ== 22:01:12 INFO - x-amz-version-id: lZJOTkZxWTXC5y7DfHxxmg_sNgt7SsjX 22:01:12 INFO - Expected file size: 16267007 22:01:12 INFO - Obtained file size: 16267007 22:01:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 22:01:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:01:18 INFO - Fetch https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 22:01:19 INFO - Http code: 200 22:01:19 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 22:01:19 INFO - x-amz-cf-id: rrypbjpZKobs2sTWXhzNAA4GbjAIRGgrUImC6S_iCF_k9MIbezwXNQ== 22:01:19 INFO - x-amz-version-id: ZuqwNr6sw3s4oTaiXPzYJc4owAnbKEZr 22:01:19 INFO - Expected file size: 14409506 22:01:19 INFO - Obtained file size: 14409506 22:01:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:20 INFO - trying https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 22:01:20 INFO - Downloading https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 22:01:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 22:01:22 INFO - Downloaded 107522115 bytes. 22:01:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 22:01:22 INFO - mkdir: /builds/slave/test/properties 22:01:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:01:22 INFO - Writing to file /builds/slave/test/properties/build_url 22:01:22 INFO - Contents: 22:01:22 INFO - build_url:https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 22:01:23 INFO - Running post-action listener: _resource_record_post_action 22:01:23 INFO - Running post-action listener: set_extra_try_arguments 22:01:23 INFO - [mozharness: 2016-11-23 06:01:23.508898Z] Finished download-and-extract step (success) 22:01:23 INFO - [mozharness: 2016-11-23 06:01:23.509049Z] Running populate-webroot step. 22:01:23 INFO - Running pre-action listener: _resource_record_pre_action 22:01:23 INFO - Running main action method: populate_webroot 22:01:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:01:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:01:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:01:23 INFO - '--tppagecycles', 22:01:23 INFO - '7'], 22:01:23 INFO - 'tests': ['tsvgm']}}, 22:01:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:01:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:01:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:01:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:01:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:01:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:01:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 22:01:23 INFO - 'talos_options': ['--disable-e10s'], 22:01:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:01:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:01:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:01:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 22:01:23 INFO - 'talos_options': ['--disable-e10s'], 22:01:23 INFO - 'tests': ['damp', 'tps']}, 22:01:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:01:23 INFO - 'tests': ['damp', 'tps']}, 22:01:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:01:23 INFO - 'tests': ['dromaeo_dom']}, 22:01:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:01:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:01:23 INFO - 'tests': ['basic_compositor_video']}, 22:01:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:01:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:01:23 INFO - 'tests': ['a11yr', 22:01:23 INFO - 'ts_paint', 22:01:23 INFO - 'tpaint', 22:01:23 INFO - 'sessionrestore', 22:01:23 INFO - 'sessionrestore_no_auto_restore', 22:01:23 INFO - 'tabpaint']}, 22:01:23 INFO - 'other-e10s': {'tests': ['a11yr', 22:01:23 INFO - 'ts_paint', 22:01:23 INFO - 'tpaint', 22:01:23 INFO - 'sessionrestore', 22:01:23 INFO - 'sessionrestore_no_auto_restore', 22:01:23 INFO - 'tabpaint']}, 22:01:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:01:23 INFO - 'ts_paint', 22:01:23 INFO - 'tpaint', 22:01:23 INFO - 'sessionrestore', 22:01:23 INFO - 'sessionrestore_no_auto_restore', 22:01:23 INFO - 'tabpaint']}, 22:01:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:01:23 INFO - 'ts_paint', 22:01:23 INFO - 'tpaint', 22:01:23 INFO - 'sessionrestore', 22:01:23 INFO - 'sessionrestore_no_auto_restore', 22:01:23 INFO - 'tabpaint']}, 22:01:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:01:23 INFO - 'tests': ['a11yr', 22:01:23 INFO - 'ts_paint', 22:01:23 INFO - 'tpaint', 22:01:23 INFO - 'sessionrestore', 22:01:23 INFO - 'sessionrestore_no_auto_restore', 22:01:23 INFO - 'tabpaint']}, 22:01:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:01:23 INFO - 'tests': ['a11yr', 22:01:23 INFO - 'ts_paint', 22:01:23 INFO - 'tpaint', 22:01:23 INFO - 'sessionrestore', 22:01:23 INFO - 'sessionrestore_no_auto_restore', 22:01:23 INFO - 'tabpaint']}, 22:01:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:01:23 INFO - 'tests': ['tsvgx', 22:01:23 INFO - 'tsvgr_opacity', 22:01:23 INFO - 'tart', 22:01:23 INFO - 'tscrollx', 22:01:23 INFO - 'cart']}, 22:01:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:01:23 INFO - 'tsvgr_opacity', 22:01:23 INFO - 'tart', 22:01:23 INFO - 'tscrollx', 22:01:23 INFO - 'cart']}, 22:01:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 22:01:23 INFO - 'talos_options': ['--disable-e10s'], 22:01:23 INFO - 'tests': ['tp5o']}, 22:01:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:01:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 22:01:23 INFO - 'talos_options': ['--disable-e10s', 22:01:23 INFO - '--xperf_path', 22:01:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:01:23 INFO - 'tests': ['tp5n']}, 22:01:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:01:23 INFO - 'talos_options': ['--xperf_path', 22:01:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:01:23 INFO - 'tests': ['tp5n']}}, 22:01:23 INFO - 'talos.zip': {'path': '', 22:01:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:01:23 INFO - Running post-action listener: _resource_record_post_action 22:01:23 INFO - [mozharness: 2016-11-23 06:01:23.527054Z] Finished populate-webroot step (success) 22:01:23 INFO - [mozharness: 2016-11-23 06:01:23.527175Z] Running create-virtualenv step. 22:01:23 INFO - Running pre-action listener: _resource_record_pre_action 22:01:23 INFO - Running main action method: create_virtualenv 22:01:23 INFO - Creating virtualenv /builds/slave/test/build/venv 22:01:23 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 22:01:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:01:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:01:23 INFO - Using real prefix '/tools/python27' 22:01:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:01:24 INFO - Installing distribute.............................................................................................................................................................................................done. 22:01:27 INFO - Installing pip.................done. 22:01:27 INFO - Return code: 0 22:01:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:01:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:01:27 INFO - Reading from file tmpfile_stdout 22:01:27 INFO - Output received: 22:01:27 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) 22:01:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:01:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107f963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefd25daa10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107dacd30>, '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 0x107c13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107da4b20>, '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.Rj74ifhysJ/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.VcR7roVL2d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 22:01:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:01:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:01:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rj74ifhysJ/Render', 22:01:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:01:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:01:27 INFO - 'HOME': '/Users/cltbld', 22:01:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:01:27 INFO - 'LOGNAME': 'cltbld', 22:01:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:27 INFO - 'MOZ_NO_REMOTE': '1', 22:01:27 INFO - 'NO_EM_RESTART': '1', 22:01:27 INFO - 'PAGER': '/bin/cat', 22:01:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:01:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:27 INFO - 'PWD': '/builds/slave/test', 22:01:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:01:27 INFO - 'SHELL': '/bin/bash', 22:01:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VcR7roVL2d/Listeners', 22:01:27 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 22:01:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:01:27 INFO - 'USER': 'cltbld', 22:01:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:01:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:01:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:27 INFO - 'XPC_FLAGS': '0x0', 22:01:27 INFO - 'XPC_SERVICE_NAME': '0', 22:01:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:01:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:27 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 22:01:27 INFO - Cleaning up... 22:01:27 INFO - Return code: 0 22:01:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:01:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107f963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefd25daa10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107dacd30>, '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 0x107c13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107da4b20>, '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.Rj74ifhysJ/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.VcR7roVL2d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 22:01:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:01:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:01:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rj74ifhysJ/Render', 22:01:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:01:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:01:27 INFO - 'HOME': '/Users/cltbld', 22:01:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:01:27 INFO - 'LOGNAME': 'cltbld', 22:01:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:27 INFO - 'MOZ_NO_REMOTE': '1', 22:01:27 INFO - 'NO_EM_RESTART': '1', 22:01:27 INFO - 'PAGER': '/bin/cat', 22:01:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:01:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:27 INFO - 'PWD': '/builds/slave/test', 22:01:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:01:27 INFO - 'SHELL': '/bin/bash', 22:01:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VcR7roVL2d/Listeners', 22:01:27 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 22:01:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:01:27 INFO - 'USER': 'cltbld', 22:01:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:01:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:01:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:27 INFO - 'XPC_FLAGS': '0x0', 22:01:27 INFO - 'XPC_SERVICE_NAME': '0', 22:01:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:01:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:28 INFO - Downloading/unpacking psutil>=3.1.1 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01: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 22:01:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:01:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:01:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:01:31 INFO - Installing collected packages: psutil 22:01:31 INFO - Running setup.py install for psutil 22:01:32 INFO - building 'psutil._psutil_osx' extension 22:01:32 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 22:01:32 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 22:01:32 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 22:01:32 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 22:01:32 INFO - building 'psutil._psutil_posix' extension 22:01:32 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 22:01:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:01:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:01:32 INFO - ^ 22:01:32 INFO - 1 warning generated. 22:01:32 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 22:01:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:01:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:01:33 INFO - Successfully installed psutil 22:01:33 INFO - Cleaning up... 22:01:33 INFO - Return code: 0 22:01:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:01:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:33 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 0x107f963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefd25daa10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107dacd30>, '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 0x107c13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107da4b20>, '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.Rj74ifhysJ/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.VcR7roVL2d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 22:01:33 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 22:01:33 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 22:01:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rj74ifhysJ/Render', 22:01:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:01:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:01:33 INFO - 'HOME': '/Users/cltbld', 22:01:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:01:33 INFO - 'LOGNAME': 'cltbld', 22:01:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:33 INFO - 'MOZ_NO_REMOTE': '1', 22:01:33 INFO - 'NO_EM_RESTART': '1', 22:01:33 INFO - 'PAGER': '/bin/cat', 22:01:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:01:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:33 INFO - 'PWD': '/builds/slave/test', 22:01:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:01:33 INFO - 'SHELL': '/bin/bash', 22:01:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VcR7roVL2d/Listeners', 22:01:33 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 22:01:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:01:33 INFO - 'USER': 'cltbld', 22:01:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:01:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:01:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:33 INFO - 'XPC_FLAGS': '0x0', 22:01:33 INFO - 'XPC_SERVICE_NAME': '0', 22:01:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:01:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:01:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:01:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:01:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:01:36 INFO - Installing collected packages: mozsystemmonitor 22:01:36 INFO - Running setup.py install for mozsystemmonitor 22:01:36 INFO - Successfully installed mozsystemmonitor 22:01:36 INFO - Cleaning up... 22:01:36 INFO - Return code: 0 22:01:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:01:36 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')]} 22:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:36 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')]} 22:01:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:36 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 0x107f963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefd25daa10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107dacd30>, '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 0x107c13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107da4b20>, '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.Rj74ifhysJ/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.VcR7roVL2d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 22:01:36 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 22:01:36 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 22:01:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rj74ifhysJ/Render', 22:01:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:01:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:01:36 INFO - 'HOME': '/Users/cltbld', 22:01:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:01:36 INFO - 'LOGNAME': 'cltbld', 22:01:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:36 INFO - 'MOZ_NO_REMOTE': '1', 22:01:36 INFO - 'NO_EM_RESTART': '1', 22:01:36 INFO - 'PAGER': '/bin/cat', 22:01:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:01:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:36 INFO - 'PWD': '/builds/slave/test', 22:01:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:01:36 INFO - 'SHELL': '/bin/bash', 22:01:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VcR7roVL2d/Listeners', 22:01:36 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 22:01:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:01:36 INFO - 'USER': 'cltbld', 22:01:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:01:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:01:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:36 INFO - 'XPC_FLAGS': '0x0', 22:01:36 INFO - 'XPC_SERVICE_NAME': '0', 22:01:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:01:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:36 INFO - Downloading/unpacking jsonschema==2.5.1 22:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:01:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:01:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:01:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:01:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:01:39 INFO - Downloading functools32-3.2.3-2.tar.gz 22:01:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:01:39 INFO - warning: no files found matching '*.txt' 22:01:39 INFO - no previously-included directories found matching 'build' 22:01:39 INFO - no previously-included directories found matching 'dist' 22:01:39 INFO - no previously-included directories found matching '.git*' 22:01:39 INFO - Installing collected packages: jsonschema, functools32 22:01:39 INFO - Running setup.py install for functools32 22:01:39 INFO - warning: no files found matching '*.txt' 22:01:39 INFO - no previously-included directories found matching 'build' 22:01:39 INFO - no previously-included directories found matching 'dist' 22:01:39 INFO - no previously-included directories found matching '.git*' 22:01:39 INFO - Successfully installed jsonschema functools32 22:01:39 INFO - Cleaning up... 22:01:39 INFO - Return code: 0 22:01:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:01: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')]} 22:01:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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')]} 22:01:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01: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', '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 0x107f963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefd25daa10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107dacd30>, '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 0x107c13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107da4b20>, '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.Rj74ifhysJ/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.VcR7roVL2d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 22:01: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:01: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 functools32==3.2.3-2 22:01:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rj74ifhysJ/Render', 22:01:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:01:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:01:39 INFO - 'HOME': '/Users/cltbld', 22:01:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:01:39 INFO - 'LOGNAME': 'cltbld', 22:01:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:39 INFO - 'MOZ_NO_REMOTE': '1', 22:01:39 INFO - 'NO_EM_RESTART': '1', 22:01:39 INFO - 'PAGER': '/bin/cat', 22:01:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:01:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:39 INFO - 'PWD': '/builds/slave/test', 22:01:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:01:39 INFO - 'SHELL': '/bin/bash', 22:01:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VcR7roVL2d/Listeners', 22:01:39 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 22:01:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:01:39 INFO - 'USER': 'cltbld', 22:01:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:01:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:01:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:39 INFO - 'XPC_FLAGS': '0x0', 22:01:39 INFO - 'XPC_SERVICE_NAME': '0', 22:01:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:01:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:01:40 INFO - Cleaning up... 22:01:40 INFO - Return code: 0 22:01:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:01:40 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')]} 22:01:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:40 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')]} 22:01:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:40 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 0x107f963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefd25daa10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107dacd30>, '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 0x107c13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107da4b20>, '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.Rj74ifhysJ/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.VcR7roVL2d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 22:01:40 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 22:01:40 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 22:01:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rj74ifhysJ/Render', 22:01:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:01:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:01:40 INFO - 'HOME': '/Users/cltbld', 22:01:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:01:40 INFO - 'LOGNAME': 'cltbld', 22:01:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:40 INFO - 'MOZ_NO_REMOTE': '1', 22:01:40 INFO - 'NO_EM_RESTART': '1', 22:01:40 INFO - 'PAGER': '/bin/cat', 22:01:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:01:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:40 INFO - 'PWD': '/builds/slave/test', 22:01:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:01:40 INFO - 'SHELL': '/bin/bash', 22:01:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VcR7roVL2d/Listeners', 22:01:40 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 22:01:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:01:40 INFO - 'USER': 'cltbld', 22:01:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:01:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:01:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:40 INFO - 'XPC_FLAGS': '0x0', 22:01:40 INFO - 'XPC_SERVICE_NAME': '0', 22:01:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:01:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:40 INFO - Downloading/unpacking blobuploader==1.2.4 22:01:40 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 22:01:40 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 22:01:40 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 22:01:40 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 22:01:40 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 22:01:40 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 22:01:42 INFO - Downloading blobuploader-1.2.4.tar.gz 22:01:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:01:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:01:43 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 22:01:43 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 22:01:43 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 22:01:43 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 22:01:43 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 22:01:43 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 22:01:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:01:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:01:43 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 22:01:43 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 22:01:43 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 22:01:43 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 22:01:43 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 22:01:43 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 22:01:44 INFO - Downloading docopt-0.6.1.tar.gz 22:01:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:01:44 INFO - Installing collected packages: blobuploader, requests, docopt 22:01:44 INFO - Running setup.py install for blobuploader 22:01:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:01:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:01:44 INFO - Running setup.py install for requests 22:01:44 INFO - Running setup.py install for docopt 22:01:45 INFO - Successfully installed blobuploader requests docopt 22:01:45 INFO - Cleaning up... 22:01:45 INFO - Return code: 0 22:01:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:01:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:45 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 0x107f963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefd25daa10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107dacd30>, '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 0x107c13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107da4b20>, '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.Rj74ifhysJ/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.VcR7roVL2d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 22:01:45 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 22:01:45 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 22:01:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rj74ifhysJ/Render', 22:01:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:01:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:01:45 INFO - 'HOME': '/Users/cltbld', 22:01:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:01:45 INFO - 'LOGNAME': 'cltbld', 22:01:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:45 INFO - 'MOZ_NO_REMOTE': '1', 22:01:45 INFO - 'NO_EM_RESTART': '1', 22:01:45 INFO - 'PAGER': '/bin/cat', 22:01:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:01:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:45 INFO - 'PWD': '/builds/slave/test', 22:01:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:01:45 INFO - 'SHELL': '/bin/bash', 22:01:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VcR7roVL2d/Listeners', 22:01:45 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 22:01:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:01:45 INFO - 'USER': 'cltbld', 22:01:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:01:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:01:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:45 INFO - 'XPC_FLAGS': '0x0', 22:01:45 INFO - 'XPC_SERVICE_NAME': '0', 22:01:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:01:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:01:45 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-F785sI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:01:45 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-JwhtrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:01:45 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-HHpkH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:01:45 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-yUUX4O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:01:45 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-FCCUUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:01:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:01:45 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-FJ_SO3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:01:46 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-9zIV6a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:01:46 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-fVZs3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:01:46 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-fJc0pp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:01:46 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-vpt0CS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:01:46 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-CG8zTS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:01:46 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-c4rv74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:01:46 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-K86YOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:01:46 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-t7o2aG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:46 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-yConon-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:01:46 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-Ua8H2m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:01:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:01:47 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-wvgc9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:01:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:01:47 INFO - Running setup.py install for manifestparser 22:01:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:01:47 INFO - Running setup.py install for mozcrash 22:01:47 INFO - Running setup.py install for mozdebug 22:01:47 INFO - Running setup.py install for mozdevice 22:01:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:01:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:01:47 INFO - Running setup.py install for mozfile 22:01:47 INFO - Running setup.py install for mozhttpd 22:01:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:01:48 INFO - Running setup.py install for mozinfo 22:01:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:01:48 INFO - Running setup.py install for mozInstall 22:01:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:01:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:01:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:01:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:01:48 INFO - Running setup.py install for mozleak 22:01:48 INFO - Running setup.py install for mozlog 22:01:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:01:48 INFO - Running setup.py install for moznetwork 22:01:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:01:48 INFO - Running setup.py install for mozprocess 22:01:48 INFO - Running setup.py install for mozprofile 22:01:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:01:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:01:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:01:49 INFO - Running setup.py install for mozrunner 22:01:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:01:49 INFO - Running setup.py install for mozscreenshot 22:01:49 INFO - Running setup.py install for moztest 22:01:49 INFO - Running setup.py install for mozversion 22:01:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:01:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:01:49 INFO - Cleaning up... 22:01:49 INFO - Return code: 0 22:01:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:01:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:01:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:49 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 0x107f963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefd25daa10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107dacd30>, '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 0x107c13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107da4b20>, '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.Rj74ifhysJ/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.VcR7roVL2d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 22:01:49 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 22:01:49 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 22:01:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rj74ifhysJ/Render', 22:01:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:01:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:01:49 INFO - 'HOME': '/Users/cltbld', 22:01:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:01:49 INFO - 'LOGNAME': 'cltbld', 22:01:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:49 INFO - 'MOZ_NO_REMOTE': '1', 22:01:49 INFO - 'NO_EM_RESTART': '1', 22:01:49 INFO - 'PAGER': '/bin/cat', 22:01:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:01:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:49 INFO - 'PWD': '/builds/slave/test', 22:01:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:01:49 INFO - 'SHELL': '/bin/bash', 22:01:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VcR7roVL2d/Listeners', 22:01:49 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 22:01:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:01:49 INFO - 'USER': 'cltbld', 22:01:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:01:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:01:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:49 INFO - 'XPC_FLAGS': '0x0', 22:01:49 INFO - 'XPC_SERVICE_NAME': '0', 22:01:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:01:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:01:50 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-DEIdI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:01:50 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)) 22:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:01:50 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-lyEdSU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:01:50 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)) 22:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:01:50 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-AG6RBo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:01:50 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)) 22:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:01:50 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-Ou9jJz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:01:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 22:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:01:50 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-QiIH5e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:01:50 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)) 22:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:01:50 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-54GT6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:01:50 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)) 22:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:01:50 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-7Fy90s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:01:50 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)) 22:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:01:50 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-HA_dib-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:01:50 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)) 22:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:01:50 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-A0eXNT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:01:50 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)) 22:01:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:01:50 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-M0Knpo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:01:51 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)) 22:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:01:51 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-73_apU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:01:51 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)) 22:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:01:51 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-mLwXKe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:01:51 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)) 22:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:01:51 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-DpjYam-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:01:51 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)) 22:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:01:51 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-NE5HnN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:01:51 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)) 22:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:51 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-FdhpW2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:01:51 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)) 22:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:01:51 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-p8Xz8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:01:51 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)) 22:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:01:51 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-e91nIw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:01:51 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)) 22:01:51 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)) 22:01:51 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)) 22:01:51 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)) 22:01:51 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:01:51 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:01:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:01:51 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 22:01:51 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 22:01:51 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 22:01:51 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 22:01:51 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 22:01:51 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 22:01:54 INFO - Downloading blessings-1.6.tar.gz 22:01:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:01:54 INFO - Installing collected packages: blessings 22:01:54 INFO - Running setup.py install for blessings 22:01:54 INFO - Successfully installed blessings 22:01:54 INFO - Cleaning up... 22:01:54 INFO - Return code: 0 22:01:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:01:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:01:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:01:54 INFO - Reading from file tmpfile_stdout 22:01:55 INFO - Current package versions: 22:01:55 INFO - blessings == 1.6 22:01:55 INFO - blobuploader == 1.2.4 22:01:55 INFO - docopt == 0.6.1 22:01:55 INFO - functools32 == 3.2.3-2 22:01:55 INFO - jsonschema == 2.5.1 22:01:55 INFO - manifestparser == 1.1 22:01:55 INFO - mozInstall == 1.12 22:01:55 INFO - mozcrash == 1.0 22:01:55 INFO - mozdebug == 0.1 22:01:55 INFO - mozdevice == 0.49 22:01:55 INFO - mozfile == 1.2 22:01:55 INFO - mozhttpd == 0.7 22:01:55 INFO - mozinfo == 0.9 22:01:55 INFO - mozleak == 0.1 22:01:55 INFO - mozlog == 3.4 22:01:55 INFO - moznetwork == 0.27 22:01:55 INFO - mozprocess == 0.23 22:01:55 INFO - mozprofile == 0.28 22:01:55 INFO - mozrunner == 6.13 22:01:55 INFO - mozscreenshot == 0.1 22:01:55 INFO - mozsystemmonitor == 0.3 22:01:55 INFO - moztest == 0.7 22:01:55 INFO - mozversion == 1.4 22:01:55 INFO - psutil == 3.1.1 22:01:55 INFO - requests == 1.2.3 22:01:55 INFO - wsgiref == 0.1.2 22:01:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:01:55 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')]} 22:01:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:55 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')]} 22:01:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:55 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 0x107f963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefd25daa10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107dacd30>, '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 0x107c13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107da4b20>, '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.Rj74ifhysJ/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.VcR7roVL2d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 22:01:55 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 22:01:55 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 22:01:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rj74ifhysJ/Render', 22:01:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:01:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:01:55 INFO - 'HOME': '/Users/cltbld', 22:01:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:01:55 INFO - 'LOGNAME': 'cltbld', 22:01:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:55 INFO - 'MOZ_NO_REMOTE': '1', 22:01:55 INFO - 'NO_EM_RESTART': '1', 22:01:55 INFO - 'PAGER': '/bin/cat', 22:01:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:01:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:55 INFO - 'PWD': '/builds/slave/test', 22:01:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:01:55 INFO - 'SHELL': '/bin/bash', 22:01:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VcR7roVL2d/Listeners', 22:01:55 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 22:01:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:01:55 INFO - 'USER': 'cltbld', 22:01:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:01:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:01:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:55 INFO - 'XPC_FLAGS': '0x0', 22:01:55 INFO - 'XPC_SERVICE_NAME': '0', 22:01:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:01:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:55 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)) 22:01:55 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)) 22:01:55 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)) 22:01:55 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)) 22:01:55 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)) 22:01:55 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)) 22:01:55 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)) 22:01:55 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)) 22:01:55 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)) 22:01:55 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)) 22:01:55 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)) 22:01:55 INFO - Cleaning up... 22:01:55 INFO - Return code: 0 22:01:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:01:55 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')]} 22:01:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:01:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:55 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')]} 22:01:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:01:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:01:55 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 0x107f963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107f0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefd25daa10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107dacd30>, '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 0x107c13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107da4b20>, '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.Rj74ifhysJ/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.VcR7roVL2d/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 22:01:55 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 22:01:55 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 22:01:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rj74ifhysJ/Render', 22:01:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:01:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:01:55 INFO - 'HOME': '/Users/cltbld', 22:01:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:01:55 INFO - 'LOGNAME': 'cltbld', 22:01:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:01:55 INFO - 'MOZ_NO_REMOTE': '1', 22:01:55 INFO - 'NO_EM_RESTART': '1', 22:01:55 INFO - 'PAGER': '/bin/cat', 22:01:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:01:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:01:55 INFO - 'PWD': '/builds/slave/test', 22:01:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:01:55 INFO - 'SHELL': '/bin/bash', 22:01:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VcR7roVL2d/Listeners', 22:01:55 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 22:01:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:01:55 INFO - 'USER': 'cltbld', 22:01:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:01:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:01:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:01:55 INFO - 'XPC_FLAGS': '0x0', 22:01:55 INFO - 'XPC_SERVICE_NAME': '0', 22:01:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:01:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:01:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:01:55 INFO - Cleaning up... 22:01:55 INFO - Return code: 0 22:01:55 INFO - Running post-action listener: _resource_record_post_action 22:01:55 INFO - Running post-action listener: _start_resource_monitoring 22:01:55 INFO - Starting resource monitoring. 22:01:55 INFO - [mozharness: 2016-11-23 06:01:55.571813Z] Finished create-virtualenv step (success) 22:01:55 INFO - [mozharness: 2016-11-23 06:01:55.572811Z] Running install step. 22:01:55 INFO - Running pre-action listener: _resource_record_pre_action 22:01:55 INFO - Running main action method: install 22:01:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:01:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:01:55 INFO - Reading from file tmpfile_stdout 22:01:55 INFO - Detecting whether we're running mozinstall >=1.0... 22:01:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:01:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:01:55 INFO - Reading from file tmpfile_stdout 22:01:55 INFO - Output received: 22:01:55 INFO - Usage: mozinstall [options] installer 22:01:55 INFO - Options: 22:01:55 INFO - -h, --help show this help message and exit 22:01:55 INFO - -d DEST, --destination=DEST 22:01:55 INFO - Directory to install application into. [default: 22:01:55 INFO - "/builds/slave/test"] 22:01:55 INFO - --app=APP Application being installed. [default: firefox] 22:01:55 INFO - mkdir: /builds/slave/test/build/application 22:01:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:01:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 22:02:28 INFO - Reading from file tmpfile_stdout 22:02:28 INFO - Output received: 22:02:28 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:02:28 INFO - Running post-action listener: _resource_record_post_action 22:02:28 INFO - [mozharness: 2016-11-23 06:02:28.236791Z] Finished install step (success) 22:02:28 INFO - [mozharness: 2016-11-23 06:02:28.236927Z] Running run-tests step. 22:02:28 INFO - Running pre-action listener: _resource_record_pre_action 22:02:28 INFO - Running main action method: run_tests 22:02:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:02:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:02:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:02:28 INFO - 2016-11-22 22:02:28.251 screenresolution[1630:8090] starting screenresolution argv=screenresolution get 22:02:28 INFO - 2016-11-22 22:02:28.254 screenresolution[1630:8090] Display 0: 1600x1200x32@60 22:02:28 INFO - 2016-11-22 22:02:28.259 screenresolution[1631:8092] starting screenresolution argv=screenresolution list 22:02:28 INFO - Available Modes on Display 0 22:02:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:02:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:02:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:02:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:02:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:02:28 INFO - Intel Iris: 22:02:28 INFO - Chipset Model: Intel Iris 22:02:28 INFO - Type: GPU 22:02:28 INFO - Bus: Built-In 22:02:28 INFO - VRAM (Dynamic, Max): 1536 MB 22:02:28 INFO - Vendor: Intel (0x8086) 22:02:28 INFO - Device ID: 0x0a2e 22:02:28 INFO - Revision ID: 0x0009 22:02:28 INFO - Displays: 22:02:28 INFO - Display: 22:02:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:02:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:02:28 INFO - Display Serial Number: 1600x1200 60 22:02:28 INFO - Main Display: Yes 22:02:28 INFO - Mirror: Off 22:02:28 INFO - Online: Yes 22:02:28 INFO - Rotation: Supported 22:02:28 INFO - Return code: 0 22:02:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:02:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:02:28 INFO - Python 2.7.3 22:02:28 INFO - Return code: 0 22:02:28 INFO - grabbing minidump binary from tooltool 22:02:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:02:28 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 0x107dacd30>, '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 0x107c13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107da4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:02:28 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 22:02:28 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 22:02:28 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 22:02:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:02:28 INFO - Return code: 0 22:02:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:02:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:02:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:02:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:02:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:02:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0152', '--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 22:02:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0152 --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 22:02:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rj74ifhysJ/Render', 22:02:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:02:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:02:28 INFO - 'HOME': '/Users/cltbld', 22:02:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:02:28 INFO - 'LOGNAME': 'cltbld', 22:02:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:02:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:02:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:02:28 INFO - 'MOZ_NO_REMOTE': '1', 22:02:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:02:28 INFO - 'NO_EM_RESTART': '1', 22:02:28 INFO - 'PAGER': '/bin/cat', 22:02:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:02:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:02:28 INFO - 'PWD': '/builds/slave/test', 22:02:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:02:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:02:28 INFO - 'SHELL': '/bin/bash', 22:02:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VcR7roVL2d/Listeners', 22:02:28 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 22:02:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:02:28 INFO - 'USER': 'cltbld', 22:02:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:02:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:02:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:02:28 INFO - 'XPC_FLAGS': '0x0', 22:02:28 INFO - 'XPC_SERVICE_NAME': '0', 22:02:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:02:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0152', '--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 22:02:28 INFO - mozversion application_buildid: 20161122200337 22:02:28 INFO - mozversion application_changeset: 0ddfec7126ec503b54df9c4b7c3b988906f6c882 22:02:28 INFO - mozversion application_display_name: Nightly 22:02:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:02:28 INFO - mozversion application_name: Firefox 22:02:28 INFO - mozversion application_remotingname: firefox 22:02:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:02:28 INFO - mozversion application_vendor: Mozilla 22:02:28 INFO - mozversion application_version: 53.0a1 22:02:28 INFO - mozversion platform_buildid: 20161122200337 22:02:28 INFO - mozversion platform_changeset: 0ddfec7126ec503b54df9c4b7c3b988906f6c882 22:02:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:02:28 INFO - mozversion platform_version: 53.0a1 22:02:28 INFO - using testdate: 1479880948 22:02:28 INFO - actual date: 1479880948 22:02:28 INFO - starting webserver on 'localhost:49250' 22:02:28 INFO - SUITE-START | Running 2 tests 22:02:28 INFO - TEST-START | dromaeo_css 22:02:28 INFO - operating with platform_type : mac_ 22:02:28 INFO - Initialising browser for dromaeo_css test... 22:02:28 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/tmplfPMSQ/profile http://localhost:49250/getInfo.html) 22:02:31 INFO - PROCESS | 1641 | __metrics Screen width/height:1600/1200 22:02:31 INFO - PROCESS | 1641 | colorDepth:24 22:02:31 INFO - PROCESS | 1641 | Browser inner width/height: 1024/689 22:02:31 INFO - PROCESS | 1641 | __metrics 22:02:31 INFO - PROCESS | 1641 | 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" 22:02:31 INFO - PROCESS | 1641 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:02:32 INFO - TEST-INFO | 1641: exit 0 22:02:32 INFO - Browser initialized. 22:02:32 INFO - Running cycle 1/1 for dromaeo_css test... 22:02:32 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/tmplfPMSQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:02:45 INFO - PROCESS | 1644 | RSS: Main: 243924992 22:02:45 INFO - PROCESS | 1644 | 22:03:36 INFO - PROCESS | 1644 | 1479881016401 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] 22:05:50 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49250/tests/dromaeo/cssquery-ext.html) 22:05:52 INFO - PROCESS | 1644 | RSS: Main: 406962176 22:05:52 INFO - PROCESS | 1644 | 22:08:55 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-ext.html (next: http://localhost:49250/tests/dromaeo/cssquery-jquery.html) 22:08:56 INFO - PROCESS | 1644 | RSS: Main: 374329344 22:08:56 INFO - PROCESS | 1644 | 22:11:58 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49250/tests/dromaeo/cssquery-mootools.html) 22:11:59 INFO - PROCESS | 1644 | RSS: Main: 345743360 22:11:59 INFO - PROCESS | 1644 | 22:15:01 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49250/tests/dromaeo/cssquery-prototype.html) 22:15:02 INFO - PROCESS | 1644 | RSS: Main: 345948160 22:15:02 INFO - PROCESS | 1644 | 22:18:06 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49250/tests/dromaeo/cssquery-yui.html) 22:18:06 INFO - PROCESS | 1644 | RSS: Main: 335650816 22:18:06 INFO - PROCESS | 1644 | 22:21:09 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-yui.html (next: http://localhost:49250/tests/dromaeo/cssquery-dojo.html) 22:21:10 INFO - PROCESS | 1644 | RSS: Main: 466427904 22:21:10 INFO - PROCESS | 1644 | 22:21:10 INFO - PROCESS | 1644 | __start_tp_report 22:21:10 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 22:21:10 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 22:21:10 INFO - PROCESS | 1644 | |i|pagename|runs| 22:21:10 INFO - PROCESS | 1644 | |0;dojo.html;3272.689094536019;3275.9017229483115;3293.814226878325;3326.334733053387;3351.32973405319;5420.186971954209;5465.016297066526;5491.725413729318;5494.478024587663;5519.862003449915;5474.7810087596445;5487.42382049885;5527.50252477277;5557.138643510262;5579.46995035473;4922.01559688062;5002.099622068026;5039.672421292605;5085.491450854904;5121.3342265505435;5101.770420331094;5167.793288268484;5172.6379153459275;5192.636515443921;5215.504527069941;4995.625328100371;5070.645054846125;5108.386993560778;5152.072626927152;5214.973925130369;5031.77357018935;5054.797808087672;5097.133487307166;5108.974455127701;5117;5047.47001564834;5074.06129865976;5078.61910356721;5088.847334579968;5123.923141152885;5512.39363669996;5534.916976245359;5542.140968149903;5550.639208451437;5575.832725018256;5795.159701843209;5830.854228644275;5852.941470585325;5923.318818335903;5989.700514974234;6096.817095487098;6114.145302971524;6114.549543274474;6124.391422862037;6130.531944610715;5581.888362232773;5589.8602534936135;5594.356648985337;5606.803761868315;5606.915896261559;6127.111568822585;6151.2618485781995;6151.9234134027265;6233.065040243909;6307.432331090224;5807.8548036299435;5862.208601838697;5869.090291004815;5871.823845284648;5873.471387575053;6021.337652858182;6022;6022.126791615191;6022.277326720821;6030.33666296707;5863.824085277449;5924.289085309704;5930.525557955353;5938.465538101505;5954.523638108941;5950.672713000827;5956.225690660273;5959.106134079922;5983.521318294526;5992.910106348409;4759.452662943806;4784.856454306377;4792.808287668524;4795.064962332382;4795.160846851857;4251.383549385322;4263.104748002892;4263.616274535307;4266.893327666833;4364.519902810688;5695.430456954271;5756.395578464285;5757.193992841006;5767.423257674199;5768.884622307531;6020.759169633166;6084.421979911901;6118.1434599156155;6134.386561343919;6138.294096178996;5591.07747221712;5654.886902261932;5663.008973429633;5677.971610141923;5704.34400043992;5260.973695131576;5270.578353731692;5286.577073834161;5288.762005709676;5297;6296.685165741603;6306.211723534559;6311.305756366888;6313.05304204373;6318.147050148263;5946.970265148647;6111.480524155497;6117.1130186123655;6119.938800611937;6130.141780150693;6146.862830376394;6232.096346029891;6235.46998505114;6237.812865614039;6255.687215639109;5905.9959806832085;5914.172015917861;5924.377940316204;5924.822255332347;5930.229070220844;5992.730327135202;6016.187814644967;6037.63774173551;6047.697615119315;6052.03167493199;5077.593792496517;5763.193152958673;5798.1302804579655;5801.767929282782;5823.912641310299;4948.233023881304;5765.682887441231;5807.738651760766;5872.03111486617;5915.792947246826;5703.971480142573;5711.143328500758;5712.714364281853;5716.828495145152;5727.284089488814;5851.239338885917;5869.735861886311;5889.234399528034;5894.882102357843;5894.941050589611;5580.5535557156145;5606.831795046155;5628.212050312877;5640.520555752642;5690.914636280377 22:21:10 INFO - PROCESS | 1644 | |1;ext.html;454.2663598288763;516.0994065355954;525.1518797142614;527.4514504914891;547.73161151036;29492;31000;32765.67234327656;38170.66402675907;38518;40142;40586;40652.79673601635;40674.59325406745;40860.18279634413;9182.954085229565;9376.437413755197;9381.15569598736;9382.718518444424;9400.952995235015;8452;8654.697085602005;10940;11176;11207.719807004809;11151.776964460705;11196.720081997975;11197.104231661488;11209.66371008867;11217.775644487105;8450.366222533274;11166.385848778315;11230.887691123107;11232.663020109328;11233.550657973672;8363.20549547792;10701.892981070168;11001.339919604849;11033.337999720043;11200.551977920873;38095.619043809485;38261.234775304336;38428.42357364642;38435;38459;3276.344731053775;3293.917652058694;3306.2560923791953;3357.84889679965;3375.6793104655017;28026.439471210462;29281.267968300748;29283.85358073217;29318.41363172746;29330;3131.561581378609;3173;3208.0536241808722;3233.7736358454918;3296.571445712066;325543.37228313944;447816.76091619657;469952;471057;471644.64177679236;52876;52973;54635;54984.45015549874;55081.449185508434;56620.15069773957;56849.43150568524;56953;56955;57064.42935570674;15028.17345046033;15178.86158538114;15209.771853422208;15212.1633310169;15227.61930951713;10751.387291906276;10956.506957186946;11707.063434925185;11740.941295293469;11745.588904388476;11746.29522228669;12972.67568310781;14039.929800350932;14428.350724217435;14722.705545889237;6633.877159308975;6912.308769123148;6914.204866440323;6922.653867306614;7017.578945263199;790.6758229126145;858.6093327535956;987.7086259553449;1006.6527048168371;1026.5303274930686;27532;28145.155645330262;28694.569581456293;28960.42079158405;29366.392561673452;844.7803571071443;1029.3360782295379;1086.6359769477156;1098.319042193845;1214.3017764785284;14006.509772157926;14159.292035398188;14457.710845783236;14492.420303187966;14901.180435075958;634757;640863;642124;642337;644369;54725;54999.72500137554;55020.44979550153;55021.724891375285;55033;1212.0243204220687;1236.4003458322707;1316.967075823093;1336.2984433172662;1362.8228330316995;28886.566701499072;29648.40703185881;29703;30314.39371212519;30400.39199215959;43146;43582.56417435785;43596.56403436052;43630.78184609057;43637.78181109074;422578;424461;425033;425269;439847;24379.9029043697;24666.01335946542;24685.87657061703;24734.505309894063;24769.009239630213;42719.786401067795;42853;43313.56686433221;43433.26266949287;43661.56338436575;36034.819825900704;36282.274354512236;36311.63688363188;37258.25483490261;37580.2483950314;34280.14299642429;35597;35733.1066723334;36797.26405471929;36801.26397472089;35549.82225088858;35612.465813012306;35790.74732384354;36169.91490255296;36250.63749362472;30507.542386864658;33464.1633959153;33481.99554013384;33498;33528.664713352555;24024.87987560051;24326.756732433158;24426;24444.14445494399;25078.49843003166 22:21:10 INFO - PROCESS | 1644 | |2;jquery.html;347.71834813800837;364.8139448881071;370.1689706608664;379.45358683495766;400.31146428143603;14414;14577.125372477645;15375.92312038441;18812.15345309461;20016.499587510283;18709.719354209697;19284;19547.41357759266;19856.20575176991;20072.69890951637;5432.809851655193;5699.031164702011;5702.885942281152;5705.115707065412;5707.971460142714;4582.450105987286;5317.2821669074565;5590.804321848736;5637.126245431965;5641.746121424525;5611.961787069385;5617.0731829248125;5617.775288988436;5621.156826476021;5625.493705566499;5292.311999440068;5476.890462190734;5535.308086489189;5539.9169012464845;5540.889182216373;4852.684575502581;4855.83004594841;4857.53853383928;4859.781309841065;4861.0277944411255;22981.77018229813;23538.411539711473;24073;24845.751542484526;25091;2177.575372802304;2196.1325276515686;2414.746451622572;2551.6045013023013;2683.047518131055;9382;9724.465154416504;9989.700308990741;10242;10307.020833020926;1759.9560010999699;1784.5449410400395;1806.5303021214447;1809.185866360143;1833.4591295567707;98159;101275;101403;101695;108818;9032.593533291018;9537.284703647254;10882.727931801768;10894;11082.390468524147;14143.50497732595;14147.717045659028;14154;14165.85834141666;14167.574972750834;6586.967065164644;8240.175982401832;8259.669613215525;8369.455985360932;8394.202550757669;8026.558539280279;8076.71731489395;8190.8771368429525;8197.590120493951;8240.546769927592;4299.355096735515;4403.141387429485;4404.757738324359;4497.865064048084;4503.099380123988;1784.6787578236042;1858.1545396844404;1858.1731129647178;1864.1704441523664;1865.580244444989;791.3589992106412;800.8438354520929;805.1827395193883;864.6411739128191;899.7660608241904;17022.14889255557;18277.58573524668;19147.851128932307;20013.699794503093;20308.289209877585;892.161368313783;892.4199270474114;905.8958219804807;940.2337095267335;963.5952899781968;9956;10420;10495.527701253463;10504.579816807394;10537.57849686004;539460;540465;552975;569691;575081;61040;100711.98576028438;126163.36918315533;128007;134490;864.4769914201818;901.2519608724742;958.7746879483316;962.0523784072823;967.2455484721928;10541.683749487527;17188.14059297055;17884;17975.640487190445;18504;44908.77545612382;48402.75798620984;50638.49361506338;53277.73361133169;54163.72918135542;44852;75864.4827103466;76647;76694.616526917;79212;10239.283250172417;11778.469968851594;12062.939685301517;12115.151939364512;12254.264744115382;11101.611443599435;12815.16701414405;13533.526326578523;13596.456141754219;13794.93102534481;10907.945460272647;10964.232503724663;11343.886561134283;11418.371989540323;11813.114016448973;11219.214654974074;11260.493277802683;11368.602098926498;11739.823902641638;11807.586734464252;11289.20975531705;11582.015528680156;11747.063234191452;11970.22193557416;12064.879351206375;11711.88288117108;11805.468753905923;12039.75920481603;12145.939270303592;12207.511699531919;11546.074334726234;11587.768244635228;11742.823857642314;11777.823332649845;11823.704407389883 22:21:10 INFO - PROCESS | 1644 | |3;mootools.html;1343.5834891183733;1453.7092581483698;1614.2251719174797;1795.3267524678245;1860.23730270589;7660.00419945407;8047.155048719881;8050.758477245678;8056.7582972510845;8060;7347.595882226476;7622;7635.427342949273;7840.8039799005055;8099.5950202489785;2090.822280106193;2096.8637038592483;2282.486440550871;2284.9314520564326;2290.3701482092392;2281.1217681192693;2296.609576372021;2309.5727290451237;2317.2353123469215;2317.2353123469297;1894.8768330058572;2273.1362082408664;2284.554511870186;2286.622707253301;2294.598445272079;2219.411855858199;2280.8061314788265;2283.2008796921195;2283.9200627978107;2290.8625482471107;2273.204378467523;2302.286291249701;2302.6315789473606;2304.50453152573;2309.099451214028;8044.074931382907;8213.630386632616;8220.63007164679;8222.259996600274;8235.588220588947;4966.826161084345;5132.692038477666;5144.6913185209005;5153.3558305211845;5155.7679904404395;3257.6090869095624;3356.5636467259346;3452.188735647121;3534.3991521441417;3548.556430446194;1054.9419781911993;1078.0620859851979;1081.8431327457474;1091.6888686724326;1098.8132017557034;10634.734131646694;10640;10781.353118812896;10785;10787.946060269727;4152.771597562102;4268.893277668083;4471.709338892962;4593.173228818778;4607.193741095295;8439.282660973764;8963.058878817761;8964.238039766562;9176.49529275883;9224.8616270756;5080.161773307363;5085.567726743194;5090.669106508065;5091.694498330112;5091.949080509147;3828.2343531293855;3978.2640211560374;4196.643285320721;4679.321498382784;4679.368285281512;3624.0215141911535;3624.7643903146213;3721.5534135903863;3725.5343082114737;3731.626837316301;1208.7522057978094;1211.8485189351331;1216.5620376664394;1227.1716591300835;1228.5577192210797;828.7886588919787;847.8050048488759;859.5143743784741;881.2421317666801;885.9291256699448;5344.412114667383;5483.506484416422;5537.889242215214;5548.4174161713245;5554.528050066736;417.36560428149045;441.6577152706678;442.8671398580413;443.58303194996597;446.3661600527259;3185.6336521300345;3617.294627547603;3624.6012938576737;3760;3791.7156213284115;9443.386179898285;9681.273904457054;9693.418394896187;9959;9974.102330790263;9911.45486998222;9913.45475998827;9921.40471571708;9927.156191723785;9936;7349.70601175947;7558.811029724191;7584.4311676624475;7707.383409327241;7718.729844455417;5359.598030147832;5421.132618780976;5435.918461223006;5503.862403439946;5561.193626924154;7677.462577619736;9260.30547708938;9270.351075424656;9291.72124836256;9301.813963720822;8845.778855528662;9233.815323693623;9267.490288033952;9279.164875161268;9364.625414983597;6451.903221451776;6650.467962562887;6675.599464032173;6943.444524438134;7118.928810711826;6630.270670226175;7463.253674632493;7607.581583012988;7750.728724494616;7803.375729941705;5947.226860508106;6841.965790171017;7068.434525237866;7079.893801592877;7111.466640001917;6876.896846547205;6885.862282754417;6895.6552172390175;6922.58464492132;7018.227994920452;6480.189976252968;6480.513961452815;6485.092087107903;6562.934370656232;6573.276939536551;4671.532846715301;4732.6687131900435;4757.7621118943225;4761.452432970253;4912.459629440687;4742.596879265301;4841.491643377394;4863.537963893425;4869;4871.90256194867 22:21:10 INFO - PROCESS | 1644 | |4;prototype.html;256.3770038805702;300.9082229919875;306.6718611086137;314.5501932236902;316.5726269536125;4627.8380256691025;4876.487968763282;5155.458676838929;5224.686518808874;5234.5027222413855;4254.978725106378;4350.216960947037;4845.49789565235;4879.585235255007;5003.749812509369;3412.2322477442626;3425.845836937344;3528.9593415539193;3744.494493243418;3942.8225729842084;3285.934281314372;3429.4567444649883;3521.3485505181498;3755.9061023474496;3951.4467974483596;3718.628137186287;3797.525309336333;3854.8458061677497;3916.725829191958;3925.2934471795065;3852.57621661617;3874.0314921269683;3891.9416208756893;3895.9805200973956;3905.2775236581324;3155.1165673611426;3190.7287880530184;3381.7463690223085;3389.67798059184;3421.161815355254;4709.882252943669;4934.728589927553;4942.555170034678;4956.3804524434445;4979.588981547651;4112.773797441139;4142.150859073913;4378.452693413324;4384.780760961939;4401.6478681705385;5357.43746906577;5357.866053348658;5357.919631205535;5390.056740070472;5472.699001554913;3635.509206257148;3655.524781778352;3663.4504824276305;3669.477166975702;3671.724620653435;4437.600615944581;4706;5655.632383894993;5655.773769049274;5772.942270577324;3710.406334986389;3713.294474049922;3730.421784623388;3730.4404339349317;3732.384156614128;3203.8878639247514;3753.530808648919;3761.8495260189284;3773.4528493368307;3779.3952967525065;3327.750418718606;4248.511421186541;4259.787010649455;4394.033312671208;4403.581659742384;4919.704817710949;5402.91895621566;5418.810341638024;5429.746655138073;5449.3460784706085;1087.6954452753241;1104.8399180860065;1211.5759484180642;1213.8422005139453;1219.621917205669;760.3764912771585;791.8794905209064;808.2604416958535;812.9430939834273;833.204289903141;4014.9397759033636;4511.233090374644;4852;4854.466008739036;4885.093369445174;4678.298255261738;5128.487151284841;5163.870903227375;5189.844304670791;5217.069684216864;4808.917993451445;4859.441164266084;4865.343178670834;4871.29366241893;5274.683518988873;2472.443700167448;2510.79913606911;2577.9613305800426;2759.7719015038115;2787.8606069696434;4855.8057677692495;4963.429205641398;5442.346918369821;5565.471280228453;5603.431039309;5134.942082049179;5330.227117068003;5363.946360536423;5449.782008719686;5479.287752562427;5394.865128371822;5589.304983813616;5610.242617246701;5613.578981576398;5624.268845050117;4412.448443944507;5204.9739751301;5225.961874868728;5262.97368513155;5294.788208471772;4499.482559505568;4868.513148685103;5117.232415137759;5228.241904923841;5259.552938000313;5581.246531718166;5602.674889920638;5632.887342253214;5651.265335506358;5655.632383895034;3073.3085055862252;3092.505199168122;3480.2865412590863;3541.1855273286774;3554.2713743683;4480.843170489017;5041.395032596112;5257.79580095491;5271.551918087006;5273.156294992783;5101.795928162832;5106.157484015096;5119.465864397147;5121.743912804271;5136.743162841918;4838.435798831231;5071.77177027042;5097.668651537638;5164.457731938092;5175.663581867167;4462.709923854939;4627.30590411441;4849.466558678472;4973.7369496879555;5016.2224855147515;4478.507364189872;4637.814487420465;4834.564889159992;5099.929014906911;5264.921026184534;4555.794989225559;4800.95199048019;5042.327566389819;5272.051030814337;5349.812756553502 22:21:10 INFO - PROCESS | 1644 | |5;yui.html;597.0536699331559;733.9633018349081;780.5160800303812;780.9726659566917;803.3894240377314;13653.65865853353;14419.78370324446;14427.567172984815;14434.711305773879;14465.493707720232;12217.20588161771;12275.63173104806;12280.754384912296;12287.26276423413;12309.938450307736;665.5507532415636;686.1800148822159;691.2292793535197;798.7523867601062;819.8442295963778;820.1757233979841;836.6569706420369;840.5251033166272;840.8990921089478;842.7219017724167;883.2977782662798;884.593087179898;886.441541828647;887.9556022198897;888.205056474455;691.8581690753383;715.1489727224638;847.7922908887363;876.2332958661171;876.6537217798998;660.5244224158646;667.3293340193123;667.7028722218614;667.9365460281266;668.1314291421133;13743.488216296199;13791.51729689456;13809.171449713049;13811.033227674066;13811.171329720148;12841.67895802603;12910.87089129106;12984.675383115404;13107.541236056692;13143.737125257441;11999.760004799942;12075;12151.149419540636;12153.453094610764;12231.143819932608;4276.828926842923;4306.935395969063;4323.898194300982;4329.7618630975285;4426.424564806554;90077;90913.63629545562;91302.5434872828;92469;92803;10344.431056291825;10346.430946298025;10452;10652.09457196147;10666.573337066431;19604;19713.901430493042;19844.305449309206;19915.302964396178;19922.701159482618;6530.673466326664;7367.447441441914;7461.962690186623;7471.066116735408;7559.130699969464;4109.506859176918;4144.70987030911;4165.91668166635;4183.49798024239;4193.895152621148;3154.1010811918495;3250.7886987345746;3252.642209356969;3537.398642230827;3731.36566783642;673.8618583190434;675.5102550650837;683.4566519616917;691.989620155698;692.712524302419;621.3413781391739;647.6793986976477;655.4854439265154;670.577536152221;681.5978572642066;8678.392512524062;8936.106389361012;8970.86543701832;8975.730728078168;9036.502992335354;635.755234234823;646.3213625693004;668.9661342107102;669.1401549009499;669.5938529088875;4075.513050408436;4118.732282401695;4334.9783251083545;4350.934735978963;4507.729536227836;63804.68097659482;74352;92504;92598.53700731453;99353.50323248337;62546.68726656338;81387;84697.30605387858;84975.15024849672;86567.26865462656;66719.66640166768;71605.64197178981;73337.26662733304;75360;76392;7719.652615632198;8916.821663566563;9485.66800161991;9492.050794920453;9492.57283422234;44597;45365.54634453613;45478.77260613676;47821.5217847831;48182.518174819204;71320.5735885269;72772;73507;73604.05583776589;74423;11880.82178767302;11946.940265298617;12044.397780110785;12084.274943503418;12481.940479820727;14019.228942408265;14052.437902483785;14250.216238107003;14314.265920384272;14380.065295755743;10731.785364292826;12795.872041279468;12907.677308067374;13013.944939465166;13584.45662173502;12706.237625742486;12887.355632218165;13099.148555343872;13222.669433264246;13374.866251337362;11818.645440636794;11973.788721207515;11987.820182697093;12099.879001209874;12146.817797733218;10141.594336226468;10432.389858728066;10432.739181520219;10763.515641796295;11153.721156970816;9138.68014619485;9547.758791357079;9595.184409325286;10732.570697172338;10862.13102951746 22:21:10 INFO - PROCESS | 1644 | __end_tp_report 22:21:10 INFO - PROCESS | 1644 | __start_cc_report 22:21:10 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,1496 22:21:10 INFO - PROCESS | 1644 | __end_cc_report 22:21:10 INFO - PROCESS | 1644 | __startTimestamp1479882070190__endTimestamp 22:21:10 INFO - PROCESS | 1644 | 22:21:10 INFO - PROCESS | 1644 | ------- Summary: start ------- 22:21:10 INFO - PROCESS | 1644 | Number of tests: 6 22:21:10 INFO - PROCESS | 1644 | 22:21:10 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5570.97 Median:5727.28 stddev:611.79 (10.7%) stddev-sans-first:586.50 22:21:10 INFO - PROCESS | 1644 | Values: 3272.7 3275.9 3293.8 3326.3 3351.3 5420.2 5465.0 5491.7 5494.5 5519.9 5474.8 5487.4 5527.5 5557.1 5579.5 4922.0 5002.1 5039.7 5085.5 5121.3 5101.8 5167.8 5172.6 5192.6 5215.5 4995.6 5070.6 5108.4 5152.1 5215.0 5031.8 5054.8 5097.1 5109.0 5117.0 5047.5 5074.1 5078.6 5088.8 5123.9 5512.4 5534.9 5542.1 5550.6 5575.8 5795.2 5830.9 5852.9 5923.3 5989.7 6096.8 6114.1 6114.5 6124.4 6130.5 5581.9 5589.9 5594.4 5606.8 5606.9 6127.1 6151.3 6151.9 6233.1 6307.4 5807.9 5862.2 5869.1 5871.8 5873.5 6021.3 6022.0 6022.1 6022.3 6030.3 5863.8 5924.3 5930.5 5938.5 5954.5 5950.7 5956.2 5959.1 5983.5 5992.9 4759.5 4784.9 4792.8 4795.1 4795.2 4251.4 4263.1 4263.6 4266.9 4364.5 5695.4 5756.4 5757.2 5767.4 5768.9 6020.8 6084.4 6118.1 6134.4 6138.3 5591.1 5654.9 5663.0 5678.0 5704.3 5261.0 5270.6 5286.6 5288.8 5297.0 6296.7 6306.2 6311.3 6313.1 6318.1 5947.0 6111.5 6117.1 6119.9 6130.1 6146.9 6232.1 6235.5 6237.8 6255.7 5906.0 5914.2 5924.4 5924.8 5930.2 5992.7 6016.2 6037.6 6047.7 6052.0 5077.6 5763.2 5798.1 5801.8 5823.9 4948.2 5765.7 5807.7 5872.0 5915.8 5704.0 5711.1 5712.7 5716.8 5727.3 5851.2 5869.7 5889.2 5894.9 5894.9 5580.6 5606.8 5628.2 5640.5 5690.9 22:21:10 INFO - PROCESS | 1644 | 22:21:10 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:63122.09 Median:18827.91 stddev:136981.84 (727.5%) stddev-sans-first:137285.31 22:21:10 INFO - PROCESS | 1644 | Values: 454.3 516.1 525.2 527.5 547.7 29492.0 31000.0 32765.7 38170.7 38518.0 40142.0 40586.0 40652.8 40674.6 40860.2 9183.0 9376.4 9381.2 9382.7 9401.0 8452.0 8654.7 10940.0 11176.0 11207.7 11151.8 11196.7 11197.1 11209.7 11217.8 8450.4 11166.4 11230.9 11232.7 11233.6 8363.2 10701.9 11001.3 11033.3 11200.6 38095.6 38261.2 38428.4 38435.0 38459.0 3276.3 3293.9 3306.3 3357.8 3375.7 28026.4 29281.3 29283.9 29318.4 29330.0 3131.6 3173.0 3208.1 3233.8 3296.6 325543.4 447816.8 469952.0 471057.0 471644.6 52876.0 52973.0 54635.0 54984.5 55081.4 56620.2 56849.4 56953.0 56955.0 57064.4 15028.2 15178.9 15209.8 15212.2 15227.6 10751.4 10956.5 11707.1 11740.9 11745.6 11746.3 12972.7 14039.9 14428.4 14722.7 6633.9 6912.3 6914.2 6922.7 7017.6 790.7 858.6 987.7 1006.7 1026.5 27532.0 28145.2 28694.6 28960.4 29366.4 844.8 1029.3 1086.6 1098.3 1214.3 14006.5 14159.3 14457.7 14492.4 14901.2 634757.0 640863.0 642124.0 642337.0 644369.0 54725.0 54999.7 55020.4 55021.7 55033.0 1212.0 1236.4 1317.0 1336.3 1362.8 28886.6 29648.4 29703.0 30314.4 30400.4 43146.0 43582.6 43596.6 43630.8 43637.8 422578.0 424461.0 425033.0 425269.0 439847.0 24379.9 24666.0 24685.9 24734.5 24769.0 42719.8 42853.0 43313.6 43433.3 43661.6 36034.8 36282.3 36311.6 37258.3 37580.2 34280.1 35597.0 35733.1 36797.3 36801.3 35549.8 35612.5 35790.7 36169.9 36250.6 30507.5 33464.2 33482.0 33498.0 33528.7 24024.9 24326.8 24426.0 24444.1 25078.5 22:21:10 INFO - PROCESS | 1644 | 22:21:10 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:32461.62 Median:23805.71 stddev:92393.96 (388.1%) stddev-sans-first:92621.69 22:21:10 INFO - PROCESS | 1644 | Values: 347.7 364.8 370.2 379.5 400.3 14414.0 14577.1 15375.9 18812.2 20016.5 18709.7 19284.0 19547.4 19856.2 20072.7 5432.8 5699.0 5702.9 5705.1 5708.0 4582.5 5317.3 5590.8 5637.1 5641.7 5612.0 5617.1 5617.8 5621.2 5625.5 5292.3 5476.9 5535.3 5539.9 5540.9 4852.7 4855.8 4857.5 4859.8 4861.0 22981.8 23538.4 24073.0 24845.8 25091.0 2177.6 2196.1 2414.7 2551.6 2683.0 9382.0 9724.5 9989.7 10242.0 10307.0 1760.0 1784.5 1806.5 1809.2 1833.5 98159.0 101275.0 101403.0 101695.0 108818.0 9032.6 9537.3 10882.7 10894.0 11082.4 14143.5 14147.7 14154.0 14165.9 14167.6 6587.0 8240.2 8259.7 8369.5 8394.2 8026.6 8076.7 8190.9 8197.6 8240.5 4299.4 4403.1 4404.8 4497.9 4503.1 1784.7 1858.2 1858.2 1864.2 1865.6 791.4 800.8 805.2 864.6 899.8 17022.1 18277.6 19147.9 20013.7 20308.3 892.2 892.4 905.9 940.2 963.6 9956.0 10420.0 10495.5 10504.6 10537.6 539460.0 540465.0 552975.0 569691.0 575081.0 61040.0 100712.0 126163.4 128007.0 134490.0 864.5 901.3 958.8 962.1 967.2 10541.7 17188.1 17884.0 17975.6 18504.0 44908.8 48402.8 50638.5 53277.7 54163.7 44852.0 75864.5 76647.0 76694.6 79212.0 10239.3 11778.5 12062.9 12115.2 12254.3 11101.6 12815.2 13533.5 13596.5 13794.9 10907.9 10964.2 11343.9 11418.4 11813.1 11219.2 11260.5 11368.6 11739.8 11807.6 11289.2 11582.0 11747.1 11970.2 12064.9 11711.9 11805.5 12039.8 12145.9 12207.5 11546.1 11587.8 11742.8 11777.8 11823.7 22:21:10 INFO - PROCESS | 1644 | 22:21:10 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:5280.24 Median:5112.32 stddev:2908.24 (56.9%) stddev-sans-first:2901.35 22:21:10 INFO - PROCESS | 1644 | Values: 1343.6 1453.7 1614.2 1795.3 1860.2 7660.0 8047.2 8050.8 8056.8 8060.0 7347.6 7622.0 7635.4 7840.8 8099.6 2090.8 2096.9 2282.5 2284.9 2290.4 2281.1 2296.6 2309.6 2317.2 2317.2 1894.9 2273.1 2284.6 2286.6 2294.6 2219.4 2280.8 2283.2 2283.9 2290.9 2273.2 2302.3 2302.6 2304.5 2309.1 8044.1 8213.6 8220.6 8222.3 8235.6 4966.8 5132.7 5144.7 5153.4 5155.8 3257.6 3356.6 3452.2 3534.4 3548.6 1054.9 1078.1 1081.8 1091.7 1098.8 10634.7 10640.0 10781.4 10785.0 10787.9 4152.8 4268.9 4471.7 4593.2 4607.2 8439.3 8963.1 8964.2 9176.5 9224.9 5080.2 5085.6 5090.7 5091.7 5091.9 3828.2 3978.3 4196.6 4679.3 4679.4 3624.0 3624.8 3721.6 3725.5 3731.6 1208.8 1211.8 1216.6 1227.2 1228.6 828.8 847.8 859.5 881.2 885.9 5344.4 5483.5 5537.9 5548.4 5554.5 417.4 441.7 442.9 443.6 446.4 3185.6 3617.3 3624.6 3760.0 3791.7 9443.4 9681.3 9693.4 9959.0 9974.1 9911.5 9913.5 9921.4 9927.2 9936.0 7349.7 7558.8 7584.4 7707.4 7718.7 5359.6 5421.1 5435.9 5503.9 5561.2 7677.5 9260.3 9270.4 9291.7 9301.8 8845.8 9233.8 9267.5 9279.2 9364.6 6451.9 6650.5 6675.6 6943.4 7118.9 6630.3 7463.3 7607.6 7750.7 7803.4 5947.2 6842.0 7068.4 7079.9 7111.5 6876.9 6885.9 6895.7 6922.6 7018.2 6480.2 6480.5 6485.1 6562.9 6573.3 4671.5 4732.7 4757.8 4761.5 4912.5 4742.6 4841.5 4863.5 4869.0 4871.9 22:21:10 INFO - PROCESS | 1644 | 22:21:10 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4247.65 Median:4841.97 stddev:1312.30 (27.1%) stddev-sans-first:1281.33 22:21:10 INFO - PROCESS | 1644 | Values: 256.4 300.9 306.7 314.6 316.6 4627.8 4876.5 5155.5 5224.7 5234.5 4255.0 4350.2 4845.5 4879.6 5003.7 3412.2 3425.8 3529.0 3744.5 3942.8 3285.9 3429.5 3521.3 3755.9 3951.4 3718.6 3797.5 3854.8 3916.7 3925.3 3852.6 3874.0 3891.9 3896.0 3905.3 3155.1 3190.7 3381.7 3389.7 3421.2 4709.9 4934.7 4942.6 4956.4 4979.6 4112.8 4142.2 4378.5 4384.8 4401.6 5357.4 5357.9 5357.9 5390.1 5472.7 3635.5 3655.5 3663.5 3669.5 3671.7 4437.6 4706.0 5655.6 5655.8 5772.9 3710.4 3713.3 3730.4 3730.4 3732.4 3203.9 3753.5 3761.8 3773.5 3779.4 3327.8 4248.5 4259.8 4394.0 4403.6 4919.7 5402.9 5418.8 5429.7 5449.3 1087.7 1104.8 1211.6 1213.8 1219.6 760.4 791.9 808.3 812.9 833.2 4014.9 4511.2 4852.0 4854.5 4885.1 4678.3 5128.5 5163.9 5189.8 5217.1 4808.9 4859.4 4865.3 4871.3 5274.7 2472.4 2510.8 2578.0 2759.8 2787.9 4855.8 4963.4 5442.3 5565.5 5603.4 5134.9 5330.2 5363.9 5449.8 5479.3 5394.9 5589.3 5610.2 5613.6 5624.3 4412.4 5205.0 5226.0 5263.0 5294.8 4499.5 4868.5 5117.2 5228.2 5259.6 5581.2 5602.7 5632.9 5651.3 5655.6 3073.3 3092.5 3480.3 3541.2 3554.3 4480.8 5041.4 5257.8 5271.6 5273.2 5101.8 5106.2 5119.5 5121.7 5136.7 4838.4 5071.8 5097.7 5164.5 5175.7 4462.7 4627.3 4849.5 4973.7 5016.2 4478.5 4637.8 4834.6 5099.9 5264.9 4555.8 4801.0 5042.3 5272.1 5349.8 22:21:10 INFO - PROCESS | 1644 | 22:21:10 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:18791.14 Median:48002.02 stddev:26393.92 (55.0%) stddev-sans-first:26432.60 22:21:10 INFO - PROCESS | 1644 | Values: 597.1 734.0 780.5 781.0 803.4 13653.7 14419.8 14427.6 14434.7 14465.5 12217.2 12275.6 12280.8 12287.3 12309.9 665.6 686.2 691.2 798.8 819.8 820.2 836.7 840.5 840.9 842.7 883.3 884.6 886.4 888.0 888.2 691.9 715.1 847.8 876.2 876.7 660.5 667.3 667.7 667.9 668.1 13743.5 13791.5 13809.2 13811.0 13811.2 12841.7 12910.9 12984.7 13107.5 13143.7 11999.8 12075.0 12151.1 12153.5 12231.1 4276.8 4306.9 4323.9 4329.8 4426.4 90077.0 90913.6 91302.5 92469.0 92803.0 10344.4 10346.4 10452.0 10652.1 10666.6 19604.0 19713.9 19844.3 19915.3 19922.7 6530.7 7367.4 7462.0 7471.1 7559.1 4109.5 4144.7 4165.9 4183.5 4193.9 3154.1 3250.8 3252.6 3537.4 3731.4 673.9 675.5 683.5 692.0 692.7 621.3 647.7 655.5 670.6 681.6 8678.4 8936.1 8970.9 8975.7 9036.5 635.8 646.3 669.0 669.1 669.6 4075.5 4118.7 4335.0 4350.9 4507.7 63804.7 74352.0 92504.0 92598.5 99353.5 62546.7 81387.0 84697.3 84975.2 86567.3 66719.7 71605.6 73337.3 75360.0 76392.0 7719.7 8916.8 9485.7 9492.1 9492.6 44597.0 45365.5 45478.8 47821.5 48182.5 71320.6 72772.0 73507.0 73604.1 74423.0 11880.8 11946.9 12044.4 12084.3 12481.9 14019.2 14052.4 14250.2 14314.3 14380.1 10731.8 12795.9 12907.7 13013.9 13584.5 12706.2 12887.4 13099.1 13222.7 13374.9 11818.6 11973.8 11987.8 12099.9 12146.8 10141.6 10432.4 10432.7 10763.5 11153.7 9138.7 9547.8 9595.2 10732.6 10862.1 22:21:10 INFO - PROCESS | 1644 | -------- Summary: end -------- 22:21:10 INFO - PROCESS | 1644 | 22:21:10 INFO - TEST-INFO | 1644: exit 0 22:21:10 INFO - TEST-OK | dromaeo_css | took 1121976ms 22:21:10 INFO - TEST-START | kraken 22:21:10 INFO - operating with platform_type : mac_ 22:21:10 INFO - Initialising browser for kraken test... 22:21:10 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/tmpWh7TMz/profile http://localhost:49250/getInfo.html) 22:21:13 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 22:21:13 INFO - PROCESS | 1649 | colorDepth:24 22:21:13 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 22:21:13 INFO - PROCESS | 1649 | __metrics 22:21:13 INFO - PROCESS | 1649 | 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" 22:21:13 INFO - PROCESS | 1649 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:21:14 INFO - TEST-INFO | 1649: exit 0 22:21:14 INFO - Browser initialized. 22:21:14 INFO - Running cycle 1/1 for kraken test... 22:21:14 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/tmpWh7TMz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:21:26 INFO - PROCESS | 1650 | RSS: Main: 242782208 22:21:26 INFO - PROCESS | 1650 | 22:21:29 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-beat-detection) 22:21:30 INFO - PROCESS | 1650 | RSS: Main: 293158912 22:21:30 INFO - PROCESS | 1650 | 22:21:32 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-dft) 22:21:33 INFO - PROCESS | 1650 | RSS: Main: 293625856 22:21:33 INFO - PROCESS | 1650 | 22:21:36 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-fft) 22:21:37 INFO - PROCESS | 1650 | RSS: Main: 386265088 22:21:37 INFO - PROCESS | 1650 | 22:21:39 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-oscillator) 22:21:40 INFO - PROCESS | 1650 | RSS: Main: 289128448 22:21:40 INFO - PROCESS | 1650 | 22:21:42 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:21:42 INFO - PROCESS | 1650 | RSS: Main: 292614144 22:21:42 INFO - PROCESS | 1650 | 22:21:45 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-darkroom) 22:21:46 INFO - PROCESS | 1650 | RSS: Main: 335732736 22:21:46 INFO - PROCESS | 1650 | 22:21:48 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-desaturate) 22:21:49 INFO - PROCESS | 1650 | RSS: Main: 336580608 22:21:49 INFO - PROCESS | 1650 | 22:21:52 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49250/tests/kraken/driver.html?testName=json-parse-financial) 22:21:52 INFO - PROCESS | 1650 | RSS: Main: 333824000 22:21:52 INFO - PROCESS | 1650 | 22:21:54 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49250/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:21:55 INFO - PROCESS | 1650 | RSS: Main: 310013952 22:21:55 INFO - PROCESS | 1650 | 22:21:57 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:21:57 INFO - PROCESS | 1650 | RSS: Main: 313176064 22:21:57 INFO - PROCESS | 1650 | 22:21:59 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:22:00 INFO - PROCESS | 1650 | RSS: Main: 314933248 22:22:00 INFO - PROCESS | 1650 | 22:22:03 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:22:04 INFO - PROCESS | 1650 | RSS: Main: 333705216 22:22:04 INFO - PROCESS | 1650 | 22:22:07 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:22:08 INFO - PROCESS | 1650 | RSS: Main: 327196672 22:22:08 INFO - PROCESS | 1650 | 22:22:09 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49250/tests/kraken/driver.html?testName=ai-astar) 22:22:09 INFO - PROCESS | 1650 | RSS: Main: 370851840 22:22:09 INFO - PROCESS | 1650 | 22:22:09 INFO - PROCESS | 1650 | __start_tp_report 22:22:09 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 22:22:09 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 22:22:09 INFO - PROCESS | 1650 | |i|pagename|runs| 22:22:09 INFO - PROCESS | 1650 | |0;ai-astar;114;116;116;114;116;116;115;114;115;115 22:22:09 INFO - PROCESS | 1650 | |1;audio-beat-detection;146;112;116;147;112;117;115;114;131;110 22:22:09 INFO - PROCESS | 1650 | |2;audio-dft;183;182;186;185;184;181;179;179;184;184 22:22:09 INFO - PROCESS | 1650 | |3;audio-fft;76;59;50;88;86;85;83;79;75;58 22:22:09 INFO - PROCESS | 1650 | |4;audio-oscillator;84;70;87;70;71;95;115;68;69;83 22:22:09 INFO - PROCESS | 1650 | |5;imaging-gaussian-blur;87;85;87;84;87;84;87;85;87;84 22:22:09 INFO - PROCESS | 1650 | |6;imaging-darkroom;94;93;94;94;93;94;93;92;93;94 22:22:09 INFO - PROCESS | 1650 | |7;imaging-desaturate;87;87;86;88;87;86;87;87;88;87 22:22:09 INFO - PROCESS | 1650 | |8;json-parse-financial;56;53;54;54;56;53;54;54;53;54 22:22:09 INFO - PROCESS | 1650 | |9;json-stringify-tinderbox;72;69;75;85;74;73;72;72;74;73 22:22:09 INFO - PROCESS | 1650 | |10;stanford-crypto-aes;89;89;88;87;88;87;86;87;91;87 22:22:09 INFO - PROCESS | 1650 | |11;stanford-crypto-ccm;151;156;165;150;149;149;148;172;218;166 22:22:09 INFO - PROCESS | 1650 | |12;stanford-crypto-pbkdf2;173;173;173;173;172;173;169;159;214;234 22:22:09 INFO - PROCESS | 1650 | |13;stanford-crypto-sha256-iterative;68;67;69;67;67;67;68;67;66;66 22:22:09 INFO - PROCESS | 1650 | __end_tp_report 22:22:09 INFO - PROCESS | 1650 | __start_cc_report 22:22:09 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,2060 22:22:09 INFO - PROCESS | 1650 | __end_cc_report 22:22:09 INFO - PROCESS | 1650 | __startTimestamp1479882129994__endTimestamp 22:22:09 INFO - PROCESS | 1650 | 22:22:10 INFO - PROCESS | 1650 | ------- Summary: start ------- 22:22:10 INFO - PROCESS | 1650 | Number of tests: 14 22:22:10 INFO - PROCESS | 1650 | 22:22:10 INFO - PROCESS | 1650 | [#0] ai-astar Cycles:10 Average:115.10 Median:115.50 stddev:0.88 (0.8%) stddev-sans-first:0.83 22:22:10 INFO - PROCESS | 1650 | Values: 114.0 116.0 116.0 114.0 116.0 116.0 115.0 114.0 115.0 115.0 22:22:10 INFO - PROCESS | 1650 | 22:22:10 INFO - PROCESS | 1650 | [#1] audio-beat-detection Cycles:10 Average:122.00 Median:116.50 stddev:14.14 (12.1%) stddev-sans-first:12.04 22:22:10 INFO - PROCESS | 1650 | Values: 146.0 112.0 116.0 147.0 112.0 117.0 115.0 114.0 131.0 110.0 22:22:10 INFO - PROCESS | 1650 | 22:22:10 INFO - PROCESS | 1650 | [#2] audio-dft Cycles:10 Average:182.70 Median:184.00 stddev:2.41 (1.3%) stddev-sans-first:2.55 22:22:10 INFO - PROCESS | 1650 | Values: 183.0 182.0 186.0 185.0 184.0 181.0 179.0 179.0 184.0 184.0 22:22:10 INFO - PROCESS | 1650 | 22:22:10 INFO - PROCESS | 1650 | [#3] audio-fft Cycles:10 Average:73.90 Median:81.00 stddev:13.45 (16.6%) stddev-sans-first:14.25 22:22:10 INFO - PROCESS | 1650 | Values: 76.0 59.0 50.0 88.0 86.0 85.0 83.0 79.0 75.0 58.0 22:22:10 INFO - PROCESS | 1650 | 22:22:10 INFO - PROCESS | 1650 | [#4] audio-oscillator Cycles:10 Average:81.20 Median:77.00 stddev:15.11 (19.6%) stddev-sans-first:16.00 22:22:10 INFO - PROCESS | 1650 | Values: 84.0 70.0 87.0 70.0 71.0 95.0 115.0 68.0 69.0 83.0 22:22:10 INFO - PROCESS | 1650 | 22:22:10 INFO - PROCESS | 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:85.70 Median:87.00 stddev:1.42 (1.6%) stddev-sans-first:1.42 22:22:10 INFO - PROCESS | 1650 | Values: 87.0 85.0 87.0 84.0 87.0 84.0 87.0 85.0 87.0 84.0 22:22:10 INFO - PROCESS | 1650 | 22:22:10 INFO - PROCESS | 1650 | [#6] imaging-darkroom Cycles:10 Average:93.40 Median:94.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 22:22:10 INFO - PROCESS | 1650 | Values: 94.0 93.0 94.0 94.0 93.0 94.0 93.0 92.0 93.0 94.0 22:22:10 INFO - PROCESS | 1650 | 22:22:10 INFO - PROCESS | 1650 | [#7] imaging-desaturate Cycles:10 Average:87.00 Median:87.00 stddev:0.67 (0.8%) stddev-sans-first:0.71 22:22:10 INFO - PROCESS | 1650 | Values: 87.0 87.0 86.0 88.0 87.0 86.0 87.0 87.0 88.0 87.0 22:22:10 INFO - PROCESS | 1650 | 22:22:10 INFO - PROCESS | 1650 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.00 stddev:1.10 (2.0%) stddev-sans-first:0.93 22:22:10 INFO - PROCESS | 1650 | Values: 56.0 53.0 54.0 54.0 56.0 53.0 54.0 54.0 53.0 54.0 22:22:10 INFO - PROCESS | 1650 | 22:22:10 INFO - PROCESS | 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:73.90 Median:73.50 stddev:4.23 (5.8%) stddev-sans-first:4.43 22:22:10 INFO - PROCESS | 1650 | Values: 72.0 69.0 75.0 85.0 74.0 73.0 72.0 72.0 74.0 73.0 22:22:10 INFO - PROCESS | 1650 | 22:22:10 INFO - PROCESS | 1650 | [#10] stanford-crypto-aes Cycles:10 Average:87.90 Median:88.00 stddev:1.45 (1.6%) stddev-sans-first:1.48 22:22:10 INFO - PROCESS | 1650 | Values: 89.0 89.0 88.0 87.0 88.0 87.0 86.0 87.0 91.0 87.0 22:22:10 INFO - PROCESS | 1650 | 22:22:10 INFO - PROCESS | 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:162.40 Median:160.50 stddev:21.33 (13.3%) stddev-sans-first:22.22 22:22:10 INFO - PROCESS | 1650 | Values: 151.0 156.0 165.0 150.0 149.0 149.0 148.0 172.0 218.0 166.0 22:22:10 INFO - PROCESS | 1650 | 22:22:10 INFO - PROCESS | 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.30 Median:173.00 stddev:23.40 (13.5%) stddev-sans-first:24.62 22:22:10 INFO - PROCESS | 1650 | Values: 173.0 173.0 173.0 173.0 172.0 173.0 169.0 159.0 214.0 234.0 22:22:10 INFO - PROCESS | 1650 | 22:22:10 INFO - PROCESS | 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.00 stddev:0.92 (1.4%) stddev-sans-first:0.93 22:22:10 INFO - PROCESS | 1650 | Values: 68.0 67.0 69.0 67.0 67.0 67.0 68.0 67.0 66.0 66.0 22:22:10 INFO - PROCESS | 1650 | -------- Summary: end -------- 22:22:10 INFO - PROCESS | 1650 | 22:22:11 INFO - TEST-INFO | 1650: exit 0 22:22:11 INFO - TEST-OK | kraken | took 60292ms 22:22:11 INFO - SUITE-END | took 1182s 22:22:11 INFO - Completed test suite (00:19:42) 22:22:11 INFO - javascript benchmark 22:22:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3272.689094536019, 3275.9017229483115, 3293.814226878325, 3326.334733053387, 3351.32973405319, 5420.186971954209, 5465.016297066526, 5491.725413729318, 5494.478024587663, 5519.862003449915, 5474.7810087596445, 5487.42382049885, 5527.50252477277, 5557.138643510262, 5579.46995035473, 4922.01559688062, 5002.099622068026, 5039.672421292605, 5085.491450854904, 5121.3342265505435, 5101.770420331094, 5167.793288268484, 5172.6379153459275, 5192.636515443921, 5215.504527069941, 4995.625328100371, 5070.645054846125, 5108.386993560778, 5152.072626927152, 5214.973925130369, 5031.77357018935, 5054.797808087672, 5097.133487307166, 5108.974455127701, 5117.0, 5047.47001564834, 5074.06129865976, 5078.61910356721, 5088.847334579968, 5123.923141152885, 5512.39363669996, 5534.916976245359, 5542.140968149903, 5550.639208451437, 5575.832725018256, 5795.159701843209, 5830.854228644275, 5852.941470585325, 5923.318818335903, 5989.700514974234, 6096.817095487098, 6114.145302971524, 6114.549543274474, 6124.391422862037, 6130.531944610715, 5581.888362232773, 5589.8602534936135, 5594.356648985337, 5606.803761868315, 5606.915896261559, 6127.111568822585, 6151.2618485781995, 6151.9234134027265, 6233.065040243909, 6307.432331090224, 5807.8548036299435, 5862.208601838697, 5869.090291004815, 5871.823845284648, 5873.471387575053, 6021.337652858182, 6022.0, 6022.126791615191, 6022.277326720821, 6030.33666296707, 5863.824085277449, 5924.289085309704, 5930.525557955353, 5938.465538101505, 5954.523638108941, 5950.672713000827, 5956.225690660273, 5959.106134079922, 5983.521318294526, 5992.910106348409, 4759.452662943806, 4784.856454306377, 4792.808287668524, 4795.064962332382, 4795.160846851857, 4251.383549385322, 4263.104748002892, 4263.616274535307, 4266.893327666833, 4364.519902810688, 5695.430456954271, 5756.395578464285, 5757.193992841006, 5767.423257674199, 5768.884622307531, 6020.759169633166, 6084.421979911901, 6118.1434599156155, 6134.386561343919, 6138.294096178996, 5591.07747221712, 5654.886902261932, 5663.008973429633, 5677.971610141923, 5704.34400043992, 5260.973695131576, 5270.578353731692, 5286.577073834161, 5288.762005709676, 5297.0, 6296.685165741603, 6306.211723534559, 6311.305756366888, 6313.05304204373, 6318.147050148263, 5946.970265148647, 6111.480524155497, 6117.1130186123655, 6119.938800611937, 6130.141780150693, 6146.862830376394, 6232.096346029891, 6235.46998505114, 6237.812865614039, 6255.687215639109, 5905.9959806832085, 5914.172015917861, 5924.377940316204, 5924.822255332347, 5930.229070220844, 5992.730327135202, 6016.187814644967, 6037.63774173551, 6047.697615119315, 6052.03167493199, 5077.593792496517, 5763.193152958673, 5798.1302804579655, 5801.767929282782, 5823.912641310299, 4948.233023881304, 5765.682887441231, 5807.738651760766, 5872.03111486617, 5915.792947246826, 5703.971480142573, 5711.143328500758, 5712.714364281853, 5716.828495145152, 5727.284089488814, 5851.239338885917, 5869.735861886311, 5889.234399528034, 5894.882102357843, 5894.941050589611, 5580.5535557156145, 5606.831795046155, 5628.212050312877, 5640.520555752642, 5690.914636280377], "value": 5532.217972992889, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [454.2663598288763, 516.0994065355954, 525.1518797142614, 527.4514504914891, 547.73161151036, 29492.0, 31000.0, 32765.67234327656, 38170.66402675907, 38518.0, 40142.0, 40586.0, 40652.79673601635, 40674.59325406745, 40860.18279634413, 9182.954085229565, 9376.437413755197, 9381.15569598736, 9382.718518444424, 9400.952995235015, 8452.0, 8654.697085602005, 10940.0, 11176.0, 11207.719807004809, 11151.776964460705, 11196.720081997975, 11197.104231661488, 11209.66371008867, 11217.775644487105, 8450.366222533274, 11166.385848778315, 11230.887691123107, 11232.663020109328, 11233.550657973672, 8363.20549547792, 10701.892981070168, 11001.339919604849, 11033.337999720043, 11200.551977920873, 38095.619043809485, 38261.234775304336, 38428.42357364642, 38435.0, 38459.0, 3276.344731053775, 3293.917652058694, 3306.2560923791953, 3357.84889679965, 3375.6793104655017, 28026.439471210462, 29281.267968300748, 29283.85358073217, 29318.41363172746, 29330.0, 3131.561581378609, 3173.0, 3208.0536241808722, 3233.7736358454918, 3296.571445712066, 325543.37228313944, 447816.76091619657, 469952.0, 471057.0, 471644.64177679236, 52876.0, 52973.0, 54635.0, 54984.45015549874, 55081.449185508434, 56620.15069773957, 56849.43150568524, 56953.0, 56955.0, 57064.42935570674, 15028.17345046033, 15178.86158538114, 15209.771853422208, 15212.1633310169, 15227.61930951713, 10751.387291906276, 10956.506957186946, 11707.063434925185, 11740.941295293469, 11745.588904388476, 11746.29522228669, 12972.67568310781, 14039.929800350932, 14428.350724217435, 14722.705545889237, 6633.877159308975, 6912.308769123148, 6914.204866440323, 6922.653867306614, 7017.578945263199, 790.6758229126145, 858.6093327535956, 987.7086259553449, 1006.6527048168371, 1026.5303274930686, 27532.0, 28145.155645330262, 28694.569581456293, 28960.42079158405, 29366.392561673452, 844.7803571071443, 1029.3360782295379, 1086.6359769477156, 1098.319042193845, 1214.3017764785284, 14006.509772157926, 14159.292035398188, 14457.710845783236, 14492.420303187966, 14901.180435075958, 634757.0, 640863.0, 642124.0, 642337.0, 644369.0, 54725.0, 54999.72500137554, 55020.44979550153, 55021.724891375285, 55033.0, 1212.0243204220687, 1236.4003458322707, 1316.967075823093, 1336.2984433172662, 1362.8228330316995, 28886.566701499072, 29648.40703185881, 29703.0, 30314.39371212519, 30400.39199215959, 43146.0, 43582.56417435785, 43596.56403436052, 43630.78184609057, 43637.78181109074, 422578.0, 424461.0, 425033.0, 425269.0, 439847.0, 24379.9029043697, 24666.01335946542, 24685.87657061703, 24734.505309894063, 24769.009239630213, 42719.786401067795, 42853.0, 43313.56686433221, 43433.26266949287, 43661.56338436575, 36034.819825900704, 36282.274354512236, 36311.63688363188, 37258.25483490261, 37580.2483950314, 34280.14299642429, 35597.0, 35733.1066723334, 36797.26405471929, 36801.26397472089, 35549.82225088858, 35612.465813012306, 35790.74732384354, 36169.91490255296, 36250.63749362472, 30507.542386864658, 33464.1633959153, 33481.99554013384, 33498.0, 33528.664713352555, 24024.87987560051, 24326.756732433158, 24426.0, 24444.14445494399, 25078.49843003166], "value": 18897.722844113192, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.71834813800837, 364.8139448881071, 370.1689706608664, 379.45358683495766, 400.31146428143603, 14414.0, 14577.125372477645, 15375.92312038441, 18812.15345309461, 20016.499587510283, 18709.719354209697, 19284.0, 19547.41357759266, 19856.20575176991, 20072.69890951637, 5432.809851655193, 5699.031164702011, 5702.885942281152, 5705.115707065412, 5707.971460142714, 4582.450105987286, 5317.2821669074565, 5590.804321848736, 5637.126245431965, 5641.746121424525, 5611.961787069385, 5617.0731829248125, 5617.775288988436, 5621.156826476021, 5625.493705566499, 5292.311999440068, 5476.890462190734, 5535.308086489189, 5539.9169012464845, 5540.889182216373, 4852.684575502581, 4855.83004594841, 4857.53853383928, 4859.781309841065, 4861.0277944411255, 22981.77018229813, 23538.411539711473, 24073.0, 24845.751542484526, 25091.0, 2177.575372802304, 2196.1325276515686, 2414.746451622572, 2551.6045013023013, 2683.047518131055, 9382.0, 9724.465154416504, 9989.700308990741, 10242.0, 10307.020833020926, 1759.9560010999699, 1784.5449410400395, 1806.5303021214447, 1809.185866360143, 1833.4591295567707, 98159.0, 101275.0, 101403.0, 101695.0, 108818.0, 9032.593533291018, 9537.284703647254, 10882.727931801768, 10894.0, 11082.390468524147, 14143.50497732595, 14147.717045659028, 14154.0, 14165.85834141666, 14167.574972750834, 6586.967065164644, 8240.175982401832, 8259.669613215525, 8369.455985360932, 8394.202550757669, 8026.558539280279, 8076.71731489395, 8190.8771368429525, 8197.590120493951, 8240.546769927592, 4299.355096735515, 4403.141387429485, 4404.757738324359, 4497.865064048084, 4503.099380123988, 1784.6787578236042, 1858.1545396844404, 1858.1731129647178, 1864.1704441523664, 1865.580244444989, 791.3589992106412, 800.8438354520929, 805.1827395193883, 864.6411739128191, 899.7660608241904, 17022.14889255557, 18277.58573524668, 19147.851128932307, 20013.699794503093, 20308.289209877585, 892.161368313783, 892.4199270474114, 905.8958219804807, 940.2337095267335, 963.5952899781968, 9956.0, 10420.0, 10495.527701253463, 10504.579816807394, 10537.57849686004, 539460.0, 540465.0, 552975.0, 569691.0, 575081.0, 61040.0, 100711.98576028438, 126163.36918315533, 128007.0, 134490.0, 864.4769914201818, 901.2519608724742, 958.7746879483316, 962.0523784072823, 967.2455484721928, 10541.683749487527, 17188.14059297055, 17884.0, 17975.640487190445, 18504.0, 44908.77545612382, 48402.75798620984, 50638.49361506338, 53277.73361133169, 54163.72918135542, 44852.0, 75864.4827103466, 76647.0, 76694.616526917, 79212.0, 10239.283250172417, 11778.469968851594, 12062.939685301517, 12115.151939364512, 12254.264744115382, 11101.611443599435, 12815.16701414405, 13533.526326578523, 13596.456141754219, 13794.93102534481, 10907.945460272647, 10964.232503724663, 11343.886561134283, 11418.371989540323, 11813.114016448973, 11219.214654974074, 11260.493277802683, 11368.602098926498, 11739.823902641638, 11807.586734464252, 11289.20975531705, 11582.015528680156, 11747.063234191452, 11970.22193557416, 12064.879351206375, 11711.88288117108, 11805.468753905923, 12039.75920481603, 12145.939270303592, 12207.511699531919, 11546.074334726234, 11587.768244635228, 11742.823857642314, 11777.823332649845, 11823.704407389883], "value": 9296.969494415831, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1343.5834891183733, 1453.7092581483698, 1614.2251719174797, 1795.3267524678245, 1860.23730270589, 7660.00419945407, 8047.155048719881, 8050.758477245678, 8056.7582972510845, 8060.0, 7347.595882226476, 7622.0, 7635.427342949273, 7840.8039799005055, 8099.5950202489785, 2090.822280106193, 2096.8637038592483, 2282.486440550871, 2284.9314520564326, 2290.3701482092392, 2281.1217681192693, 2296.609576372021, 2309.5727290451237, 2317.2353123469215, 2317.2353123469297, 1894.8768330058572, 2273.1362082408664, 2284.554511870186, 2286.622707253301, 2294.598445272079, 2219.411855858199, 2280.8061314788265, 2283.2008796921195, 2283.9200627978107, 2290.8625482471107, 2273.204378467523, 2302.286291249701, 2302.6315789473606, 2304.50453152573, 2309.099451214028, 8044.074931382907, 8213.630386632616, 8220.63007164679, 8222.259996600274, 8235.588220588947, 4966.826161084345, 5132.692038477666, 5144.6913185209005, 5153.3558305211845, 5155.7679904404395, 3257.6090869095624, 3356.5636467259346, 3452.188735647121, 3534.3991521441417, 3548.556430446194, 1054.9419781911993, 1078.0620859851979, 1081.8431327457474, 1091.6888686724326, 1098.8132017557034, 10634.734131646694, 10640.0, 10781.353118812896, 10785.0, 10787.946060269727, 4152.771597562102, 4268.893277668083, 4471.709338892962, 4593.173228818778, 4607.193741095295, 8439.282660973764, 8963.058878817761, 8964.238039766562, 9176.49529275883, 9224.8616270756, 5080.161773307363, 5085.567726743194, 5090.669106508065, 5091.694498330112, 5091.949080509147, 3828.2343531293855, 3978.2640211560374, 4196.643285320721, 4679.321498382784, 4679.368285281512, 3624.0215141911535, 3624.7643903146213, 3721.5534135903863, 3725.5343082114737, 3731.626837316301, 1208.7522057978094, 1211.8485189351331, 1216.5620376664394, 1227.1716591300835, 1228.5577192210797, 828.7886588919787, 847.8050048488759, 859.5143743784741, 881.2421317666801, 885.9291256699448, 5344.412114667383, 5483.506484416422, 5537.889242215214, 5548.4174161713245, 5554.528050066736, 417.36560428149045, 441.6577152706678, 442.8671398580413, 443.58303194996597, 446.3661600527259, 3185.6336521300345, 3617.294627547603, 3624.6012938576737, 3760.0, 3791.7156213284115, 9443.386179898285, 9681.273904457054, 9693.418394896187, 9959.0, 9974.102330790263, 9911.45486998222, 9913.45475998827, 9921.40471571708, 9927.156191723785, 9936.0, 7349.70601175947, 7558.811029724191, 7584.4311676624475, 7707.383409327241, 7718.729844455417, 5359.598030147832, 5421.132618780976, 5435.918461223006, 5503.862403439946, 5561.193626924154, 7677.462577619736, 9260.30547708938, 9270.351075424656, 9291.72124836256, 9301.813963720822, 8845.778855528662, 9233.815323693623, 9267.490288033952, 9279.164875161268, 9364.625414983597, 6451.903221451776, 6650.467962562887, 6675.599464032173, 6943.444524438134, 7118.928810711826, 6630.270670226175, 7463.253674632493, 7607.581583012988, 7750.728724494616, 7803.375729941705, 5947.226860508106, 6841.965790171017, 7068.434525237866, 7079.893801592877, 7111.466640001917, 6876.896846547205, 6885.862282754417, 6895.6552172390175, 6922.58464492132, 7018.227994920452, 6480.189976252968, 6480.513961452815, 6485.092087107903, 6562.934370656232, 6573.276939536551, 4671.532846715301, 4732.6687131900435, 4757.7621118943225, 4761.452432970253, 4912.459629440687, 4742.596879265301, 4841.491643377394, 4863.537963893425, 4869.0, 4871.90256194867], "value": 4221.832497525426, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [256.3770038805702, 300.9082229919875, 306.6718611086137, 314.5501932236902, 316.5726269536125, 4627.8380256691025, 4876.487968763282, 5155.458676838929, 5224.686518808874, 5234.5027222413855, 4254.978725106378, 4350.216960947037, 4845.49789565235, 4879.585235255007, 5003.749812509369, 3412.2322477442626, 3425.845836937344, 3528.9593415539193, 3744.494493243418, 3942.8225729842084, 3285.934281314372, 3429.4567444649883, 3521.3485505181498, 3755.9061023474496, 3951.4467974483596, 3718.628137186287, 3797.525309336333, 3854.8458061677497, 3916.725829191958, 3925.2934471795065, 3852.57621661617, 3874.0314921269683, 3891.9416208756893, 3895.9805200973956, 3905.2775236581324, 3155.1165673611426, 3190.7287880530184, 3381.7463690223085, 3389.67798059184, 3421.161815355254, 4709.882252943669, 4934.728589927553, 4942.555170034678, 4956.3804524434445, 4979.588981547651, 4112.773797441139, 4142.150859073913, 4378.452693413324, 4384.780760961939, 4401.6478681705385, 5357.43746906577, 5357.866053348658, 5357.919631205535, 5390.056740070472, 5472.699001554913, 3635.509206257148, 3655.524781778352, 3663.4504824276305, 3669.477166975702, 3671.724620653435, 4437.600615944581, 4706.0, 5655.632383894993, 5655.773769049274, 5772.942270577324, 3710.406334986389, 3713.294474049922, 3730.421784623388, 3730.4404339349317, 3732.384156614128, 3203.8878639247514, 3753.530808648919, 3761.8495260189284, 3773.4528493368307, 3779.3952967525065, 3327.750418718606, 4248.511421186541, 4259.787010649455, 4394.033312671208, 4403.581659742384, 4919.704817710949, 5402.91895621566, 5418.810341638024, 5429.746655138073, 5449.3460784706085, 1087.6954452753241, 1104.8399180860065, 1211.5759484180642, 1213.8422005139453, 1219.621917205669, 760.3764912771585, 791.8794905209064, 808.2604416958535, 812.9430939834273, 833.204289903141, 4014.9397759033636, 4511.233090374644, 4852.0, 4854.466008739036, 4885.093369445174, 4678.298255261738, 5128.487151284841, 5163.870903227375, 5189.844304670791, 5217.069684216864, 4808.917993451445, 4859.441164266084, 4865.343178670834, 4871.29366241893, 5274.683518988873, 2472.443700167448, 2510.79913606911, 2577.9613305800426, 2759.7719015038115, 2787.8606069696434, 4855.8057677692495, 4963.429205641398, 5442.346918369821, 5565.471280228453, 5603.431039309, 5134.942082049179, 5330.227117068003, 5363.946360536423, 5449.782008719686, 5479.287752562427, 5394.865128371822, 5589.304983813616, 5610.242617246701, 5613.578981576398, 5624.268845050117, 4412.448443944507, 5204.9739751301, 5225.961874868728, 5262.97368513155, 5294.788208471772, 4499.482559505568, 4868.513148685103, 5117.232415137759, 5228.241904923841, 5259.552938000313, 5581.246531718166, 5602.674889920638, 5632.887342253214, 5651.265335506358, 5655.632383895034, 3073.3085055862252, 3092.505199168122, 3480.2865412590863, 3541.1855273286774, 3554.2713743683, 4480.843170489017, 5041.395032596112, 5257.79580095491, 5271.551918087006, 5273.156294992783, 5101.795928162832, 5106.157484015096, 5119.465864397147, 5121.743912804271, 5136.743162841918, 4838.435798831231, 5071.77177027042, 5097.668651537638, 5164.457731938092, 5175.663581867167, 4462.709923854939, 4627.30590411441, 4849.466558678472, 4973.7369496879555, 5016.2224855147515, 4478.507364189872, 4637.814487420465, 4834.564889159992, 5099.929014906911, 5264.921026184534, 4555.794989225559, 4800.95199048019, 5042.327566389819, 5272.051030814337, 5349.812756553502], "value": 3826.4501583474907, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [597.0536699331559, 733.9633018349081, 780.5160800303812, 780.9726659566917, 803.3894240377314, 13653.65865853353, 14419.78370324446, 14427.567172984815, 14434.711305773879, 14465.493707720232, 12217.20588161771, 12275.63173104806, 12280.754384912296, 12287.26276423413, 12309.938450307736, 665.5507532415636, 686.1800148822159, 691.2292793535197, 798.7523867601062, 819.8442295963778, 820.1757233979841, 836.6569706420369, 840.5251033166272, 840.8990921089478, 842.7219017724167, 883.2977782662798, 884.593087179898, 886.441541828647, 887.9556022198897, 888.205056474455, 691.8581690753383, 715.1489727224638, 847.7922908887363, 876.2332958661171, 876.6537217798998, 660.5244224158646, 667.3293340193123, 667.7028722218614, 667.9365460281266, 668.1314291421133, 13743.488216296199, 13791.51729689456, 13809.171449713049, 13811.033227674066, 13811.171329720148, 12841.67895802603, 12910.87089129106, 12984.675383115404, 13107.541236056692, 13143.737125257441, 11999.760004799942, 12075.0, 12151.149419540636, 12153.453094610764, 12231.143819932608, 4276.828926842923, 4306.935395969063, 4323.898194300982, 4329.7618630975285, 4426.424564806554, 90077.0, 90913.63629545562, 91302.5434872828, 92469.0, 92803.0, 10344.431056291825, 10346.430946298025, 10452.0, 10652.09457196147, 10666.573337066431, 19604.0, 19713.901430493042, 19844.305449309206, 19915.302964396178, 19922.701159482618, 6530.673466326664, 7367.447441441914, 7461.962690186623, 7471.066116735408, 7559.130699969464, 4109.506859176918, 4144.70987030911, 4165.91668166635, 4183.49798024239, 4193.895152621148, 3154.1010811918495, 3250.7886987345746, 3252.642209356969, 3537.398642230827, 3731.36566783642, 673.8618583190434, 675.5102550650837, 683.4566519616917, 691.989620155698, 692.712524302419, 621.3413781391739, 647.6793986976477, 655.4854439265154, 670.577536152221, 681.5978572642066, 8678.392512524062, 8936.106389361012, 8970.86543701832, 8975.730728078168, 9036.502992335354, 635.755234234823, 646.3213625693004, 668.9661342107102, 669.1401549009499, 669.5938529088875, 4075.513050408436, 4118.732282401695, 4334.9783251083545, 4350.934735978963, 4507.729536227836, 63804.68097659482, 74352.0, 92504.0, 92598.53700731453, 99353.50323248337, 62546.68726656338, 81387.0, 84697.30605387858, 84975.15024849672, 86567.26865462656, 66719.66640166768, 71605.64197178981, 73337.26662733304, 75360.0, 76392.0, 7719.652615632198, 8916.821663566563, 9485.66800161991, 9492.050794920453, 9492.57283422234, 44597.0, 45365.54634453613, 45478.77260613676, 47821.5217847831, 48182.518174819204, 71320.5735885269, 72772.0, 73507.0, 73604.05583776589, 74423.0, 11880.82178767302, 11946.940265298617, 12044.397780110785, 12084.274943503418, 12481.940479820727, 14019.228942408265, 14052.437902483785, 14250.216238107003, 14314.265920384272, 14380.065295755743, 10731.785364292826, 12795.872041279468, 12907.677308067374, 13013.944939465166, 13584.45662173502, 12706.237625742486, 12887.355632218165, 13099.148555343872, 13222.669433264246, 13374.866251337362, 11818.645440636794, 11973.788721207515, 11987.820182697093, 12099.879001209874, 12146.817797733218, 10141.594336226468, 10432.389858728066, 10432.739181520219, 10763.515641796295, 11153.721156970816, 9138.68014619485, 9547.758791357079, 9595.184409325286, 10732.570697172338, 10862.13102951746], "value": 7090.334947129979, "unit": "score"}], "name": "dromaeo_css", "value": 6936.025511597155, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 116.0, 116.0, 114.0, 116.0, 116.0, 115.0, 114.0, 115.0, 115.0], "value": 115.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 112.0, 116.0, 147.0, 112.0, 117.0, 115.0, 114.0, 131.0, 110.0], "value": 122.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 182.0, 186.0, 185.0, 184.0, 181.0, 179.0, 179.0, 184.0, 184.0], "value": 182.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 59.0, 50.0, 88.0, 86.0, 85.0, 83.0, 79.0, 75.0, 58.0], "value": 73.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 70.0, 87.0, 70.0, 71.0, 95.0, 115.0, 68.0, 69.0, 83.0], "value": 81.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 84.0, 87.0, 84.0, 87.0, 85.0, 87.0, 84.0], "value": 85.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 94.0, 93.0, 94.0, 93.0, 92.0, 93.0, 94.0], "value": 93.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 86.0, 88.0, 87.0, 86.0, 87.0, 87.0, 88.0, 87.0], "value": 87.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 54.0, 54.0, 56.0, 53.0, 54.0, 54.0, 53.0, 54.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 69.0, 75.0, 85.0, 74.0, 73.0, 72.0, 72.0, 74.0, 73.0], "value": 73.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 88.0, 87.0, 88.0, 87.0, 86.0, 87.0, 91.0, 87.0], "value": 87.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 156.0, 165.0, 150.0, 149.0, 149.0, 148.0, 172.0, 218.0, 166.0], "value": 162.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 173.0, 173.0, 173.0, 172.0, 173.0, 169.0, 159.0, 214.0, 234.0], "value": 181.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 69.0, 67.0, 67.0, 67.0, 68.0, 67.0, 66.0, 66.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1467.8000000000002, "alertThreshold": 2.0}]} 22:22:11 INFO - Return code: 0 22:22:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:22:11 INFO - # TBPL SUCCESS # 22:22:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:22:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:22:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:22:11 INFO - 2016-11-22 22:22:11.325 screenresolution[1654:10208] starting screenresolution argv=screenresolution get 22:22:11 INFO - 2016-11-22 22:22:11.327 screenresolution[1654:10208] Display 0: 1600x1200x32@60 22:22:11 INFO - 2016-11-22 22:22:11.333 screenresolution[1655:10211] starting screenresolution argv=screenresolution list 22:22:11 INFO - Available Modes on Display 0 22:22:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:22:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:22:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:22:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:22:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:22:11 INFO - Intel Iris: 22:22:11 INFO - Chipset Model: Intel Iris 22:22:11 INFO - Type: GPU 22:22:11 INFO - Bus: Built-In 22:22:11 INFO - VRAM (Dynamic, Max): 1536 MB 22:22:11 INFO - Vendor: Intel (0x8086) 22:22:11 INFO - Device ID: 0x0a2e 22:22:11 INFO - Revision ID: 0x0009 22:22:11 INFO - Displays: 22:22:11 INFO - Display: 22:22:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:22:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:22:11 INFO - Display Serial Number: 1600x1200 60 22:22:11 INFO - Main Display: Yes 22:22:11 INFO - Mirror: Off 22:22:11 INFO - Online: Yes 22:22:11 INFO - Rotation: Supported 22:22:11 INFO - Return code: 0 22:22:11 INFO - Running post-action listener: _resource_record_post_action 22:22:11 INFO - [mozharness: 2016-11-23 06:22:11.581395Z] Finished run-tests step (success) 22:22:11 INFO - Running post-run listener: _resource_record_post_run 22:22:11 INFO - Total resource usage - Wall time: 1215s; CPU: 54.0%; Read bytes: 33262592; Write bytes: 695043584; Read time: 306; Write time: 2061 22:22:11 INFO - TinderboxPrint: CPU usage
53.6% 22:22:11 INFO - TinderboxPrint: I/O read bytes / time
33,262,592 / 306 22:22:11 INFO - TinderboxPrint: I/O write bytes / time
695,043,584 / 2,061 22:22:11 INFO - TinderboxPrint: CPU idle
2,255.4 (46.4%) 22:22:11 INFO - TinderboxPrint: CPU system
971.7 (20.0%) 22:22:11 INFO - TinderboxPrint: CPU user
1,634.6 (33.6%) 22:22:11 INFO - TinderboxPrint: Swap in / out
411,828,224 / 0 22:22:11 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 369734144; Write bytes: 366794240; Read time: 27625; Write time: 687 22:22:11 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 31468544; Write bytes: 308854784; Read time: 289; Write time: 1331 22:22:12 INFO - Running post-run listener: _upload_blobber_files 22:22:12 INFO - Blob upload gear active. 22:22:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:22:12 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 22:22:12 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:22:12 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:22:12 INFO - (blobuploader) - INFO - Open directory for files ... 22:22:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:22:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:22:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:22:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:22:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:22:13 INFO - (blobuploader) - INFO - Done attempting. 22:22:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:22:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:22:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:22:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:22:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:22:14 INFO - (blobuploader) - INFO - Done attempting. 22:22:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:22:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:22:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:22:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:22:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:22:16 INFO - (blobuploader) - INFO - Done attempting. 22:22:16 INFO - (blobuploader) - INFO - Iteration through files over. 22:22:16 INFO - Return code: 0 22:22:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:22:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:22:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e86a0eb8a7b55031bb01e3f668f83b88e9ac54167002e911ed6106ed79452d7e109e24d114f7f1aa4a58b05582029d97233fe51ef44e8e137e325a1afe6e176", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68e8336c3b1160e95102f373c0ac81eb0d542f66e38bca68808e95f2edfb731f387bd486e6c60cc3efad91096fe592aa5d5c510251c1195d190b7a4a45990ef9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78b38825c5786d5ce972aed689fcca4b8f5bd201cb02ba681d9ded4f8b5d1bc5b5a59df33ceaacbbcb421e95dcb3b54d8427cfeac2bebf5e7fc2e32efc1daa31"} 22:22:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:22:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:22:16 INFO - Contents: 22:22:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e86a0eb8a7b55031bb01e3f668f83b88e9ac54167002e911ed6106ed79452d7e109e24d114f7f1aa4a58b05582029d97233fe51ef44e8e137e325a1afe6e176", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68e8336c3b1160e95102f373c0ac81eb0d542f66e38bca68808e95f2edfb731f387bd486e6c60cc3efad91096fe592aa5d5c510251c1195d190b7a4a45990ef9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78b38825c5786d5ce972aed689fcca4b8f5bd201cb02ba681d9ded4f8b5d1bc5b5a59df33ceaacbbcb421e95dcb3b54d8427cfeac2bebf5e7fc2e32efc1daa31"} 22:22:16 INFO - Running post-run listener: copy_logs_to_upload_dir 22:22:16 INFO - Copying logs to upload dir... 22:22:16 INFO - mkdir: /builds/slave/test/build/upload/logs 22:22:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.320840 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-11-22 22:22:16.422075) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 22:22:16.425865) ========= 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.Rj74ifhysJ/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.VcR7roVL2d/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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/Firefox/sha512/5e86a0eb8a7b55031bb01e3f668f83b88e9ac54167002e911ed6106ed79452d7e109e24d114f7f1aa4a58b05582029d97233fe51ef44e8e137e325a1afe6e176", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68e8336c3b1160e95102f373c0ac81eb0d542f66e38bca68808e95f2edfb731f387bd486e6c60cc3efad91096fe592aa5d5c510251c1195d190b7a4a45990ef9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78b38825c5786d5ce972aed689fcca4b8f5bd201cb02ba681d9ded4f8b5d1bc5b5a59df33ceaacbbcb421e95dcb3b54d8427cfeac2bebf5e7fc2e32efc1daa31"} build_url:https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011255 build_url: 'https://queue.taskcluster.net/v1/task/FgYyjQEoRSCS9cConuG-GA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e86a0eb8a7b55031bb01e3f668f83b88e9ac54167002e911ed6106ed79452d7e109e24d114f7f1aa4a58b05582029d97233fe51ef44e8e137e325a1afe6e176", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68e8336c3b1160e95102f373c0ac81eb0d542f66e38bca68808e95f2edfb731f387bd486e6c60cc3efad91096fe592aa5d5c510251c1195d190b7a4a45990ef9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78b38825c5786d5ce972aed689fcca4b8f5bd201cb02ba681d9ded4f8b5d1bc5b5a59df33ceaacbbcb421e95dcb3b54d8427cfeac2bebf5e7fc2e32efc1daa31"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 22:22:16.460008) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 22:22:16.460427) ========= 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.Rj74ifhysJ/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.VcR7roVL2d/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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.005208 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 22:22:16.509046) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-22 22:22:16.509353) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-22 22:22:16.509651) ========= ========= Total master_lag: 0.18 =========