builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0383 starttime: 1482332778.04 results: success (0) buildid: 20161221051553 builduid: bc03c4a0eb7042899d69d9732de2c2b6 revision: 72904e12c789881a79cf053ce6f50a0080c2791e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 07:06:18.044286) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 07:06:18.044807) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 07:06:18.045088) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 07:06:18.070349) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 07:06:18.070766) ========= 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.2TrofkS004/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.F0jn5NqNDY/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-21 07:06:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 143M=0s 2016-12-21 07:06:18 (143 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.094604 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 07:06:18.188893) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 07:06:18.189274) ========= 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.2TrofkS004/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.F0jn5NqNDY/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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.020156 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 07:06:18.239019) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-21 07:06:18.239372) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 72904e12c789881a79cf053ce6f50a0080c2791e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 72904e12c789881a79cf053ce6f50a0080c2791e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2TrofkS004/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.F0jn5NqNDY/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-21 07:06:18,320 truncating revision to first 12 chars 2016-12-21 07:06:18,320 Setting DEBUG logging. 2016-12-21 07:06:18,320 attempt 1/10 2016-12-21 07:06:18,320 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/72904e12c789?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-21 07:06:19,952 unpacking tar archive at: mozilla-aurora-72904e12c789/testing/mozharness/ program finished with exit code 0 elapsedTime=1.889295 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-21 07:06:20.152369) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 07:06:20.152685) ========= script_repo_revision: 72904e12c789881a79cf053ce6f50a0080c2791e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 07:06:20.153087) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 07:06:20.153351) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 07:06:20.173080) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-12-21 07:06:20.173426) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2TrofkS004/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.F0jn5NqNDY/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 07:06:20 INFO - MultiFileLogger online at 20161221 07:06:20 in /builds/slave/test 07:06:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 07:06:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:06:20 INFO - {'append_to_log': False, 07:06:20 INFO - 'base_work_dir': '/builds/slave/test', 07:06:20 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:06:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:06:20 INFO - 'branch': 'Mozilla-Aurora', 07:06:20 INFO - 'buildbot_json_path': 'buildprops.json', 07:06:20 INFO - 'config_files': ('talos/mac_config.py',), 07:06:20 INFO - 'default_actions': ('clobber', 07:06:20 INFO - 'read-buildbot-config', 07:06:20 INFO - 'download-and-extract', 07:06:20 INFO - 'populate-webroot', 07:06:20 INFO - 'create-virtualenv', 07:06:20 INFO - 'install', 07:06:20 INFO - 'run-tests'), 07:06:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:06:20 INFO - 'download_minidump_stackwalk': True, 07:06:20 INFO - 'download_symbols': 'ondemand', 07:06:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:06:20 INFO - 'tooltool.py': '/tools/tooltool.py', 07:06:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:06:20 INFO - '/tools/misc-python/virtualenv.py')}, 07:06:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:06:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:06:20 INFO - 'installer_path': 'installer.exe', 07:06:20 INFO - 'log_level': 'info', 07:06:20 INFO - 'log_name': 'talos', 07:06:20 INFO - 'log_to_console': True, 07:06:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:06:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:06:20 INFO - 'opt_config_files': (), 07:06:20 INFO - 'pip_index': False, 07:06:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:06:20 INFO - 'cmd': ('bash', 07:06:20 INFO - '-c', 07:06:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:06:20 INFO - 'enabled': True, 07:06:20 INFO - 'halt_on_failure': False, 07:06:20 INFO - 'name': 'check_screen_resolution'},), 07:06:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:06:20 INFO - 'cmd': ('bash', 07:06:20 INFO - '-c', 07:06:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:06:20 INFO - 'enabled': True, 07:06:20 INFO - 'halt_on_failure': False, 07:06:20 INFO - 'name': 'check_screen_resolution'},), 07:06:20 INFO - 'run_cmd_checks_enabled': True, 07:06:20 INFO - 'sps_profile': False, 07:06:20 INFO - 'sps_profile_interval': 0, 07:06:20 INFO - 'suite': 'dromaeojs', 07:06:20 INFO - 'system_bits': '32', 07:06:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:06:20 INFO - 'title': 't-yosemite-r7-0383', 07:06:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:06:20 INFO - 'use_talos_json': True, 07:06:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:06:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:06:20 INFO - 'work_dir': 'build'} 07:06:20 INFO - [mozharness: 2016-12-21 15:06:20.372486Z] Running clobber step. 07:06:20 INFO - Running pre-action listener: _resource_record_pre_action 07:06:20 INFO - Running main action method: clobber 07:06:20 INFO - rmtree: /builds/slave/test/build 07:06:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:06:22 INFO - Running post-action listener: _resource_record_post_action 07:06:22 INFO - [mozharness: 2016-12-21 15:06:22.577947Z] Finished clobber step (success) 07:06:22 INFO - [mozharness: 2016-12-21 15:06:22.578126Z] Running read-buildbot-config step. 07:06:22 INFO - Running pre-action listener: _resource_record_pre_action 07:06:22 INFO - Running main action method: read_buildbot_config 07:06:22 INFO - Using buildbot properties: 07:06:22 INFO - { 07:06:22 INFO - "project": "", 07:06:22 INFO - "product": "firefox", 07:06:22 INFO - "script_repo_revision": "production", 07:06:22 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 07:06:22 INFO - "repository": "", 07:06:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 07:06:22 INFO - "buildid": "20161221051553", 07:06:22 INFO - "pgo_build": "False", 07:06:22 INFO - "basedir": "/builds/slave/test", 07:06:22 INFO - "buildnumber": 18, 07:06:22 INFO - "slavename": "t-yosemite-r7-0383", 07:06:22 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 07:06:22 INFO - "platform": "yosemite_r7", 07:06:22 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 07:06:22 INFO - "branch": "mozilla-aurora", 07:06:22 INFO - "repo_path": "releases/mozilla-aurora", 07:06:22 INFO - "revision": "72904e12c789881a79cf053ce6f50a0080c2791e", 07:06:22 INFO - "stage_platform": "macosx64", 07:06:22 INFO - "builduid": "bc03c4a0eb7042899d69d9732de2c2b6", 07:06:22 INFO - "slavebuilddir": "test" 07:06:22 INFO - } 07:06:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/YpLX7sLsRsCq6G4nIOWMMg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 07:06:22 INFO - Running post-action listener: _resource_record_post_action 07:06:22 INFO - [mozharness: 2016-12-21 15:06:22.581489Z] Finished read-buildbot-config step (success) 07:06:22 INFO - [mozharness: 2016-12-21 15:06:22.581590Z] Running download-and-extract step. 07:06:22 INFO - Running pre-action listener: _resource_record_pre_action 07:06:22 INFO - Running main action method: download_and_extract 07:06:22 INFO - mkdir: /builds/slave/test/build/tests 07:06:22 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')]} 07:06:22 INFO - trying https://queue.taskcluster.net/v1/task/YpLX7sLsRsCq6G4nIOWMMg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 07:06:22 INFO - Downloading https://queue.taskcluster.net/v1/task/YpLX7sLsRsCq6G4nIOWMMg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 07:06:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YpLX7sLsRsCq6G4nIOWMMg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 07:06:23 INFO - Downloaded 1199 bytes. 07:06:23 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 07:06:23 INFO - Using the following test package requirements: 07:06:23 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 07:06:23 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:06:23 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 07:06:23 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:06:23 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 07:06:23 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:06:23 INFO - u'jsshell-mac.zip'], 07:06:23 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:06:23 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 07:06:23 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 07:06:23 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:06:23 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 07:06:23 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:06:23 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 07:06:23 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:06:23 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 07:06:23 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 07:06:23 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 07:06:23 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 07:06:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YpLX7sLsRsCq6G4nIOWMMg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 07:06:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YpLX7sLsRsCq6G4nIOWMMg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:06:23 INFO - Fetch https://queue.taskcluster.net/v1/task/YpLX7sLsRsCq6G4nIOWMMg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 07:06:24 INFO - Http code: 200 07:06:24 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 07:06:24 INFO - x-amz-cf-id: 8MWBSw1P-x-YTL9fwUsyCq73xdM01E8ikkdPdI-s-dquIVTpCrdQPQ== 07:06:24 INFO - x-amz-version-id: zb8mUxVaAaElGee9r7pY4esrrW3zmO4z 07:06:25 INFO - Expected file size: 16267522 07:06:25 INFO - Obtained file size: 16267522 07:06:31 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 07:06:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YpLX7sLsRsCq6G4nIOWMMg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 07:06:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YpLX7sLsRsCq6G4nIOWMMg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 07:06:31 INFO - Fetch https://queue.taskcluster.net/v1/task/YpLX7sLsRsCq6G4nIOWMMg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 07:06:32 INFO - Http code: 200 07:06:32 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 07:06:32 INFO - x-amz-cf-id: Hp3VfKezijJgiF3wpEPnauYYJle1hec0IXTo6T2qEAvFGsuljyo5pg== 07:06:32 INFO - x-amz-version-id: zb8mUxVaAaElGee9r7pY4esrrW3zmO4z 07:06:32 INFO - Expected file size: 16267522 07:06:32 INFO - Obtained file size: 16267522 07:06:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YpLX7sLsRsCq6G4nIOWMMg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 07:06:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YpLX7sLsRsCq6G4nIOWMMg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 07:06:38 INFO - Fetch https://queue.taskcluster.net/v1/task/YpLX7sLsRsCq6G4nIOWMMg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 07:06:39 INFO - Http code: 200 07:06:39 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 07:06:39 INFO - x-amz-cf-id: OtYL5OmPagfEwAZxFePMEkT37QPXnMLHGgNRoi_wPPx5ymyJF9_beA== 07:06:39 INFO - x-amz-version-id: A01SMxFBnr8BcgaXTK4ayrGLxkNgbEg5 07:06:40 INFO - Expected file size: 14409038 07:06:40 INFO - Obtained file size: 14409038 07:06: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')]} 07:06:40 INFO - trying https://queue.taskcluster.net/v1/task/YpLX7sLsRsCq6G4nIOWMMg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:06:40 INFO - Downloading https://queue.taskcluster.net/v1/task/YpLX7sLsRsCq6G4nIOWMMg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 07:06:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YpLX7sLsRsCq6G4nIOWMMg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 07:06:44 INFO - Downloaded 109767883 bytes. 07:06:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YpLX7sLsRsCq6G4nIOWMMg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:06:44 INFO - mkdir: /builds/slave/test/properties 07:06:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:06:44 INFO - Writing to file /builds/slave/test/properties/build_url 07:06:44 INFO - Contents: 07:06:44 INFO - build_url:https://queue.taskcluster.net/v1/task/YpLX7sLsRsCq6G4nIOWMMg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 07:06:44 INFO - Running post-action listener: _resource_record_post_action 07:06:44 INFO - Running post-action listener: set_extra_try_arguments 07:06:44 INFO - [mozharness: 2016-12-21 15:06:44.836288Z] Finished download-and-extract step (success) 07:06:44 INFO - [mozharness: 2016-12-21 15:06:44.836447Z] Running populate-webroot step. 07:06:44 INFO - Running pre-action listener: _resource_record_pre_action 07:06:44 INFO - Running main action method: populate_webroot 07:06:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:06:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:06:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:06:44 INFO - '--tppagecycles', 07:06:44 INFO - '7'], 07:06:44 INFO - 'tests': ['tsvgm']}}, 07:06:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:06:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:06:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:06:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:06:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:06:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:06:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:06:44 INFO - 'talos_options': ['--disable-e10s'], 07:06:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:06:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:06:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:06:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:06:44 INFO - 'talos_options': ['--disable-e10s'], 07:06:44 INFO - 'tests': ['damp', 'tps']}, 07:06:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:06:44 INFO - 'tests': ['damp', 'tps']}, 07:06:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:06:44 INFO - 'tests': ['dromaeo_dom']}, 07:06:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:06:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:06:44 INFO - 'tests': ['basic_compositor_video']}, 07:06:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:06:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:06:44 INFO - 'tests': ['a11yr', 07:06:44 INFO - 'ts_paint', 07:06:44 INFO - 'tpaint', 07:06:44 INFO - 'sessionrestore', 07:06:44 INFO - 'sessionrestore_no_auto_restore', 07:06:44 INFO - 'tabpaint']}, 07:06:44 INFO - 'other-e10s': {'tests': ['a11yr', 07:06:44 INFO - 'ts_paint', 07:06:44 INFO - 'tpaint', 07:06:44 INFO - 'sessionrestore', 07:06:44 INFO - 'sessionrestore_no_auto_restore', 07:06:44 INFO - 'tabpaint']}, 07:06:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:06:44 INFO - 'ts_paint', 07:06:44 INFO - 'tpaint', 07:06:44 INFO - 'sessionrestore', 07:06:44 INFO - 'sessionrestore_no_auto_restore', 07:06:44 INFO - 'tabpaint']}, 07:06:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:06:44 INFO - 'ts_paint', 07:06:44 INFO - 'tpaint', 07:06:44 INFO - 'sessionrestore', 07:06:44 INFO - 'sessionrestore_no_auto_restore', 07:06:44 INFO - 'tabpaint']}, 07:06:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:06:44 INFO - 'tests': ['a11yr', 07:06:44 INFO - 'ts_paint', 07:06:44 INFO - 'tpaint', 07:06:44 INFO - 'sessionrestore', 07:06:44 INFO - 'sessionrestore_no_auto_restore', 07:06:44 INFO - 'tabpaint']}, 07:06:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:06:44 INFO - 'tests': ['a11yr', 07:06:44 INFO - 'ts_paint', 07:06:44 INFO - 'tpaint', 07:06:44 INFO - 'sessionrestore', 07:06:44 INFO - 'sessionrestore_no_auto_restore', 07:06:44 INFO - 'tabpaint']}, 07:06:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:06:44 INFO - 'tests': ['tsvgx', 07:06:44 INFO - 'tsvgr_opacity', 07:06:44 INFO - 'tart', 07:06:44 INFO - 'tscrollx', 07:06:44 INFO - 'cart']}, 07:06:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:06:44 INFO - 'tsvgr_opacity', 07:06:44 INFO - 'tart', 07:06:44 INFO - 'tscrollx', 07:06:44 INFO - 'cart']}, 07:06:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:06:44 INFO - 'talos_options': ['--disable-e10s'], 07:06:44 INFO - 'tests': ['tp5o']}, 07:06:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:06:44 INFO - 'tests': ['tp5o']}, 07:06:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:06:44 INFO - 'talos_options': ['--disable-e10s', 07:06:44 INFO - '--xperf_path', 07:06:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:06:44 INFO - 'tests': ['tp5n']}, 07:06:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:06:44 INFO - 'talos_options': ['--xperf_path', 07:06:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:06:44 INFO - 'tests': ['tp5n']}}, 07:06:44 INFO - 'talos.zip': {'path': '', 07:06:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:06:44 INFO - Running post-action listener: _resource_record_post_action 07:06:44 INFO - [mozharness: 2016-12-21 15:06:44.856882Z] Finished populate-webroot step (success) 07:06:44 INFO - [mozharness: 2016-12-21 15:06:44.857022Z] Running create-virtualenv step. 07:06:44 INFO - Running pre-action listener: _resource_record_pre_action 07:06:44 INFO - Running main action method: create_virtualenv 07:06:44 INFO - Creating virtualenv /builds/slave/test/build/venv 07:06:44 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 07:06:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:06:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:06:44 INFO - Using real prefix '/tools/python27' 07:06:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:06:45 INFO - Installing distribute.............................................................................................................................................................................................done. 07:06:48 INFO - Installing pip.................done. 07:06:48 INFO - Return code: 0 07:06:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:06:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:06:48 INFO - Reading from file tmpfile_stdout 07:06:48 INFO - Output received: 07:06:48 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) 07:06:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:06:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:06:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:06:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:06:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:06:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:48 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 0x10f768490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6de570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96f358b650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57cd30>, '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 0x10f3e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f574b20>, '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.2TrofkS004/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.F0jn5NqNDY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 07:06:48 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 07:06:48 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 07:06:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2TrofkS004/Render', 07:06:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:06:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:06:48 INFO - 'HOME': '/Users/cltbld', 07:06:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:06:48 INFO - 'LOGNAME': 'cltbld', 07:06:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:06:48 INFO - 'MOZ_NO_REMOTE': '1', 07:06:48 INFO - 'NO_EM_RESTART': '1', 07:06:48 INFO - 'PAGER': '/bin/cat', 07:06:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:06:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:06:48 INFO - 'PWD': '/builds/slave/test', 07:06:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:06:48 INFO - 'SHELL': '/bin/bash', 07:06:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F0jn5NqNDY/Listeners', 07:06:48 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 07:06:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:06:48 INFO - 'USER': 'cltbld', 07:06:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:06:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:06:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:06:48 INFO - 'XPC_FLAGS': '0x0', 07:06:48 INFO - 'XPC_SERVICE_NAME': '0', 07:06:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:06:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:06:49 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 07:06:49 INFO - Cleaning up... 07:06:49 INFO - Return code: 0 07:06:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:06: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')]} 07:06:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:06:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06: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')]} 07:06:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:06:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:49 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 0x10f768490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6de570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96f358b650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57cd30>, '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 0x10f3e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f574b20>, '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.2TrofkS004/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.F0jn5NqNDY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 07:06:49 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 07:06:49 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 07:06:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2TrofkS004/Render', 07:06:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:06:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:06:49 INFO - 'HOME': '/Users/cltbld', 07:06:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:06:49 INFO - 'LOGNAME': 'cltbld', 07:06:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:06:49 INFO - 'MOZ_NO_REMOTE': '1', 07:06:49 INFO - 'NO_EM_RESTART': '1', 07:06:49 INFO - 'PAGER': '/bin/cat', 07:06:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:06:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:06:49 INFO - 'PWD': '/builds/slave/test', 07:06:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:06:49 INFO - 'SHELL': '/bin/bash', 07:06:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F0jn5NqNDY/Listeners', 07:06:49 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 07:06:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:06:49 INFO - 'USER': 'cltbld', 07:06:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:06:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:06:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:06:49 INFO - 'XPC_FLAGS': '0x0', 07:06:49 INFO - 'XPC_SERVICE_NAME': '0', 07:06:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:06:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:06:49 INFO - Downloading/unpacking psutil>=3.1.1 07:06:49 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 07:06:49 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 07:06:49 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 07:06:49 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 07:06:49 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 07:06:49 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 07:06:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:06:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:06:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:06:53 INFO - Installing collected packages: psutil 07:06:53 INFO - Running setup.py install for psutil 07:06:53 INFO - building 'psutil._psutil_osx' extension 07:06:53 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 07:06:53 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 07:06:53 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 07:06:53 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 07:06:53 INFO - building 'psutil._psutil_posix' extension 07:06:53 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 07:06:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:06:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:06:53 INFO - ^ 07:06:53 INFO - 1 warning generated. 07:06:53 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 07:06:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:06:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:06:53 INFO - Successfully installed psutil 07:06:53 INFO - Cleaning up... 07:06:53 INFO - Return code: 0 07:06:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:06:53 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')]} 07:06:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:06:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:53 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')]} 07:06:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:06:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:53 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 0x10f768490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6de570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96f358b650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57cd30>, '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 0x10f3e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f574b20>, '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.2TrofkS004/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.F0jn5NqNDY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 07:06:53 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 07:06:53 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 07:06:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2TrofkS004/Render', 07:06:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:06:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:06:53 INFO - 'HOME': '/Users/cltbld', 07:06:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:06:53 INFO - 'LOGNAME': 'cltbld', 07:06:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:06:53 INFO - 'MOZ_NO_REMOTE': '1', 07:06:53 INFO - 'NO_EM_RESTART': '1', 07:06:53 INFO - 'PAGER': '/bin/cat', 07:06:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:06:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:06:53 INFO - 'PWD': '/builds/slave/test', 07:06:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:06:53 INFO - 'SHELL': '/bin/bash', 07:06:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F0jn5NqNDY/Listeners', 07:06:53 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 07:06:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:06:53 INFO - 'USER': 'cltbld', 07:06:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:06:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:06:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:06:53 INFO - 'XPC_FLAGS': '0x0', 07:06:53 INFO - 'XPC_SERVICE_NAME': '0', 07:06:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:06:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:06:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:06:54 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 07:06:54 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 07:06:54 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 07:06:54 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 07:06:54 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 07:06:54 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 07:06:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:06:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:06:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:06:56 INFO - Installing collected packages: mozsystemmonitor 07:06:56 INFO - Running setup.py install for mozsystemmonitor 07:06:57 INFO - Successfully installed mozsystemmonitor 07:06:57 INFO - Cleaning up... 07:06:57 INFO - Return code: 0 07:06:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:06:57 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')]} 07:06:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:06:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:57 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')]} 07:06:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:06:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:06:57 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 0x10f768490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6de570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96f358b650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57cd30>, '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 0x10f3e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f574b20>, '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.2TrofkS004/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.F0jn5NqNDY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 07:06:57 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 07:06:57 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 07:06:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2TrofkS004/Render', 07:06:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:06:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:06:57 INFO - 'HOME': '/Users/cltbld', 07:06:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:06:57 INFO - 'LOGNAME': 'cltbld', 07:06:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:06:57 INFO - 'MOZ_NO_REMOTE': '1', 07:06:57 INFO - 'NO_EM_RESTART': '1', 07:06:57 INFO - 'PAGER': '/bin/cat', 07:06:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:06:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:06:57 INFO - 'PWD': '/builds/slave/test', 07:06:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:06:57 INFO - 'SHELL': '/bin/bash', 07:06:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F0jn5NqNDY/Listeners', 07:06:57 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 07:06:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:06:57 INFO - 'USER': 'cltbld', 07:06:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:06:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:06:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:06:57 INFO - 'XPC_FLAGS': '0x0', 07:06:57 INFO - 'XPC_SERVICE_NAME': '0', 07:06:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:06:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:06:57 INFO - Downloading/unpacking jsonschema==2.5.1 07:06:57 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 07:06:57 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 07:06:57 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 07:06:57 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 07:06:57 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 07:06:57 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 07:07:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:07:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:07:00 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 07:07:00 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 07:07:00 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 07:07:00 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 07:07:00 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 07:07:00 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 07:07:00 INFO - Downloading functools32-3.2.3-2.tar.gz 07:07:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:07:00 INFO - warning: no files found matching '*.txt' 07:07:00 INFO - no previously-included directories found matching 'build' 07:07:00 INFO - no previously-included directories found matching 'dist' 07:07:00 INFO - no previously-included directories found matching '.git*' 07:07:00 INFO - Installing collected packages: jsonschema, functools32 07:07:00 INFO - Running setup.py install for functools32 07:07:00 INFO - warning: no files found matching '*.txt' 07:07:00 INFO - no previously-included directories found matching 'build' 07:07:00 INFO - no previously-included directories found matching 'dist' 07:07:00 INFO - no previously-included directories found matching '.git*' 07:07:00 INFO - Successfully installed jsonschema functools32 07:07:00 INFO - Cleaning up... 07:07:00 INFO - Return code: 0 07:07:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:07:00 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')]} 07:07:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:00 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')]} 07:07:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:00 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 0x10f768490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6de570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96f358b650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57cd30>, '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 0x10f3e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f574b20>, '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.2TrofkS004/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.F0jn5NqNDY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 07:07:00 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 07:07:00 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 07:07:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2TrofkS004/Render', 07:07:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:07:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:07:00 INFO - 'HOME': '/Users/cltbld', 07:07:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:07:00 INFO - 'LOGNAME': 'cltbld', 07:07:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:00 INFO - 'MOZ_NO_REMOTE': '1', 07:07:00 INFO - 'NO_EM_RESTART': '1', 07:07:00 INFO - 'PAGER': '/bin/cat', 07:07:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:07:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:00 INFO - 'PWD': '/builds/slave/test', 07:07:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:07:00 INFO - 'SHELL': '/bin/bash', 07:07:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F0jn5NqNDY/Listeners', 07:07:00 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 07:07:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:07:00 INFO - 'USER': 'cltbld', 07:07:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:07:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:07:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:00 INFO - 'XPC_FLAGS': '0x0', 07:07:00 INFO - 'XPC_SERVICE_NAME': '0', 07:07:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:07:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:07:01 INFO - Cleaning up... 07:07:01 INFO - Return code: 0 07:07:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:07:01 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')]} 07:07:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:01 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')]} 07:07:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:01 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 0x10f768490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6de570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96f358b650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57cd30>, '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 0x10f3e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f574b20>, '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.2TrofkS004/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.F0jn5NqNDY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 07:07:01 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 07:07:01 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 07:07:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2TrofkS004/Render', 07:07:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:07:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:07:01 INFO - 'HOME': '/Users/cltbld', 07:07:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:07:01 INFO - 'LOGNAME': 'cltbld', 07:07:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:01 INFO - 'MOZ_NO_REMOTE': '1', 07:07:01 INFO - 'NO_EM_RESTART': '1', 07:07:01 INFO - 'PAGER': '/bin/cat', 07:07:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:07:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:01 INFO - 'PWD': '/builds/slave/test', 07:07:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:07:01 INFO - 'SHELL': '/bin/bash', 07:07:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F0jn5NqNDY/Listeners', 07:07:01 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 07:07:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:07:01 INFO - 'USER': 'cltbld', 07:07:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:07:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:07:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:01 INFO - 'XPC_FLAGS': '0x0', 07:07:01 INFO - 'XPC_SERVICE_NAME': '0', 07:07:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:07:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:01 INFO - Downloading/unpacking blobuploader==1.2.4 07:07:01 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 07:07:01 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 07:07:01 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 07:07:01 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 07:07:01 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 07:07:01 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 07:07:03 INFO - Downloading blobuploader-1.2.4.tar.gz 07:07:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:07:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:07:04 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 07:07:04 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 07:07:04 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 07:07:04 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 07:07:04 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 07:07:04 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 07:07:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:07:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:07:04 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 07:07:04 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 07:07:04 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 07:07:04 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 07:07:04 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 07:07:04 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 07:07:05 INFO - Downloading docopt-0.6.1.tar.gz 07:07:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:07:05 INFO - Installing collected packages: blobuploader, requests, docopt 07:07:05 INFO - Running setup.py install for blobuploader 07:07:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:07:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:07:05 INFO - Running setup.py install for requests 07:07:05 INFO - Running setup.py install for docopt 07:07:06 INFO - Successfully installed blobuploader requests docopt 07:07:06 INFO - Cleaning up... 07:07:06 INFO - Return code: 0 07:07:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:07:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:06 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 0x10f768490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6de570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96f358b650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57cd30>, '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 0x10f3e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f574b20>, '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.2TrofkS004/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.F0jn5NqNDY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 07:07:06 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 07:07:06 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 07:07:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2TrofkS004/Render', 07:07:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:07:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:07:06 INFO - 'HOME': '/Users/cltbld', 07:07:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:07:06 INFO - 'LOGNAME': 'cltbld', 07:07:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:06 INFO - 'MOZ_NO_REMOTE': '1', 07:07:06 INFO - 'NO_EM_RESTART': '1', 07:07:06 INFO - 'PAGER': '/bin/cat', 07:07:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:07:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:06 INFO - 'PWD': '/builds/slave/test', 07:07:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:07:06 INFO - 'SHELL': '/bin/bash', 07:07:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F0jn5NqNDY/Listeners', 07:07:06 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 07:07:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:07:06 INFO - 'USER': 'cltbld', 07:07:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:07:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:07:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:06 INFO - 'XPC_FLAGS': '0x0', 07:07:06 INFO - 'XPC_SERVICE_NAME': '0', 07:07:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:07:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:07:06 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-pf8CDx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:07:06 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-6Y4SzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:07:06 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-pCzAKg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:07:06 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-z0S5g8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:07:06 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-wbTYfF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:07:06 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-JZVIA0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:07:07 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-Ldtcw2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:07:07 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-kvFYIm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:07:07 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-RtJMPP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:07:07 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-5aAh3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:07:07 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-uzfuvg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:07:07 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-qENrK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:07:07 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-uSVWFn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:07:07 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-_3pbIW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:07:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:07:07 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-iMaKX0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:07:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:07:08 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-fHIhYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:07:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:07:08 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-OyYaty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:07:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:07:08 INFO - Running setup.py install for manifestparser 07:07:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:07:08 INFO - Running setup.py install for mozcrash 07:07:08 INFO - Running setup.py install for mozdebug 07:07:08 INFO - Running setup.py install for mozdevice 07:07:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:07:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:07:08 INFO - Running setup.py install for mozfile 07:07:08 INFO - Running setup.py install for mozhttpd 07:07:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:07:09 INFO - Running setup.py install for mozinfo 07:07:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:07:09 INFO - Running setup.py install for mozInstall 07:07:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:07:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:07:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:07:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:07:09 INFO - Running setup.py install for mozleak 07:07:09 INFO - Running setup.py install for mozlog 07:07:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:07:09 INFO - Running setup.py install for moznetwork 07:07:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:07:09 INFO - Running setup.py install for mozprocess 07:07:10 INFO - Running setup.py install for mozprofile 07:07:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:07:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:07:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:07:10 INFO - Running setup.py install for mozrunner 07:07:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:07:10 INFO - Running setup.py install for mozscreenshot 07:07:10 INFO - Running setup.py install for moztest 07:07:10 INFO - Running setup.py install for mozversion 07:07:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:07:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:07:10 INFO - Cleaning up... 07:07:10 INFO - Return code: 0 07:07:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:07:10 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')]} 07:07:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:10 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')]} 07:07:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:10 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 0x10f768490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6de570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96f358b650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57cd30>, '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 0x10f3e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f574b20>, '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.2TrofkS004/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.F0jn5NqNDY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 07:07:10 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 07:07:10 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 07:07:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2TrofkS004/Render', 07:07:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:07:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:07:10 INFO - 'HOME': '/Users/cltbld', 07:07:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:07:10 INFO - 'LOGNAME': 'cltbld', 07:07:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:10 INFO - 'MOZ_NO_REMOTE': '1', 07:07:10 INFO - 'NO_EM_RESTART': '1', 07:07:10 INFO - 'PAGER': '/bin/cat', 07:07:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:07:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:10 INFO - 'PWD': '/builds/slave/test', 07:07:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:07:10 INFO - 'SHELL': '/bin/bash', 07:07:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F0jn5NqNDY/Listeners', 07:07:10 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 07:07:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:07:10 INFO - 'USER': 'cltbld', 07:07:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:07:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:07:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:10 INFO - 'XPC_FLAGS': '0x0', 07:07:10 INFO - 'XPC_SERVICE_NAME': '0', 07:07:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:07:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:07:11 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-T1aBmy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:07:11 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)) 07:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:07:11 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-BrtYds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:07:11 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)) 07:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:07:11 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-1Fnxxq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:07:11 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)) 07:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:07:11 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-0C4Cpk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:07:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:07:11 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-I3TiVJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:07:11 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)) 07:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:07:11 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-3ZCmtj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:07:11 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)) 07:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:07:11 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-qS5C5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:07:11 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)) 07:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:07:11 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-wx_LGR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:07:11 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)) 07:07:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:07:11 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-yMs5QL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:07:12 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)) 07:07:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:07:12 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-PTewxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:07:12 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)) 07:07:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:07:12 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-lo3EqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:07:12 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)) 07:07:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:07:12 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-mkTTyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:07:12 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)) 07:07:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:07:12 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-JXhngl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:07:12 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)) 07:07:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:07:12 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-74Vhpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:07:12 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)) 07:07:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:07:12 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-iYiITR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:07:12 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)) 07:07:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:07:12 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-4OZPnT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:07:12 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)) 07:07:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:07:12 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-X7ScUU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:07:12 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)) 07:07:12 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)) 07:07:12 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)) 07:07:12 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)) 07:07:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:07:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:07:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:07:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:07:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:07:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:07:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:07:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:07:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:07:15 INFO - Downloading blessings-1.6.tar.gz 07:07:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:07:15 INFO - Installing collected packages: blessings 07:07:15 INFO - Running setup.py install for blessings 07:07:15 INFO - Successfully installed blessings 07:07:15 INFO - Cleaning up... 07:07:15 INFO - Return code: 0 07:07:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:07:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:07:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:07:16 INFO - Reading from file tmpfile_stdout 07:07:16 INFO - Current package versions: 07:07:16 INFO - blessings == 1.6 07:07:16 INFO - blobuploader == 1.2.4 07:07:16 INFO - docopt == 0.6.1 07:07:16 INFO - functools32 == 3.2.3-2 07:07:16 INFO - jsonschema == 2.5.1 07:07:16 INFO - manifestparser == 1.1 07:07:16 INFO - mozInstall == 1.12 07:07:16 INFO - mozcrash == 1.0 07:07:16 INFO - mozdebug == 0.1 07:07:16 INFO - mozdevice == 0.48 07:07:16 INFO - mozfile == 1.2 07:07:16 INFO - mozhttpd == 0.7 07:07:16 INFO - mozinfo == 0.9 07:07:16 INFO - mozleak == 0.1 07:07:16 INFO - mozlog == 3.4 07:07:16 INFO - moznetwork == 0.27 07:07:16 INFO - mozprocess == 0.23 07:07:16 INFO - mozprofile == 0.28 07:07:16 INFO - mozrunner == 6.13 07:07:16 INFO - mozscreenshot == 0.1 07:07:16 INFO - mozsystemmonitor == 0.3 07:07:16 INFO - moztest == 0.7 07:07:16 INFO - mozversion == 1.4 07:07:16 INFO - psutil == 3.1.1 07:07:16 INFO - requests == 1.2.3 07:07:16 INFO - wsgiref == 0.1.2 07:07:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:07:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:16 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 0x10f768490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6de570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96f358b650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57cd30>, '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 0x10f3e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f574b20>, '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.2TrofkS004/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.F0jn5NqNDY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 07:07:16 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 07:07:16 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 07:07:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2TrofkS004/Render', 07:07:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:07:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:07:16 INFO - 'HOME': '/Users/cltbld', 07:07:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:07:16 INFO - 'LOGNAME': 'cltbld', 07:07:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:16 INFO - 'MOZ_NO_REMOTE': '1', 07:07:16 INFO - 'NO_EM_RESTART': '1', 07:07:16 INFO - 'PAGER': '/bin/cat', 07:07:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:07:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:16 INFO - 'PWD': '/builds/slave/test', 07:07:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:07:16 INFO - 'SHELL': '/bin/bash', 07:07:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F0jn5NqNDY/Listeners', 07:07:16 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 07:07:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:07:16 INFO - 'USER': 'cltbld', 07:07:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:07:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:07:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:16 INFO - 'XPC_FLAGS': '0x0', 07:07:16 INFO - 'XPC_SERVICE_NAME': '0', 07:07:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:07:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:16 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)) 07:07:16 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)) 07:07:16 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)) 07:07:16 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)) 07:07:16 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)) 07:07:16 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)) 07:07:16 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)) 07:07:16 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)) 07:07:16 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)) 07:07:16 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)) 07:07:16 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)) 07:07:16 INFO - Cleaning up... 07:07:17 INFO - Return code: 0 07:07:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:07:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f768490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f6de570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f96f358b650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f57cd30>, '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 0x10f3e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f574b20>, '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.2TrofkS004/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.F0jn5NqNDY/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 07:07:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:07:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:07:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2TrofkS004/Render', 07:07:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:07:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:07:17 INFO - 'HOME': '/Users/cltbld', 07:07:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:07:17 INFO - 'LOGNAME': 'cltbld', 07:07:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:17 INFO - 'MOZ_NO_REMOTE': '1', 07:07:17 INFO - 'NO_EM_RESTART': '1', 07:07:17 INFO - 'PAGER': '/bin/cat', 07:07:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:07:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:17 INFO - 'PWD': '/builds/slave/test', 07:07:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:07:17 INFO - 'SHELL': '/bin/bash', 07:07:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F0jn5NqNDY/Listeners', 07:07:17 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 07:07:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:07:17 INFO - 'USER': 'cltbld', 07:07:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:07:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:07:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:17 INFO - 'XPC_FLAGS': '0x0', 07:07:17 INFO - 'XPC_SERVICE_NAME': '0', 07:07:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:07:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:07:17 INFO - Cleaning up... 07:07:17 INFO - Return code: 0 07:07:17 INFO - Running post-action listener: _resource_record_post_action 07:07:17 INFO - Running post-action listener: _start_resource_monitoring 07:07:17 INFO - Starting resource monitoring. 07:07:17 INFO - [mozharness: 2016-12-21 15:07:17.639356Z] Finished create-virtualenv step (success) 07:07:17 INFO - [mozharness: 2016-12-21 15:07:17.640406Z] Running install step. 07:07:17 INFO - Running pre-action listener: _resource_record_pre_action 07:07:17 INFO - Running main action method: install 07:07:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:07:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:07:17 INFO - Reading from file tmpfile_stdout 07:07:17 INFO - Detecting whether we're running mozinstall >=1.0... 07:07:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:07:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:07:17 INFO - Reading from file tmpfile_stdout 07:07:17 INFO - Output received: 07:07:17 INFO - Usage: mozinstall [options] installer 07:07:17 INFO - Options: 07:07:17 INFO - -h, --help show this help message and exit 07:07:17 INFO - -d DEST, --destination=DEST 07:07:17 INFO - Directory to install application into. [default: 07:07:17 INFO - "/builds/slave/test"] 07:07:17 INFO - --app=APP Application being installed. [default: firefox] 07:07:17 INFO - mkdir: /builds/slave/test/build/application 07:07:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:07:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 07:07:49 INFO - Reading from file tmpfile_stdout 07:07:49 INFO - Output received: 07:07:49 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:07:49 INFO - Running post-action listener: _resource_record_post_action 07:07:49 INFO - [mozharness: 2016-12-21 15:07:49.450031Z] Finished install step (success) 07:07:49 INFO - [mozharness: 2016-12-21 15:07:49.450179Z] Running run-tests step. 07:07:49 INFO - Running pre-action listener: _resource_record_pre_action 07:07:49 INFO - Running main action method: run_tests 07:07:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:07:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:07:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:07:49 INFO - 2016-12-21 07:07:49.464 screenresolution[1959:10173] starting screenresolution argv=screenresolution get 07:07:49 INFO - 2016-12-21 07:07:49.467 screenresolution[1959:10173] Display 0: 1600x1200x32@60 07:07:49 INFO - 2016-12-21 07:07:49.473 screenresolution[1960:10175] starting screenresolution argv=screenresolution list 07:07:49 INFO - Available Modes on Display 0 07:07:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:07:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:07:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:07:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:07:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:07:49 INFO - Intel Iris: 07:07:49 INFO - Chipset Model: Intel Iris 07:07:49 INFO - Type: GPU 07:07:49 INFO - Bus: Built-In 07:07:49 INFO - VRAM (Dynamic, Max): 1536 MB 07:07:49 INFO - Vendor: Intel (0x8086) 07:07:49 INFO - Device ID: 0x0a2e 07:07:49 INFO - Revision ID: 0x0009 07:07:49 INFO - Displays: 07:07:49 INFO - Display: 07:07:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:07:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:07:49 INFO - Display Serial Number: 1600x1200 60 07:07:49 INFO - Main Display: Yes 07:07:49 INFO - Mirror: Off 07:07:49 INFO - Online: Yes 07:07:49 INFO - Rotation: Supported 07:07:49 INFO - Return code: 0 07:07:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:07:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:07:49 INFO - Python 2.7.3 07:07:49 INFO - Return code: 0 07:07:49 INFO - grabbing minidump binary from tooltool 07:07: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')]} 07:07:49 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 0x10f57cd30>, '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 0x10f3e2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f574b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:07:49 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 07:07:49 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 07:07:49 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 07:07:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:07:49 INFO - Return code: 0 07:07:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:07:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:07:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:07:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:07:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:07:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YpLX7sLsRsCq6G4nIOWMMg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0383', '--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 07:07:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YpLX7sLsRsCq6G4nIOWMMg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0383 --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 07:07:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2TrofkS004/Render', 07:07:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:07:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:07:49 INFO - 'HOME': '/Users/cltbld', 07:07:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:07:49 INFO - 'LOGNAME': 'cltbld', 07:07:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:07:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:07:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:49 INFO - 'MOZ_NO_REMOTE': '1', 07:07:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:07:49 INFO - 'NO_EM_RESTART': '1', 07:07:49 INFO - 'PAGER': '/bin/cat', 07:07:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:07:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:49 INFO - 'PWD': '/builds/slave/test', 07:07:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:07:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:07:49 INFO - 'SHELL': '/bin/bash', 07:07:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F0jn5NqNDY/Listeners', 07:07:49 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 07:07:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:07:49 INFO - 'USER': 'cltbld', 07:07:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:07:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:07:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:49 INFO - 'XPC_FLAGS': '0x0', 07:07:49 INFO - 'XPC_SERVICE_NAME': '0', 07:07:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:07:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YpLX7sLsRsCq6G4nIOWMMg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0383', '--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 07:07:50 INFO - mozversion application_buildid: 20161221051553 07:07:50 INFO - mozversion application_changeset: 72904e12c789881a79cf053ce6f50a0080c2791e 07:07:50 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:07:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:07:50 INFO - mozversion application_name: Firefox 07:07:50 INFO - mozversion application_remotingname: firefox-dev 07:07:50 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:07:50 INFO - mozversion application_vendor: Mozilla 07:07:50 INFO - mozversion application_version: 52.0a2 07:07:50 INFO - mozversion platform_buildid: 20161221051553 07:07:50 INFO - mozversion platform_changeset: 72904e12c789881a79cf053ce6f50a0080c2791e 07:07:50 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:07:50 INFO - mozversion platform_version: 52.0a2 07:07:50 INFO - using testdate: 1482332870 07:07:50 INFO - actual date: 1482332870 07:07:50 INFO - starting webserver on 'localhost:49342' 07:07:50 INFO - SUITE-START | Running 2 tests 07:07:50 INFO - TEST-START | dromaeo_css 07:07:50 INFO - operating with platform_type : mac_ 07:07:50 INFO - Initialising browser for dromaeo_css test... 07:07:50 INFO - TEST-INFO | started process 1970 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmp72AMfl/profile http://localhost:49342/getInfo.html) 07:07:52 INFO - PROCESS | 1970 | __metrics Screen width/height:1600/1200 07:07:52 INFO - PROCESS | 1970 | colorDepth:24 07:07:52 INFO - PROCESS | 1970 | Browser inner width/height: 1024/689 07:07:52 INFO - PROCESS | 1970 | __metrics 07:07:52 INFO - PROCESS | 1970 | 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" 07:07:52 INFO - PROCESS | 1970 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:07:53 INFO - TEST-INFO | 1970: exit 0 07:07:53 INFO - Browser initialized. 07:07:53 INFO - Running cycle 1/1 for dromaeo_css test... 07:07:53 INFO - TEST-INFO | started process 1972 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmp72AMfl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:08:05 INFO - PROCESS | 1972 | RSS: Main: 222806016 07:08:05 INFO - PROCESS | 1972 | 07:08:57 INFO - PROCESS | 1972 | 1482332937907 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] 07:11:11 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:49342/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49342/tests/dromaeo/cssquery-ext.html) 07:11:13 INFO - PROCESS | 1972 | RSS: Main: 354127872 07:11:13 INFO - PROCESS | 1972 | 07:14:15 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:49342/tests/dromaeo/cssquery-ext.html (next: http://localhost:49342/tests/dromaeo/cssquery-jquery.html) 07:14:16 INFO - PROCESS | 1972 | RSS: Main: 357584896 07:14:16 INFO - PROCESS | 1972 | 07:17:18 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:49342/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49342/tests/dromaeo/cssquery-mootools.html) 07:17:19 INFO - PROCESS | 1972 | RSS: Main: 317325312 07:17:19 INFO - PROCESS | 1972 | 07:20:21 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:49342/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49342/tests/dromaeo/cssquery-prototype.html) 07:20:22 INFO - PROCESS | 1972 | RSS: Main: 327917568 07:20:22 INFO - PROCESS | 1972 | 07:23:25 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:49342/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49342/tests/dromaeo/cssquery-yui.html) 07:23:26 INFO - PROCESS | 1972 | RSS: Main: 315695104 07:23:26 INFO - PROCESS | 1972 | 07:26:29 INFO - PROCESS | 1972 | Cycle 1(1): loaded http://localhost:49342/tests/dromaeo/cssquery-yui.html (next: http://localhost:49342/tests/dromaeo/cssquery-dojo.html) 07:26:30 INFO - PROCESS | 1972 | RSS: Main: 380002304 07:26:30 INFO - PROCESS | 1972 | 07:26:30 INFO - PROCESS | 1972 | __start_tp_report 07:26:30 INFO - PROCESS | 1972 | _x_x_mozilla_page_load 07:26:30 INFO - PROCESS | 1972 | _x_x_mozilla_page_load_details 07:26:30 INFO - PROCESS | 1972 | |i|pagename|runs| 07:26:30 INFO - PROCESS | 1972 | |0;dojo.html;3339.6994270515647;3343.364760695468;3343.6154842193146;3351.882684106056;3397.7791443556166;5711.714414279285;5775.162601422791;5804.158397032428;5807.099899515577;5809.215755872961;4673.929891051637;5761.452661997114;5780.132980052994;5780.999999999989;5786.276715410574;4595.58639722425;4984.4548190061105;5224.320838291017;5266.368035835705;5294.311739473863;4428.158649856533;5212.113940630102;5232.241325007872;5343.866403339928;5379.731013449332;5226.451222621627;5322.680639161644;5323.866903327409;5325.573954083664;5328.280682107924;5231.398389185235;5303.734813259361;5308.761105750251;5310.389305229871;5332.786688532454;5214.921776173361;5271.2620233167;5293.470652934714;5315.973420132913;5325.121354976424;5523.7238138093335;5574.219609254707;5787.13193021046;5805.941940580582;5864.179014937912;6134.540608262945;6166.7533298667995;6171.413715697002;6239.407256310643;6287.245530536319;6305.0542418637115;6327.651979141146;6357.872842543124;6369.235691716977;6373.8406539836415;5680.091185410314;5852.67810270435;5853.7365818538265;5884.0585506318785;5889.116632505073;6244.531660125509;6319.557630965788;6335.904961425582;6345.270293916166;6371.076193951943;6208.068789681584;6210.068489726577;6211.9689401552705;6239.563230573907;6249.7187626556915;6158.414950579688;6332.588381755171;6341.55609107358;6342.587731797418;6352.333005034498;5168.8449346519665;6120.903538226997;6137.60105593135;6176.629402235881;6196.845078873064;6286;6294.929861923484;6303.46420554258;6305.054241863757;6330.113784070234;4902.166631672624;4945.629077819178;4950.8267210647455;4962.354893863847;4973.079980203674;4387.780610969438;4402.493713222956;4422.84520041797;4461.263891457873;4506.549345065533;5734.569907256972;5747;5788.305403351625;5789.363170051291;5859.384764599741;6293.96853015732;6301.243850737941;6302.558820882586;6317.052442133625;6342.175517182736;5679.403662615366;5802.158686990363;5805.36141024487;5810.302763668386;5827.300723913073;5305.946940530623;5365.168398898177;5367.2485851980755;5385.838424847261;5439.129739241782;6321.399467050623;6330.841728956813;6339.175907132043;6351.110844481684;6366.77716279928;6307.90538141919;6324.778632748016;6326.9683651581445;6338.461230795434;6344.555881088279;6112.908306375319;6281.214848143982;6286.654234017173;6340.1123842661145;6363.96818015907;6130.45928571088;6151.26184857802;6172.259328880563;6219.253689557282;6284.4344009039405;5251.868703282448;6306.558540902276;6345.1434056401795;6346.397092276226;6354.269259035085;4578.5650363215445;6119.969400152971;6165.5375846810775;6171.413715697002;6189.876202476015;5968.22413086296;6068.423499767515;6087.817365479043;6113.816585502442;6125.968084364015;5995.070764031582;6050.727717252822;6056.3035250946705;6080.0575910733905;6083.66539840313;6177.7837775677635;6178.320384757763;6184.103305020837;6195.4424101831055;6197.907031394442;5741.224934633938;5865.442782935615;5872.324682661378;5944.524438045032;6031.969840150947 07:26:30 INFO - PROCESS | 1972 | |1;ext.html;511.68787039905675;512.8282025521451;514.2055524215087;518.0209404226011;531.160765989736;30097.849510752443;31796.523052154207;39583.40624890629;40866;41006.38490422646;41707;41986;42090.15819683605;42127.36808947868;42221.73334799961;9230.86153707695;9399.389039712396;9402.153806157445;9402.952985235064;9412.670556530522;9249.722508324761;9379.671711490098;9380.249580033615;9401.717948461557;9406.905930940706;9387.43675379476;9434.245260379153;9444.433333999981;9446.622135114587;9472.289578281621;9331.953340233289;9360.625574976993;9380.296477764126;9389.483578403186;9415.764605884804;9235.445873247627;9327.533623318806;9330.300227482965;9335.626574936994;9365.906340936572;38890.416643750366;39244.80377598122;39253;39292.21415571701;39295.803520982496;3233.304839459504;3309.7352211823236;3328.8169150696704;3433.853841349157;3520.2607452434763;27919.022834200707;29032.12903612895;29846.70153298461;30583.694163058306;30587.694123058707;3131.123285480069;3166.477531207348;3170.6036745406823;3180.189051791778;3187.4900015997328;465376;466533.9999999966;467306;468112;476304;32184.517232241535;57704.422955770744;58059.70970145207;58062.4193758057;58119.709401452725;33494.665053349156;34558.30883382318;34591.13522161966;34627.65372346294;34635;14265.072770270106;14500.637484062983;14505.347259373353;14507;14513.419463221564;11769.941150294193;12247.387630618434;12321.260724356389;12322.753544929052;12326.260424374566;7603.619819009138;7688.192739762357;7721.305082542598;7806.1413244543055;7811.68753249875;3190.5692731480954;3262.2333751568362;3263.4125857345907;3274.1978215337394;3312.2878581104696;811.9147489513634;946.8153710026479;999.3004896572428;1049.506731836036;1062.7678646808542;22512.437189070075;29016.129516114128;29131.7086829129;29815.956441524875;30159;1013.4780587997187;1017.0388982411698;1037.1288117980932;1071.3197119828808;1100.763335882773;14737.926310368379;15201.239938003055;15241.923790380977;15313.46402875894;15588.986715863433;592332;595385.0230748819;595478;597212;598414;57506;58079.83840323254;58147.70926145342;58201;58247;1152.475623591264;1237.616338934933;1279.2452453052745;1282.454956643404;1282.923024618526;23336.416589584718;30623.877021361906;31107;31270.374592508477;31800;52604.736976314874;53260.73369633127;53343;53375.733121334146;53420;505526;507157;509649;512082;512521;26056.088036918616;26562.86718566395;26907.596386053832;26929.596056059567;26987.595186072616;46168.30747538857;46305;46472.070558589316;46536.53463465322;46732;38653.613463866124;39091.6090839088;39869.800650996556;40544.186418322875;41132;37717.434238486996;37812.81093594514;39067.6093239064;39152.608473914894;39510;36135.27729445449;36888.07779805527;37278;38247.42628860625;38406.423903640905;30703.385932281675;34016.82991585026;34913.9525814226;34919.476207856394;35139;20569.98769129549;24276;25395.61906571366;25428.49143017092;26050.73949260559 07:26:30 INFO - PROCESS | 1972 | |2;jquery.html;350.3815765174466;363.42034455044205;375.19146239853126;381.3440881683504;401.1455599572909;14871.107733535982;15142;15653.686926261467;19891.502712432197;20750.48123796906;19981;20045.1981920723;20515;20654.863982480958;20811.583768324625;4742.027884283713;5833.854153646172;6103.054026625858;6103.328633850273;6110.633361998272;5941.732622031997;5985.461308482258;6066.332703402622;6072.848178795543;6081.179040829497;5672.546196304285;5876.5298776098025;5914.645121292715;5982.222311099551;6016.548758843082;5953.196318496991;5989.88020239595;5993.4006599340155;6001.279846418414;6007.8197654070445;4999.150144475448;5176.378834539842;5185.974070129663;5235.240890070919;5258.947410525922;22251;23213.419664508354;23492.060317587275;23660.881695591583;23724.05103795846;2168.544605632819;2273.2498275569023;2306.8039216666602;2406.6630671705975;2888.9133326000256;9889.851652225223;10388.636397726043;10559;10647.307924984929;14928.62678433048;1811.7644706188112;1824.2520566567775;1899.2403038784596;1904.628597423503;1935.7870634230223;102796;109603;115386.42306788413;115743;115889.4205528967;11396.601118960796;11450.541978320774;11569.595064172714;11674.649760507198;11689.707757306136;14807.925960370128;14810;14818.184999824898;14825.92587037058;14866.554003379915;8252.669893204325;8386.454880432751;8400.915990840136;8423.789405264917;8546.743597692079;6718.294579069225;7145.928540714526;8154.103048664642;8308.501489910625;8325.875111873327;4473.463184417826;4520.909581808345;4538.455385353779;4616.16908956391;4617.12274667812;1581.936722531109;1680.3950577791986;1855.3877220517197;1856.4616261284345;1915.2817693364989;872.0886673426285;952.8618350339162;975.3952549419405;999.160705007797;1001.3891526169031;19781.505462363555;21252.468688282916;21323.253686120912;21395.572088558452;21619.35141945744;908.2779190543401;912.7854954085784;937.3110763588757;958.5830163878735;994.8109859126743;10358.689239322832;10593.152547796159;11122.38826864514;11144.2199046066;11185.552577896793;494974.52512737206;576832;580831;581165;594613.0269348626;70523.64738176379;71931.2806871935;90439;113672;115564.42217788857;946.4794363100267;965.0060437748989;985.1380042462845;1010.5604062232952;1012.5544760305442;14739.705205895607;18488.537786555444;19084.805247396725;19763.421655250426;19898.005099744667;51462.970740585726;51651.48348516467;52365;52916.73541632421;53481;69402.65298673474;74813.62593187217;75707;77996.61001694955;83965;5417.187421886749;6035.305939816802;7504.699812007459;7903.644336004779;8263;11989.820152697543;12432.191907525981;12432.564860229848;12725.045621578234;12738.872611274139;10842.241043126905;10969.890301097206;11176.329420234812;11523.769524609294;11557.42212889369;10678.733031673959;10767;10918.50866710984;10939.124870010539;11293.887061129284;11179.832302515306;11288.830667539829;11485.483153257956;11536.192466527262;11537.538498459968;8076;8228.50628962264;8332.583370831555;8351.498910065415;8353.665853365797;10885.836712449478;11003.33979961205;11120.893332338466;11204.607838725606;11409.942950285196 07:26:30 INFO - PROCESS | 1972 | |3;mootools.html;1390.9234992075435;1437.7052704195642;1584.3345794766196;1782.4296225207922;1817.9181936812843;8058.234467725571;8329.375296852737;8342.124076971922;8344.916550834498;8540.530270835101;7524.2851929066655;7715.305622493975;8145.226203510671;8283.420160588761;8371.497710137395;2300.631898896177;2336.1823361823313;2339.3449834046496;2339.4034521197154;2341.95316093678;2320.9651855222187;2353.870537120458;2371.5256948610263;2377.809775217987;2380.0836677879056;2267.1611503743557;2270.0806173499677;2300.4363930836967;2301.1485750272423;2301.9079236830507;2292.071710957065;2307.284741730069;2323.8024767894663;2329.5340931813535;2332.54515369503;2336.5326934612976;2347.94130146746;2353.85876847388;2357.563850687611;2359.2332491940015;8247.381446391484;8323.292520135796;8339.249467547888;8353.874691879626;8427.073021967579;5032.924506132411;5172.301739265189;5178.792848286102;5189.091908915962;5195.636305458619;3251.8536665850093;3393.2025973896116;3492.0746002309406;3567.571891373026;3584.766990145632;1049.1759217485658;1054.6150655010943;1074.607768164622;1080.8540846985634;1093.240198062347;10763.892361076367;10862.131029517699;10931.344119352785;10958.452077396178;10961.397123158224;4480.731156130642;4679.344891715163;4759.119562880878;4792.616590672737;4804.399450068741;9340.626374945063;9341.673041443517;9356.485393303297;9363.812723745623;9364.344495885223;5183.092958732207;5195.116830138885;5197.480251974848;5197.532222100029;5199.4540573239265;3429.9828500857334;4393.51671316155;4450.777461126931;4669.766511674403;4739.4075740532435;3154.9053528394184;3776.7922764247683;3898.571157172709;3903.9219215616095;3910.687145028391;1252.7745005898844;1257.3964497041472;1261.3062815451465;1272.2125413793635;1276.1768659214754;715.8317795318096;793.0483419896033;823.3577809308749;901.8286525560122;906.211595911548;5487.533559647395;5572.359178694421;5674.4892959633835;5748.540116790647;5858;726.3426598927979;894.4901406198535;922.1516205091198;944.2210176604328;960.4813400763649;3252.6747325267756;3282.7373810095137;3794.4498047782918;3825.196708691206;3937.4290727844873;9898.158656514133;10121.645742399129;10224.233182511192;10248.948755256177;10259.9487002566;9458;9947.850782238273;10026.598936042623;10098.394096354243;10103.797924041624;7634.847303054019;7849.646765895434;7854.018247719036;7863.52818830872;7869.645865935932;5631.380548139783;5714.885702286014;5717.428257174416;5731.226284451545;5898.174255604133;9254.35219534626;9357.39176953496;9387.953060234655;9398;9404.247660187306;9037.728868133967;9588.424694518351;9697.6120955161;9700.5149742514;9713.46575938302;6625.469962403093;6841.3842754152365;6866.519343645897;7235.095613048369;7390.334869861738;7709.383249340151;7835.960820195863;8005.199480051948;8051.194880511902;8077.83844323122;7089.893651595127;7200.243974382824;7226.602536860523;7258.963705181441;7316.048913641405;6930.27232140618;6976.407005404394;6992.965035174791;7119.323664251888;7120.715171393086;5534.252875861707;6635.402813746786;6687.331266873273;6705.0612914193025;6767.695453704497;4770.689905156154;4825.276208568743;4839.322494850653;4881.414230292387;4926.729029903389;4808.326834243138;4843.782029808737;4918.680285781413;4972.055309491185;4979.875503112451 07:26:30 INFO - PROCESS | 1972 | |4;prototype.html;283.24232677587446;295.09111935239457;299.2518703241894;308.84866415456435;313.9450596145674;5009.949900500994;5338.67967921925;5347.491988261109;5352.946470535294;5398.380485854243;4170.353595192742;5131.666441681288;5229.3201883755155;5291.6031297652735;5329.493698098684;3598.280343931211;3889.6888248940018;4080.5307389650125;4083.9387409188885;4096.733712308705;3959.307121253769;4100.917981640366;4104.281750693632;4114.321137012389;4117.644120042156;4042.393640953851;4044.5237488316698;4045.1190196558596;4068.9186216275657;4074.8166332515107;3015.638123425192;4029.395590661395;4040.5555388907196;4046.2312160689376;4047.045656670632;3320.6015278166774;3431.605365382988;3441.7074548663395;3546.6098729139776;3566.661167189113;3866.7293289469467;5071.8985620287385;5122.644667742635;5128.230765385185;5130.07658621448;3975.980120099381;3997.780676893546;4531.637469002505;4547.499775024745;4562.817487300504;4802.4237091548885;5114.923276150861;5180;5285.810692594174;5414.94408590325;3202.727768139711;3422.828858557062;3425.1950791563963;3744.8578183653976;3751.6998640108727;4879.926801097987;5055.797768089272;5730.025895597759;5788.739506722165;5867.589268751189;3153.7003984621424;3717.9070523237137;3846.2692088503095;3847.825978322239;3878.5733569307354;3687.2625474905126;3700.4634328022285;3907.5506316773367;3913.8845429052585;3934.206713233636;3790.3935370341164;4475.261581839025;4544.454665440168;4551.362809206714;4604.907901842012;5532.585056120726;5615.213870058195;5618.494335509742;5639.943600564024;5650.796189486104;1155.4280631087497;1194.2834299420279;1217.8721234270452;1268.8160216768702;1276.8020956751718;701.9649017549102;737.7712908998228;841.3395484544603;853.2448782827119;858.8668756342777;4252.574742525722;4542.432195975503;4837.564619184291;4866.6106711463;4945.505449455098;4745.810167593327;4763.595094416944;5129.281900533928;5346.705931173745;5364.892702145935;3919.8236079376497;4814.759262036884;4840.467548569654;4856.635752318591;4896.608271338285;2459.6187590923437;2467.260581290186;2556.8394706473;2636.934076648099;2828.7454129128064;4704.623630109583;5433.728313584305;5457.244091733649;5495.972520137374;5514.531264842453;5400.110940646735;5511.5039646432015;5553.611247212656;5567;5586.91619625706;5456;5487.582496090686;5510.311211098532;5520.337559492887;5576.737608498993;5202.895942081062;5337.653052551572;5345.1714984177515;5345.487620818365;5440.809571664972;5150.841060761299;5290.65610735301;5301.469853014668;5336.653117547347;5441.5102640762525;4337.967957169794;4590.563896429834;5468.48049435303;5502.504774570308;5561.304836895388;3062.9693703062685;3205.6634053424054;3556.395412779885;3566.465030245484;3595.3708101081684;4678.76606169697;4840.651080556076;5427.782888684408;5607.009511623315;5681.335538577677;4532.501424843199;4613.446386433515;4777.904441911072;5502.862428439321;5605.523530499953;4678.222275535275;5249.448807875118;5487.241239750783;5488.286522752017;5554.333479982428;4664.813407463664;4787.904241915212;5192.818251361185;5430.7013114277515;5464.9453505464435;4710.081534100817;4839.395075615548;5447.019536483471;5565.276514053147;5614.512154279201;4544.863654090383;5195.038289725566;5320.574354051744;5435.347758269033;5513.078068623636 07:26:30 INFO - PROCESS | 1972 | |5;yui.html;698.591324075416;746.2910235276486;765.3035737478896;781.7928249014012;787.5274835098945;11368.943155284232;15868.682626347467;16524.752128718064;16614.41849535265;17220.655586888253;15059.77410338843;15135.848641513609;15160.014599051054;15207.771883421758;15213.61965950849;645.4158986117571;665.6239224837975;806.6450761664863;831.4470876866907;832.8750687396879;670.587588632992;798.56079156464;814.7148498025703;820.9261166495014;830.2030051150903;873.2490058549643;876.1676407412951;876.3208513402101;877.1404024056428;878.964841406343;868.2489646455833;869.9304055675531;869.9608517616722;870.9346798990038;871.2942516561574;498.9865891129312;512.183068006529;618.1191801682585;630.224823467138;638.0174531221912;14301.070430422093;14302.213378264192;14330.64173395663;14342.42630294787;14389.208593527352;12463.75072498545;12652.240865548096;12723.10938234324;12755.234685918875;12758.425870835834;10738.570457181704;11346.546138154465;11348.205625606211;11961.880381196164;12351.876481235162;3625.665424705941;3638.2541578976247;3677.852885884588;3748.6813620842263;3769.453429252743;85973.57013214893;88672;89378;92438.53780731054;92673.53663231707;10354.948225258977;10405.918816236783;10407.271490995709;10463.68608941733;10465.162786977024;19496.80503194978;19580.70628940567;19585.510362241057;19922.90038549798;20130.496737581678;7231.240719724353;7409.444291678038;7506.324430801144;7732.226777322336;7747.38020958322;4173.707840451139;4343.370211319341;4361.454818147731;4391.14372697321;4512.887177820515;3369.898903032913;3483.1466290758926;3486.1284678830293;3488.965110348864;3489.284696637183;545.3210752613022;632.8101569529123;638.5466318913624;659.6839305585388;668.6589839182052;533.4851867947449;639.7345101782709;676.5331920974512;684.4387602166275;685.4550632247375;8867.467951922905;9030.36787424887;9178.35751497402;9195.632174712937;9251.722448326562;637.2894222941387;656.8226578823691;674.6356967237734;678.9966050169817;679.0934102972587;4306.849260275938;4479.46246450428;4536.523665015192;4543.727376357429;4561.400011959042;66782.66434671279;73839;77966;88792.5560372194;92368.53815731013;42442.57557424448;53225.7338713304;61234.0814887777;81440.55678329659;89279.55360223158;66504.6674766623;66774.66612666905;66916;68514.65742671354;84850.57574712086;9287.257019438293;9741.318107732392;9742.220622350053;9835.213182945205;9851.950740246253;48584.757076214395;48927.30899800545;51316;53991;54833.451665484434;68376.89738615698;74832.62583687264;78211;78750.6062469684;79766.60116699612;11988.460519276829;12013.519459221534;12022.458989345676;12282.570110046105;12326.445309961253;12970.535598236305;13408.32958352059;13816.93091534536;14129.319701257464;14533.200673963034;10266.435346056038;12110.939445302716;12890.226586404846;12966.675833104247;13425.86574134246;12872.292023938773;13018.934905325414;13162.80255796183;13192.340382980621;13857.584272471842;12571.18287311322;12606.621801345975;12707.745845082862;12929.54746583865;13413.32933353348;10818.404987725751;10890.29213101146;11489;11525.135614829089;11565.016973557005;8955.820883582423;10043.096121349114;10369.63706270277;10947.397893115955;11066.802070691 07:26:30 INFO - PROCESS | 1972 | __end_tp_report 07:26:30 INFO - PROCESS | 1972 | __start_cc_report 07:26:30 INFO - PROCESS | 1972 | _x_x_mozilla_cycle_collect,1519 07:26:30 INFO - PROCESS | 1972 | __end_cc_report 07:26:30 INFO - PROCESS | 1972 | __startTimestamp1482333990021__endTimestamp 07:26:30 INFO - PROCESS | 1972 | 07:26:30 INFO - PROCESS | 1972 | ------- Summary: start ------- 07:26:30 INFO - PROCESS | 1972 | Number of tests: 6 07:26:30 INFO - PROCESS | 1972 | 07:26:30 INFO - PROCESS | 1972 | [#0] dojo.html Cycles:165 Average:5755.25 Median:5968.22 stddev:671.29 (11.2%) stddev-sans-first:646.05 07:26:30 INFO - PROCESS | 1972 | Values: 3339.7 3343.4 3343.6 3351.9 3397.8 5711.7 5775.2 5804.2 5807.1 5809.2 4673.9 5761.5 5780.1 5781.0 5786.3 4595.6 4984.5 5224.3 5266.4 5294.3 4428.2 5212.1 5232.2 5343.9 5379.7 5226.5 5322.7 5323.9 5325.6 5328.3 5231.4 5303.7 5308.8 5310.4 5332.8 5214.9 5271.3 5293.5 5316.0 5325.1 5523.7 5574.2 5787.1 5805.9 5864.2 6134.5 6166.8 6171.4 6239.4 6287.2 6305.1 6327.7 6357.9 6369.2 6373.8 5680.1 5852.7 5853.7 5884.1 5889.1 6244.5 6319.6 6335.9 6345.3 6371.1 6208.1 6210.1 6212.0 6239.6 6249.7 6158.4 6332.6 6341.6 6342.6 6352.3 5168.8 6120.9 6137.6 6176.6 6196.8 6286.0 6294.9 6303.5 6305.1 6330.1 4902.2 4945.6 4950.8 4962.4 4973.1 4387.8 4402.5 4422.8 4461.3 4506.5 5734.6 5747.0 5788.3 5789.4 5859.4 6294.0 6301.2 6302.6 6317.1 6342.2 5679.4 5802.2 5805.4 5810.3 5827.3 5305.9 5365.2 5367.2 5385.8 5439.1 6321.4 6330.8 6339.2 6351.1 6366.8 6307.9 6324.8 6327.0 6338.5 6344.6 6112.9 6281.2 6286.7 6340.1 6364.0 6130.5 6151.3 6172.3 6219.3 6284.4 5251.9 6306.6 6345.1 6346.4 6354.3 4578.6 6120.0 6165.5 6171.4 6189.9 5968.2 6068.4 6087.8 6113.8 6126.0 5995.1 6050.7 6056.3 6080.1 6083.7 6177.8 6178.3 6184.1 6195.4 6197.9 5741.2 5865.4 5872.3 5944.5 6032.0 07:26:30 INFO - PROCESS | 1972 | 07:26:30 INFO - PROCESS | 1972 | [#1] ext.html Cycles:180 Average:64858.92 Median:39122.11 stddev:140871.21 (360.1%) stddev-sans-first:141183.54 07:26:30 INFO - PROCESS | 1972 | Values: 511.7 512.8 514.2 518.0 531.2 30097.8 31796.5 39583.4 40866.0 41006.4 41707.0 41986.0 42090.2 42127.4 42221.7 9230.9 9399.4 9402.2 9403.0 9412.7 9249.7 9379.7 9380.2 9401.7 9406.9 9387.4 9434.2 9444.4 9446.6 9472.3 9332.0 9360.6 9380.3 9389.5 9415.8 9235.4 9327.5 9330.3 9335.6 9365.9 38890.4 39244.8 39253.0 39292.2 39295.8 3233.3 3309.7 3328.8 3433.9 3520.3 27919.0 29032.1 29846.7 30583.7 30587.7 3131.1 3166.5 3170.6 3180.2 3187.5 465376.0 466534.0 467306.0 468112.0 476304.0 32184.5 57704.4 58059.7 58062.4 58119.7 33494.7 34558.3 34591.1 34627.7 34635.0 14265.1 14500.6 14505.3 14507.0 14513.4 11769.9 12247.4 12321.3 12322.8 12326.3 7603.6 7688.2 7721.3 7806.1 7811.7 3190.6 3262.2 3263.4 3274.2 3312.3 811.9 946.8 999.3 1049.5 1062.8 22512.4 29016.1 29131.7 29816.0 30159.0 1013.5 1017.0 1037.1 1071.3 1100.8 14737.9 15201.2 15241.9 15313.5 15589.0 592332.0 595385.0 595478.0 597212.0 598414.0 57506.0 58079.8 58147.7 58201.0 58247.0 1152.5 1237.6 1279.2 1282.5 1282.9 23336.4 30623.9 31107.0 31270.4 31800.0 52604.7 53260.7 53343.0 53375.7 53420.0 505526.0 507157.0 509649.0 512082.0 512521.0 26056.1 26562.9 26907.6 26929.6 26987.6 46168.3 46305.0 46472.1 46536.5 46732.0 38653.6 39091.6 39869.8 40544.2 41132.0 37717.4 37812.8 39067.6 39152.6 39510.0 36135.3 36888.1 37278.0 38247.4 38406.4 30703.4 34016.8 34914.0 34919.5 35139.0 20570.0 24276.0 25395.6 25428.5 26050.7 07:26:30 INFO - PROCESS | 1972 | 07:26:30 INFO - PROCESS | 1972 | [#2] jquery.html Cycles:180 Average:32749.49 Median:23692.47 stddev:94024.84 (396.9%) stddev-sans-first:94257.13 07:26:30 INFO - PROCESS | 1972 | Values: 350.4 363.4 375.2 381.3 401.1 14871.1 15142.0 15653.7 19891.5 20750.5 19981.0 20045.2 20515.0 20654.9 20811.6 4742.0 5833.9 6103.1 6103.3 6110.6 5941.7 5985.5 6066.3 6072.8 6081.2 5672.5 5876.5 5914.6 5982.2 6016.5 5953.2 5989.9 5993.4 6001.3 6007.8 4999.2 5176.4 5186.0 5235.2 5258.9 22251.0 23213.4 23492.1 23660.9 23724.1 2168.5 2273.2 2306.8 2406.7 2888.9 9889.9 10388.6 10559.0 10647.3 14928.6 1811.8 1824.3 1899.2 1904.6 1935.8 102796.0 109603.0 115386.4 115743.0 115889.4 11396.6 11450.5 11569.6 11674.6 11689.7 14807.9 14810.0 14818.2 14825.9 14866.6 8252.7 8386.5 8400.9 8423.8 8546.7 6718.3 7145.9 8154.1 8308.5 8325.9 4473.5 4520.9 4538.5 4616.2 4617.1 1581.9 1680.4 1855.4 1856.5 1915.3 872.1 952.9 975.4 999.2 1001.4 19781.5 21252.5 21323.3 21395.6 21619.4 908.3 912.8 937.3 958.6 994.8 10358.7 10593.2 11122.4 11144.2 11185.6 494974.5 576832.0 580831.0 581165.0 594613.0 70523.6 71931.3 90439.0 113672.0 115564.4 946.5 965.0 985.1 1010.6 1012.6 14739.7 18488.5 19084.8 19763.4 19898.0 51463.0 51651.5 52365.0 52916.7 53481.0 69402.7 74813.6 75707.0 77996.6 83965.0 5417.2 6035.3 7504.7 7903.6 8263.0 11989.8 12432.2 12432.6 12725.0 12738.9 10842.2 10969.9 11176.3 11523.8 11557.4 10678.7 10767.0 10918.5 10939.1 11293.9 11179.8 11288.8 11485.5 11536.2 11537.5 8076.0 8228.5 8332.6 8351.5 8353.7 10885.8 11003.3 11120.9 11204.6 11409.9 07:26:30 INFO - PROCESS | 1972 | 07:26:30 INFO - PROCESS | 1972 | [#3] mootools.html Cycles:180 Average:5444.85 Median:5186.09 stddev:2987.60 (57.6%) stddev-sans-first:2980.45 07:26:30 INFO - PROCESS | 1972 | Values: 1390.9 1437.7 1584.3 1782.4 1817.9 8058.2 8329.4 8342.1 8344.9 8540.5 7524.3 7715.3 8145.2 8283.4 8371.5 2300.6 2336.2 2339.3 2339.4 2342.0 2321.0 2353.9 2371.5 2377.8 2380.1 2267.2 2270.1 2300.4 2301.1 2301.9 2292.1 2307.3 2323.8 2329.5 2332.5 2336.5 2347.9 2353.9 2357.6 2359.2 8247.4 8323.3 8339.2 8353.9 8427.1 5032.9 5172.3 5178.8 5189.1 5195.6 3251.9 3393.2 3492.1 3567.6 3584.8 1049.2 1054.6 1074.6 1080.9 1093.2 10763.9 10862.1 10931.3 10958.5 10961.4 4480.7 4679.3 4759.1 4792.6 4804.4 9340.6 9341.7 9356.5 9363.8 9364.3 5183.1 5195.1 5197.5 5197.5 5199.5 3430.0 4393.5 4450.8 4669.8 4739.4 3154.9 3776.8 3898.6 3903.9 3910.7 1252.8 1257.4 1261.3 1272.2 1276.2 715.8 793.0 823.4 901.8 906.2 5487.5 5572.4 5674.5 5748.5 5858.0 726.3 894.5 922.2 944.2 960.5 3252.7 3282.7 3794.4 3825.2 3937.4 9898.2 10121.6 10224.2 10248.9 10259.9 9458.0 9947.9 10026.6 10098.4 10103.8 7634.8 7849.6 7854.0 7863.5 7869.6 5631.4 5714.9 5717.4 5731.2 5898.2 9254.4 9357.4 9388.0 9398.0 9404.2 9037.7 9588.4 9697.6 9700.5 9713.5 6625.5 6841.4 6866.5 7235.1 7390.3 7709.4 7836.0 8005.2 8051.2 8077.8 7089.9 7200.2 7226.6 7259.0 7316.0 6930.3 6976.4 6993.0 7119.3 7120.7 5534.3 6635.4 6687.3 6705.1 6767.7 4770.7 4825.3 4839.3 4881.4 4926.7 4808.3 4843.8 4918.7 4972.1 4979.9 07:26:30 INFO - PROCESS | 1972 | 07:26:30 INFO - PROCESS | 1972 | [#4] prototype.html Cycles:180 Average:4345.94 Median:4826.16 stddev:1342.34 (27.8%) stddev-sans-first:1311.01 07:26:30 INFO - PROCESS | 1972 | Values: 283.2 295.1 299.3 308.8 313.9 5009.9 5338.7 5347.5 5352.9 5398.4 4170.4 5131.7 5229.3 5291.6 5329.5 3598.3 3889.7 4080.5 4083.9 4096.7 3959.3 4100.9 4104.3 4114.3 4117.6 4042.4 4044.5 4045.1 4068.9 4074.8 3015.6 4029.4 4040.6 4046.2 4047.0 3320.6 3431.6 3441.7 3546.6 3566.7 3866.7 5071.9 5122.6 5128.2 5130.1 3976.0 3997.8 4531.6 4547.5 4562.8 4802.4 5114.9 5180.0 5285.8 5414.9 3202.7 3422.8 3425.2 3744.9 3751.7 4879.9 5055.8 5730.0 5788.7 5867.6 3153.7 3717.9 3846.3 3847.8 3878.6 3687.3 3700.5 3907.6 3913.9 3934.2 3790.4 4475.3 4544.5 4551.4 4604.9 5532.6 5615.2 5618.5 5639.9 5650.8 1155.4 1194.3 1217.9 1268.8 1276.8 702.0 737.8 841.3 853.2 858.9 4252.6 4542.4 4837.6 4866.6 4945.5 4745.8 4763.6 5129.3 5346.7 5364.9 3919.8 4814.8 4840.5 4856.6 4896.6 2459.6 2467.3 2556.8 2636.9 2828.7 4704.6 5433.7 5457.2 5496.0 5514.5 5400.1 5511.5 5553.6 5567.0 5586.9 5456.0 5487.6 5510.3 5520.3 5576.7 5202.9 5337.7 5345.2 5345.5 5440.8 5150.8 5290.7 5301.5 5336.7 5441.5 4338.0 4590.6 5468.5 5502.5 5561.3 3063.0 3205.7 3556.4 3566.5 3595.4 4678.8 4840.7 5427.8 5607.0 5681.3 4532.5 4613.4 4777.9 5502.9 5605.5 4678.2 5249.4 5487.2 5488.3 5554.3 4664.8 4787.9 5192.8 5430.7 5464.9 4710.1 4839.4 5447.0 5565.3 5614.5 4544.9 5195.0 5320.6 5435.3 5513.1 07:26:30 INFO - PROCESS | 1972 | 07:26:30 INFO - PROCESS | 1972 | [#5] yui.html Cycles:180 Average:18545.44 Median:48756.03 stddev:25410.63 (52.1%) stddev-sans-first:25446.57 07:26:30 INFO - PROCESS | 1972 | Values: 698.6 746.3 765.3 781.8 787.5 11368.9 15868.7 16524.8 16614.4 17220.7 15059.8 15135.8 15160.0 15207.8 15213.6 645.4 665.6 806.6 831.4 832.9 670.6 798.6 814.7 820.9 830.2 873.2 876.2 876.3 877.1 879.0 868.2 869.9 870.0 870.9 871.3 499.0 512.2 618.1 630.2 638.0 14301.1 14302.2 14330.6 14342.4 14389.2 12463.8 12652.2 12723.1 12755.2 12758.4 10738.6 11346.5 11348.2 11961.9 12351.9 3625.7 3638.3 3677.9 3748.7 3769.5 85973.6 88672.0 89378.0 92438.5 92673.5 10354.9 10405.9 10407.3 10463.7 10465.2 19496.8 19580.7 19585.5 19922.9 20130.5 7231.2 7409.4 7506.3 7732.2 7747.4 4173.7 4343.4 4361.5 4391.1 4512.9 3369.9 3483.1 3486.1 3489.0 3489.3 545.3 632.8 638.5 659.7 668.7 533.5 639.7 676.5 684.4 685.5 8867.5 9030.4 9178.4 9195.6 9251.7 637.3 656.8 674.6 679.0 679.1 4306.8 4479.5 4536.5 4543.7 4561.4 66782.7 73839.0 77966.0 88792.6 92368.5 42442.6 53225.7 61234.1 81440.6 89279.6 66504.7 66774.7 66916.0 68514.7 84850.6 9287.3 9741.3 9742.2 9835.2 9852.0 48584.8 48927.3 51316.0 53991.0 54833.5 68376.9 74832.6 78211.0 78750.6 79766.6 11988.5 12013.5 12022.5 12282.6 12326.4 12970.5 13408.3 13816.9 14129.3 14533.2 10266.4 12110.9 12890.2 12966.7 13425.9 12872.3 13018.9 13162.8 13192.3 13857.6 12571.2 12606.6 12707.7 12929.5 13413.3 10818.4 10890.3 11489.0 11525.1 11565.0 8955.8 10043.1 10369.6 10947.4 11066.8 07:26:30 INFO - PROCESS | 1972 | -------- Summary: end -------- 07:26:30 INFO - PROCESS | 1972 | 07:26:30 INFO - TEST-INFO | 1972: exit 0 07:26:30 INFO - TEST-OK | dromaeo_css | took 1120617ms 07:26:30 INFO - TEST-START | kraken 07:26:30 INFO - operating with platform_type : mac_ 07:26:30 INFO - Initialising browser for kraken test... 07:26:30 INFO - TEST-INFO | started process 1975 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmpDJCAtC/profile http://localhost:49342/getInfo.html) 07:26:33 INFO - PROCESS | 1975 | __metrics Screen width/height:1600/1200 07:26:33 INFO - PROCESS | 1975 | colorDepth:24 07:26:33 INFO - PROCESS | 1975 | Browser inner width/height: 1024/689 07:26:33 INFO - PROCESS | 1975 | __metrics 07:26:33 INFO - PROCESS | 1975 | 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" 07:26:33 INFO - PROCESS | 1975 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:26:34 INFO - TEST-INFO | 1975: exit 0 07:26:34 INFO - Browser initialized. 07:26:34 INFO - Running cycle 1/1 for kraken test... 07:26:34 INFO - TEST-INFO | started process 1976 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmpDJCAtC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:26:46 INFO - PROCESS | 1976 | RSS: Main: 222883840 07:26:46 INFO - PROCESS | 1976 | 07:26:49 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49342/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49342/tests/kraken/driver.html?testName=audio-beat-detection) 07:26:50 INFO - PROCESS | 1976 | RSS: Main: 272998400 07:26:50 INFO - PROCESS | 1976 | 07:26:52 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49342/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49342/tests/kraken/driver.html?testName=audio-dft) 07:26:53 INFO - PROCESS | 1976 | RSS: Main: 283279360 07:26:53 INFO - PROCESS | 1976 | 07:26:56 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49342/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49342/tests/kraken/driver.html?testName=audio-fft) 07:26:57 INFO - PROCESS | 1976 | RSS: Main: 380878848 07:26:57 INFO - PROCESS | 1976 | 07:26:59 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49342/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49342/tests/kraken/driver.html?testName=audio-oscillator) 07:26:59 INFO - PROCESS | 1976 | RSS: Main: 282910720 07:26:59 INFO - PROCESS | 1976 | 07:27:01 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49342/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49342/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:27:02 INFO - PROCESS | 1976 | RSS: Main: 283684864 07:27:02 INFO - PROCESS | 1976 | 07:27:05 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49342/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49342/tests/kraken/driver.html?testName=imaging-darkroom) 07:27:05 INFO - PROCESS | 1976 | RSS: Main: 323575808 07:27:05 INFO - PROCESS | 1976 | 07:27:08 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49342/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49342/tests/kraken/driver.html?testName=imaging-desaturate) 07:27:09 INFO - PROCESS | 1976 | RSS: Main: 326631424 07:27:09 INFO - PROCESS | 1976 | 07:27:12 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49342/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49342/tests/kraken/driver.html?testName=json-parse-financial) 07:27:12 INFO - PROCESS | 1976 | RSS: Main: 322088960 07:27:12 INFO - PROCESS | 1976 | 07:27:14 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49342/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49342/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:27:15 INFO - PROCESS | 1976 | RSS: Main: 299810816 07:27:15 INFO - PROCESS | 1976 | 07:27:17 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49342/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49342/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:27:17 INFO - PROCESS | 1976 | RSS: Main: 300699648 07:27:17 INFO - PROCESS | 1976 | 07:27:19 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49342/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49342/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:27:20 INFO - PROCESS | 1976 | RSS: Main: 305635328 07:27:20 INFO - PROCESS | 1976 | 07:27:23 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49342/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49342/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:27:24 INFO - PROCESS | 1976 | RSS: Main: 319746048 07:27:24 INFO - PROCESS | 1976 | 07:27:26 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49342/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49342/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:27:27 INFO - PROCESS | 1976 | RSS: Main: 313155584 07:27:27 INFO - PROCESS | 1976 | 07:27:29 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49342/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49342/tests/kraken/driver.html?testName=ai-astar) 07:27:29 INFO - PROCESS | 1976 | RSS: Main: 357543936 07:27:29 INFO - PROCESS | 1976 | 07:27:29 INFO - PROCESS | 1976 | __start_tp_report 07:27:29 INFO - PROCESS | 1976 | _x_x_mozilla_page_load 07:27:29 INFO - PROCESS | 1976 | _x_x_mozilla_page_load_details 07:27:29 INFO - PROCESS | 1976 | |i|pagename|runs| 07:27:29 INFO - PROCESS | 1976 | |0;ai-astar;152;169;115;114;115;116;116;115;114;115 07:27:29 INFO - PROCESS | 1976 | |1;audio-beat-detection;175;139;112;143;128;141;113;107;151;141 07:27:29 INFO - PROCESS | 1976 | |2;audio-dft;184;183;182;185;182;185;185;186;182;183 07:27:29 INFO - PROCESS | 1976 | |3;audio-fft;81;58;59;89;59;59;61;58;83;57 07:27:29 INFO - PROCESS | 1976 | |4;audio-oscillator;86;72;96;71;68;68;90;68;68;91 07:27:29 INFO - PROCESS | 1976 | |5;imaging-gaussian-blur;87;85;88;86;90;124;88;85;88;85 07:27:29 INFO - PROCESS | 1976 | |6;imaging-darkroom;93;112;151;151;94;95;95;95;95;94 07:27:29 INFO - PROCESS | 1976 | |7;imaging-desaturate;84;80;80;82;81;84;88;83;83;83 07:27:29 INFO - PROCESS | 1976 | |8;json-parse-financial;54;55;54;54;58;58;59;58;59;56 07:27:29 INFO - PROCESS | 1976 | |9;json-stringify-tinderbox;72;69;78;74;73;69;71;71;70;71 07:27:29 INFO - PROCESS | 1976 | |10;stanford-crypto-aes;84;84;83;84;83;82;81;83;83;85 07:27:29 INFO - PROCESS | 1976 | |11;stanford-crypto-ccm;141;139;143;155;130;163;151;153;191;167 07:27:29 INFO - PROCESS | 1976 | |12;stanford-crypto-pbkdf2;168;166;167;168;166;166;165;166;171;171 07:27:29 INFO - PROCESS | 1976 | |13;stanford-crypto-sha256-iterative;67;68;67;67;68;68;67;65;68;67 07:27:29 INFO - PROCESS | 1976 | __end_tp_report 07:27:29 INFO - PROCESS | 1976 | __start_cc_report 07:27:29 INFO - PROCESS | 1976 | _x_x_mozilla_cycle_collect,2033 07:27:29 INFO - PROCESS | 1976 | __end_cc_report 07:27:29 INFO - PROCESS | 1976 | __startTimestamp1482334049572__endTimestamp 07:27:29 INFO - PROCESS | 1976 | 07:27:29 INFO - PROCESS | 1976 | ------- Summary: start ------- 07:27:29 INFO - PROCESS | 1976 | Number of tests: 14 07:27:29 INFO - PROCESS | 1976 | 07:27:29 INFO - PROCESS | 1976 | [#0] ai-astar Cycles:10 Average:124.10 Median:115.50 stddev:19.61 (17.0%) stddev-sans-first:18.01 07:27:29 INFO - PROCESS | 1976 | Values: 152.0 169.0 115.0 114.0 115.0 116.0 116.0 115.0 114.0 115.0 07:27:29 INFO - PROCESS | 1976 | 07:27:29 INFO - PROCESS | 1976 | [#1] audio-beat-detection Cycles:10 Average:135.00 Median:141.00 stddev:20.69 (14.7%) stddev-sans-first:16.11 07:27:29 INFO - PROCESS | 1976 | Values: 175.0 139.0 112.0 143.0 128.0 141.0 113.0 107.0 151.0 141.0 07:27:29 INFO - PROCESS | 1976 | 07:27:29 INFO - PROCESS | 1976 | [#2] audio-dft Cycles:10 Average:183.70 Median:184.50 stddev:1.49 (0.8%) stddev-sans-first:1.58 07:27:29 INFO - PROCESS | 1976 | Values: 184.0 183.0 182.0 185.0 182.0 185.0 185.0 186.0 182.0 183.0 07:27:29 INFO - PROCESS | 1976 | 07:27:29 INFO - PROCESS | 1976 | [#3] audio-fft Cycles:10 Average:66.40 Median:60.00 stddev:12.57 (21.0%) stddev-sans-first:12.17 07:27:29 INFO - PROCESS | 1976 | Values: 81.0 58.0 59.0 89.0 59.0 59.0 61.0 58.0 83.0 57.0 07:27:29 INFO - PROCESS | 1976 | 07:27:29 INFO - PROCESS | 1976 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:79.00 stddev:11.48 (14.5%) stddev-sans-first:11.78 07:27:29 INFO - PROCESS | 1976 | Values: 86.0 72.0 96.0 71.0 68.0 68.0 90.0 68.0 68.0 91.0 07:27:29 INFO - PROCESS | 1976 | 07:27:29 INFO - PROCESS | 1976 | [#5] imaging-gaussian-blur Cycles:10 Average:90.60 Median:87.50 stddev:11.85 (13.5%) stddev-sans-first:12.50 07:27:29 INFO - PROCESS | 1976 | Values: 87.0 85.0 88.0 86.0 90.0 124.0 88.0 85.0 88.0 85.0 07:27:29 INFO - PROCESS | 1976 | 07:27:29 INFO - PROCESS | 1976 | [#6] imaging-darkroom Cycles:10 Average:107.50 Median:94.50 stddev:23.58 (25.0%) stddev-sans-first:24.42 07:27:29 INFO - PROCESS | 1976 | Values: 93.0 112.0 151.0 151.0 94.0 95.0 95.0 95.0 95.0 94.0 07:27:29 INFO - PROCESS | 1976 | 07:27:29 INFO - PROCESS | 1976 | [#7] imaging-desaturate Cycles:10 Average:82.80 Median:83.00 stddev:2.35 (2.8%) stddev-sans-first:2.45 07:27:29 INFO - PROCESS | 1976 | Values: 84.0 80.0 80.0 82.0 81.0 84.0 88.0 83.0 83.0 83.0 07:27:29 INFO - PROCESS | 1976 | 07:27:29 INFO - PROCESS | 1976 | [#8] json-parse-financial Cycles:10 Average:56.50 Median:58.00 stddev:2.12 (3.7%) stddev-sans-first:2.05 07:27:29 INFO - PROCESS | 1976 | Values: 54.0 55.0 54.0 54.0 58.0 58.0 59.0 58.0 59.0 56.0 07:27:29 INFO - PROCESS | 1976 | 07:27:29 INFO - PROCESS | 1976 | [#9] json-stringify-tinderbox Cycles:10 Average:71.80 Median:71.50 stddev:2.70 (3.8%) stddev-sans-first:2.86 07:27:29 INFO - PROCESS | 1976 | Values: 72.0 69.0 78.0 74.0 73.0 69.0 71.0 71.0 70.0 71.0 07:27:29 INFO - PROCESS | 1976 | 07:27:29 INFO - PROCESS | 1976 | [#10] stanford-crypto-aes Cycles:10 Average:83.20 Median:83.50 stddev:1.14 (1.4%) stddev-sans-first:1.17 07:27:29 INFO - PROCESS | 1976 | Values: 84.0 84.0 83.0 84.0 83.0 82.0 81.0 83.0 83.0 85.0 07:27:29 INFO - PROCESS | 1976 | 07:27:29 INFO - PROCESS | 1976 | [#11] stanford-crypto-ccm Cycles:10 Average:153.30 Median:154.00 stddev:17.37 (11.3%) stddev-sans-first:17.85 07:27:29 INFO - PROCESS | 1976 | Values: 141.0 139.0 143.0 155.0 130.0 163.0 151.0 153.0 191.0 167.0 07:27:29 INFO - PROCESS | 1976 | 07:27:29 INFO - PROCESS | 1976 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.40 Median:167.50 stddev:2.12 (1.3%) stddev-sans-first:2.24 07:27:29 INFO - PROCESS | 1976 | Values: 168.0 166.0 167.0 168.0 166.0 166.0 165.0 166.0 171.0 171.0 07:27:29 INFO - PROCESS | 1976 | 07:27:29 INFO - PROCESS | 1976 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.50 stddev:0.92 (1.4%) stddev-sans-first:0.97 07:27:29 INFO - PROCESS | 1976 | Values: 67.0 68.0 67.0 67.0 68.0 68.0 67.0 65.0 68.0 67.0 07:27:29 INFO - PROCESS | 1976 | -------- Summary: end -------- 07:27:29 INFO - PROCESS | 1976 | 07:27:30 INFO - TEST-INFO | 1976: exit 0 07:27:30 INFO - TEST-OK | kraken | took 59966ms 07:27:30 INFO - SUITE-END | took 1180s 07:27:30 INFO - Completed test suite (00:19:40) 07:27:30 INFO - javascript benchmark 07:27:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3339.6994270515647, 3343.364760695468, 3343.6154842193146, 3351.882684106056, 3397.7791443556166, 5711.714414279285, 5775.162601422791, 5804.158397032428, 5807.099899515577, 5809.215755872961, 4673.929891051637, 5761.452661997114, 5780.132980052994, 5780.999999999989, 5786.276715410574, 4595.58639722425, 4984.4548190061105, 5224.320838291017, 5266.368035835705, 5294.311739473863, 4428.158649856533, 5212.113940630102, 5232.241325007872, 5343.866403339928, 5379.731013449332, 5226.451222621627, 5322.680639161644, 5323.866903327409, 5325.573954083664, 5328.280682107924, 5231.398389185235, 5303.734813259361, 5308.761105750251, 5310.389305229871, 5332.786688532454, 5214.921776173361, 5271.2620233167, 5293.470652934714, 5315.973420132913, 5325.121354976424, 5523.7238138093335, 5574.219609254707, 5787.13193021046, 5805.941940580582, 5864.179014937912, 6134.540608262945, 6166.7533298667995, 6171.413715697002, 6239.407256310643, 6287.245530536319, 6305.0542418637115, 6327.651979141146, 6357.872842543124, 6369.235691716977, 6373.8406539836415, 5680.091185410314, 5852.67810270435, 5853.7365818538265, 5884.0585506318785, 5889.116632505073, 6244.531660125509, 6319.557630965788, 6335.904961425582, 6345.270293916166, 6371.076193951943, 6208.068789681584, 6210.068489726577, 6211.9689401552705, 6239.563230573907, 6249.7187626556915, 6158.414950579688, 6332.588381755171, 6341.55609107358, 6342.587731797418, 6352.333005034498, 5168.8449346519665, 6120.903538226997, 6137.60105593135, 6176.629402235881, 6196.845078873064, 6286.0, 6294.929861923484, 6303.46420554258, 6305.054241863757, 6330.113784070234, 4902.166631672624, 4945.629077819178, 4950.8267210647455, 4962.354893863847, 4973.079980203674, 4387.780610969438, 4402.493713222956, 4422.84520041797, 4461.263891457873, 4506.549345065533, 5734.569907256972, 5747.0, 5788.305403351625, 5789.363170051291, 5859.384764599741, 6293.96853015732, 6301.243850737941, 6302.558820882586, 6317.052442133625, 6342.175517182736, 5679.403662615366, 5802.158686990363, 5805.36141024487, 5810.302763668386, 5827.300723913073, 5305.946940530623, 5365.168398898177, 5367.2485851980755, 5385.838424847261, 5439.129739241782, 6321.399467050623, 6330.841728956813, 6339.175907132043, 6351.110844481684, 6366.77716279928, 6307.90538141919, 6324.778632748016, 6326.9683651581445, 6338.461230795434, 6344.555881088279, 6112.908306375319, 6281.214848143982, 6286.654234017173, 6340.1123842661145, 6363.96818015907, 6130.45928571088, 6151.26184857802, 6172.259328880563, 6219.253689557282, 6284.4344009039405, 5251.868703282448, 6306.558540902276, 6345.1434056401795, 6346.397092276226, 6354.269259035085, 4578.5650363215445, 6119.969400152971, 6165.5375846810775, 6171.413715697002, 6189.876202476015, 5968.22413086296, 6068.423499767515, 6087.817365479043, 6113.816585502442, 6125.968084364015, 5995.070764031582, 6050.727717252822, 6056.3035250946705, 6080.0575910733905, 6083.66539840313, 6177.7837775677635, 6178.320384757763, 6184.103305020837, 6195.4424101831055, 6197.907031394442, 5741.224934633938, 5865.442782935615, 5872.324682661378, 5944.524438045032, 6031.969840150947], "value": 5712.683396996658, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [511.68787039905675, 512.8282025521451, 514.2055524215087, 518.0209404226011, 531.160765989736, 30097.849510752443, 31796.523052154207, 39583.40624890629, 40866.0, 41006.38490422646, 41707.0, 41986.0, 42090.15819683605, 42127.36808947868, 42221.73334799961, 9230.86153707695, 9399.389039712396, 9402.153806157445, 9402.952985235064, 9412.670556530522, 9249.722508324761, 9379.671711490098, 9380.249580033615, 9401.717948461557, 9406.905930940706, 9387.43675379476, 9434.245260379153, 9444.433333999981, 9446.622135114587, 9472.289578281621, 9331.953340233289, 9360.625574976993, 9380.296477764126, 9389.483578403186, 9415.764605884804, 9235.445873247627, 9327.533623318806, 9330.300227482965, 9335.626574936994, 9365.906340936572, 38890.416643750366, 39244.80377598122, 39253.0, 39292.21415571701, 39295.803520982496, 3233.304839459504, 3309.7352211823236, 3328.8169150696704, 3433.853841349157, 3520.2607452434763, 27919.022834200707, 29032.12903612895, 29846.70153298461, 30583.694163058306, 30587.694123058707, 3131.123285480069, 3166.477531207348, 3170.6036745406823, 3180.189051791778, 3187.4900015997328, 465376.0, 466533.9999999966, 467306.0, 468112.0, 476304.0, 32184.517232241535, 57704.422955770744, 58059.70970145207, 58062.4193758057, 58119.709401452725, 33494.665053349156, 34558.30883382318, 34591.13522161966, 34627.65372346294, 34635.0, 14265.072770270106, 14500.637484062983, 14505.347259373353, 14507.0, 14513.419463221564, 11769.941150294193, 12247.387630618434, 12321.260724356389, 12322.753544929052, 12326.260424374566, 7603.619819009138, 7688.192739762357, 7721.305082542598, 7806.1413244543055, 7811.68753249875, 3190.5692731480954, 3262.2333751568362, 3263.4125857345907, 3274.1978215337394, 3312.2878581104696, 811.9147489513634, 946.8153710026479, 999.3004896572428, 1049.506731836036, 1062.7678646808542, 22512.437189070075, 29016.129516114128, 29131.7086829129, 29815.956441524875, 30159.0, 1013.4780587997187, 1017.0388982411698, 1037.1288117980932, 1071.3197119828808, 1100.763335882773, 14737.926310368379, 15201.239938003055, 15241.923790380977, 15313.46402875894, 15588.986715863433, 592332.0, 595385.0230748819, 595478.0, 597212.0, 598414.0, 57506.0, 58079.83840323254, 58147.70926145342, 58201.0, 58247.0, 1152.475623591264, 1237.616338934933, 1279.2452453052745, 1282.454956643404, 1282.923024618526, 23336.416589584718, 30623.877021361906, 31107.0, 31270.374592508477, 31800.0, 52604.736976314874, 53260.73369633127, 53343.0, 53375.733121334146, 53420.0, 505526.0, 507157.0, 509649.0, 512082.0, 512521.0, 26056.088036918616, 26562.86718566395, 26907.596386053832, 26929.596056059567, 26987.595186072616, 46168.30747538857, 46305.0, 46472.070558589316, 46536.53463465322, 46732.0, 38653.613463866124, 39091.6090839088, 39869.800650996556, 40544.186418322875, 41132.0, 37717.434238486996, 37812.81093594514, 39067.6093239064, 39152.608473914894, 39510.0, 36135.27729445449, 36888.07779805527, 37278.0, 38247.42628860625, 38406.423903640905, 30703.385932281675, 34016.82991585026, 34913.9525814226, 34919.476207856394, 35139.0, 20569.98769129549, 24276.0, 25395.61906571366, 25428.49143017092, 26050.73949260559], "value": 18224.56171525414, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.3815765174466, 363.42034455044205, 375.19146239853126, 381.3440881683504, 401.1455599572909, 14871.107733535982, 15142.0, 15653.686926261467, 19891.502712432197, 20750.48123796906, 19981.0, 20045.1981920723, 20515.0, 20654.863982480958, 20811.583768324625, 4742.027884283713, 5833.854153646172, 6103.054026625858, 6103.328633850273, 6110.633361998272, 5941.732622031997, 5985.461308482258, 6066.332703402622, 6072.848178795543, 6081.179040829497, 5672.546196304285, 5876.5298776098025, 5914.645121292715, 5982.222311099551, 6016.548758843082, 5953.196318496991, 5989.88020239595, 5993.4006599340155, 6001.279846418414, 6007.8197654070445, 4999.150144475448, 5176.378834539842, 5185.974070129663, 5235.240890070919, 5258.947410525922, 22251.0, 23213.419664508354, 23492.060317587275, 23660.881695591583, 23724.05103795846, 2168.544605632819, 2273.2498275569023, 2306.8039216666602, 2406.6630671705975, 2888.9133326000256, 9889.851652225223, 10388.636397726043, 10559.0, 10647.307924984929, 14928.62678433048, 1811.7644706188112, 1824.2520566567775, 1899.2403038784596, 1904.628597423503, 1935.7870634230223, 102796.0, 109603.0, 115386.42306788413, 115743.0, 115889.4205528967, 11396.601118960796, 11450.541978320774, 11569.595064172714, 11674.649760507198, 11689.707757306136, 14807.925960370128, 14810.0, 14818.184999824898, 14825.92587037058, 14866.554003379915, 8252.669893204325, 8386.454880432751, 8400.915990840136, 8423.789405264917, 8546.743597692079, 6718.294579069225, 7145.928540714526, 8154.103048664642, 8308.501489910625, 8325.875111873327, 4473.463184417826, 4520.909581808345, 4538.455385353779, 4616.16908956391, 4617.12274667812, 1581.936722531109, 1680.3950577791986, 1855.3877220517197, 1856.4616261284345, 1915.2817693364989, 872.0886673426285, 952.8618350339162, 975.3952549419405, 999.160705007797, 1001.3891526169031, 19781.505462363555, 21252.468688282916, 21323.253686120912, 21395.572088558452, 21619.35141945744, 908.2779190543401, 912.7854954085784, 937.3110763588757, 958.5830163878735, 994.8109859126743, 10358.689239322832, 10593.152547796159, 11122.38826864514, 11144.2199046066, 11185.552577896793, 494974.52512737206, 576832.0, 580831.0, 581165.0, 594613.0269348626, 70523.64738176379, 71931.2806871935, 90439.0, 113672.0, 115564.42217788857, 946.4794363100267, 965.0060437748989, 985.1380042462845, 1010.5604062232952, 1012.5544760305442, 14739.705205895607, 18488.537786555444, 19084.805247396725, 19763.421655250426, 19898.005099744667, 51462.970740585726, 51651.48348516467, 52365.0, 52916.73541632421, 53481.0, 69402.65298673474, 74813.62593187217, 75707.0, 77996.61001694955, 83965.0, 5417.187421886749, 6035.305939816802, 7504.699812007459, 7903.644336004779, 8263.0, 11989.820152697543, 12432.191907525981, 12432.564860229848, 12725.045621578234, 12738.872611274139, 10842.241043126905, 10969.890301097206, 11176.329420234812, 11523.769524609294, 11557.42212889369, 10678.733031673959, 10767.0, 10918.50866710984, 10939.124870010539, 11293.887061129284, 11179.832302515306, 11288.830667539829, 11485.483153257956, 11536.192466527262, 11537.538498459968, 8076.0, 8228.50628962264, 8332.583370831555, 8351.498910065415, 8353.665853365797, 10885.836712449478, 11003.33979961205, 11120.893332338466, 11204.607838725606, 11409.942950285196], "value": 9350.227270911946, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1390.9234992075435, 1437.7052704195642, 1584.3345794766196, 1782.4296225207922, 1817.9181936812843, 8058.234467725571, 8329.375296852737, 8342.124076971922, 8344.916550834498, 8540.530270835101, 7524.2851929066655, 7715.305622493975, 8145.226203510671, 8283.420160588761, 8371.497710137395, 2300.631898896177, 2336.1823361823313, 2339.3449834046496, 2339.4034521197154, 2341.95316093678, 2320.9651855222187, 2353.870537120458, 2371.5256948610263, 2377.809775217987, 2380.0836677879056, 2267.1611503743557, 2270.0806173499677, 2300.4363930836967, 2301.1485750272423, 2301.9079236830507, 2292.071710957065, 2307.284741730069, 2323.8024767894663, 2329.5340931813535, 2332.54515369503, 2336.5326934612976, 2347.94130146746, 2353.85876847388, 2357.563850687611, 2359.2332491940015, 8247.381446391484, 8323.292520135796, 8339.249467547888, 8353.874691879626, 8427.073021967579, 5032.924506132411, 5172.301739265189, 5178.792848286102, 5189.091908915962, 5195.636305458619, 3251.8536665850093, 3393.2025973896116, 3492.0746002309406, 3567.571891373026, 3584.766990145632, 1049.1759217485658, 1054.6150655010943, 1074.607768164622, 1080.8540846985634, 1093.240198062347, 10763.892361076367, 10862.131029517699, 10931.344119352785, 10958.452077396178, 10961.397123158224, 4480.731156130642, 4679.344891715163, 4759.119562880878, 4792.616590672737, 4804.399450068741, 9340.626374945063, 9341.673041443517, 9356.485393303297, 9363.812723745623, 9364.344495885223, 5183.092958732207, 5195.116830138885, 5197.480251974848, 5197.532222100029, 5199.4540573239265, 3429.9828500857334, 4393.51671316155, 4450.777461126931, 4669.766511674403, 4739.4075740532435, 3154.9053528394184, 3776.7922764247683, 3898.571157172709, 3903.9219215616095, 3910.687145028391, 1252.7745005898844, 1257.3964497041472, 1261.3062815451465, 1272.2125413793635, 1276.1768659214754, 715.8317795318096, 793.0483419896033, 823.3577809308749, 901.8286525560122, 906.211595911548, 5487.533559647395, 5572.359178694421, 5674.4892959633835, 5748.540116790647, 5858.0, 726.3426598927979, 894.4901406198535, 922.1516205091198, 944.2210176604328, 960.4813400763649, 3252.6747325267756, 3282.7373810095137, 3794.4498047782918, 3825.196708691206, 3937.4290727844873, 9898.158656514133, 10121.645742399129, 10224.233182511192, 10248.948755256177, 10259.9487002566, 9458.0, 9947.850782238273, 10026.598936042623, 10098.394096354243, 10103.797924041624, 7634.847303054019, 7849.646765895434, 7854.018247719036, 7863.52818830872, 7869.645865935932, 5631.380548139783, 5714.885702286014, 5717.428257174416, 5731.226284451545, 5898.174255604133, 9254.35219534626, 9357.39176953496, 9387.953060234655, 9398.0, 9404.247660187306, 9037.728868133967, 9588.424694518351, 9697.6120955161, 9700.5149742514, 9713.46575938302, 6625.469962403093, 6841.3842754152365, 6866.519343645897, 7235.095613048369, 7390.334869861738, 7709.383249340151, 7835.960820195863, 8005.199480051948, 8051.194880511902, 8077.83844323122, 7089.893651595127, 7200.243974382824, 7226.602536860523, 7258.963705181441, 7316.048913641405, 6930.27232140618, 6976.407005404394, 6992.965035174791, 7119.323664251888, 7120.715171393086, 5534.252875861707, 6635.402813746786, 6687.331266873273, 6705.0612914193025, 6767.695453704497, 4770.689905156154, 4825.276208568743, 4839.322494850653, 4881.414230292387, 4926.729029903389, 4808.326834243138, 4843.782029808737, 4918.680285781413, 4972.055309491185, 4979.875503112451], "value": 4408.9902953908, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [283.24232677587446, 295.09111935239457, 299.2518703241894, 308.84866415456435, 313.9450596145674, 5009.949900500994, 5338.67967921925, 5347.491988261109, 5352.946470535294, 5398.380485854243, 4170.353595192742, 5131.666441681288, 5229.3201883755155, 5291.6031297652735, 5329.493698098684, 3598.280343931211, 3889.6888248940018, 4080.5307389650125, 4083.9387409188885, 4096.733712308705, 3959.307121253769, 4100.917981640366, 4104.281750693632, 4114.321137012389, 4117.644120042156, 4042.393640953851, 4044.5237488316698, 4045.1190196558596, 4068.9186216275657, 4074.8166332515107, 3015.638123425192, 4029.395590661395, 4040.5555388907196, 4046.2312160689376, 4047.045656670632, 3320.6015278166774, 3431.605365382988, 3441.7074548663395, 3546.6098729139776, 3566.661167189113, 3866.7293289469467, 5071.8985620287385, 5122.644667742635, 5128.230765385185, 5130.07658621448, 3975.980120099381, 3997.780676893546, 4531.637469002505, 4547.499775024745, 4562.817487300504, 4802.4237091548885, 5114.923276150861, 5180.0, 5285.810692594174, 5414.94408590325, 3202.727768139711, 3422.828858557062, 3425.1950791563963, 3744.8578183653976, 3751.6998640108727, 4879.926801097987, 5055.797768089272, 5730.025895597759, 5788.739506722165, 5867.589268751189, 3153.7003984621424, 3717.9070523237137, 3846.2692088503095, 3847.825978322239, 3878.5733569307354, 3687.2625474905126, 3700.4634328022285, 3907.5506316773367, 3913.8845429052585, 3934.206713233636, 3790.3935370341164, 4475.261581839025, 4544.454665440168, 4551.362809206714, 4604.907901842012, 5532.585056120726, 5615.213870058195, 5618.494335509742, 5639.943600564024, 5650.796189486104, 1155.4280631087497, 1194.2834299420279, 1217.8721234270452, 1268.8160216768702, 1276.8020956751718, 701.9649017549102, 737.7712908998228, 841.3395484544603, 853.2448782827119, 858.8668756342777, 4252.574742525722, 4542.432195975503, 4837.564619184291, 4866.6106711463, 4945.505449455098, 4745.810167593327, 4763.595094416944, 5129.281900533928, 5346.705931173745, 5364.892702145935, 3919.8236079376497, 4814.759262036884, 4840.467548569654, 4856.635752318591, 4896.608271338285, 2459.6187590923437, 2467.260581290186, 2556.8394706473, 2636.934076648099, 2828.7454129128064, 4704.623630109583, 5433.728313584305, 5457.244091733649, 5495.972520137374, 5514.531264842453, 5400.110940646735, 5511.5039646432015, 5553.611247212656, 5567.0, 5586.91619625706, 5456.0, 5487.582496090686, 5510.311211098532, 5520.337559492887, 5576.737608498993, 5202.895942081062, 5337.653052551572, 5345.1714984177515, 5345.487620818365, 5440.809571664972, 5150.841060761299, 5290.65610735301, 5301.469853014668, 5336.653117547347, 5441.5102640762525, 4337.967957169794, 4590.563896429834, 5468.48049435303, 5502.504774570308, 5561.304836895388, 3062.9693703062685, 3205.6634053424054, 3556.395412779885, 3566.465030245484, 3595.3708101081684, 4678.76606169697, 4840.651080556076, 5427.782888684408, 5607.009511623315, 5681.335538577677, 4532.501424843199, 4613.446386433515, 4777.904441911072, 5502.862428439321, 5605.523530499953, 4678.222275535275, 5249.448807875118, 5487.241239750783, 5488.286522752017, 5554.333479982428, 4664.813407463664, 4787.904241915212, 5192.818251361185, 5430.7013114277515, 5464.9453505464435, 4710.081534100817, 4839.395075615548, 5447.019536483471, 5565.276514053147, 5614.512154279201, 4544.863654090383, 5195.038289725566, 5320.574354051744, 5435.347758269033, 5513.078068623636], "value": 3914.296943235172, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [698.591324075416, 746.2910235276486, 765.3035737478896, 781.7928249014012, 787.5274835098945, 11368.943155284232, 15868.682626347467, 16524.752128718064, 16614.41849535265, 17220.655586888253, 15059.77410338843, 15135.848641513609, 15160.014599051054, 15207.771883421758, 15213.61965950849, 645.4158986117571, 665.6239224837975, 806.6450761664863, 831.4470876866907, 832.8750687396879, 670.587588632992, 798.56079156464, 814.7148498025703, 820.9261166495014, 830.2030051150903, 873.2490058549643, 876.1676407412951, 876.3208513402101, 877.1404024056428, 878.964841406343, 868.2489646455833, 869.9304055675531, 869.9608517616722, 870.9346798990038, 871.2942516561574, 498.9865891129312, 512.183068006529, 618.1191801682585, 630.224823467138, 638.0174531221912, 14301.070430422093, 14302.213378264192, 14330.64173395663, 14342.42630294787, 14389.208593527352, 12463.75072498545, 12652.240865548096, 12723.10938234324, 12755.234685918875, 12758.425870835834, 10738.570457181704, 11346.546138154465, 11348.205625606211, 11961.880381196164, 12351.876481235162, 3625.665424705941, 3638.2541578976247, 3677.852885884588, 3748.6813620842263, 3769.453429252743, 85973.57013214893, 88672.0, 89378.0, 92438.53780731054, 92673.53663231707, 10354.948225258977, 10405.918816236783, 10407.271490995709, 10463.68608941733, 10465.162786977024, 19496.80503194978, 19580.70628940567, 19585.510362241057, 19922.90038549798, 20130.496737581678, 7231.240719724353, 7409.444291678038, 7506.324430801144, 7732.226777322336, 7747.38020958322, 4173.707840451139, 4343.370211319341, 4361.454818147731, 4391.14372697321, 4512.887177820515, 3369.898903032913, 3483.1466290758926, 3486.1284678830293, 3488.965110348864, 3489.284696637183, 545.3210752613022, 632.8101569529123, 638.5466318913624, 659.6839305585388, 668.6589839182052, 533.4851867947449, 639.7345101782709, 676.5331920974512, 684.4387602166275, 685.4550632247375, 8867.467951922905, 9030.36787424887, 9178.35751497402, 9195.632174712937, 9251.722448326562, 637.2894222941387, 656.8226578823691, 674.6356967237734, 678.9966050169817, 679.0934102972587, 4306.849260275938, 4479.46246450428, 4536.523665015192, 4543.727376357429, 4561.400011959042, 66782.66434671279, 73839.0, 77966.0, 88792.5560372194, 92368.53815731013, 42442.57557424448, 53225.7338713304, 61234.0814887777, 81440.55678329659, 89279.55360223158, 66504.6674766623, 66774.66612666905, 66916.0, 68514.65742671354, 84850.57574712086, 9287.257019438293, 9741.318107732392, 9742.220622350053, 9835.213182945205, 9851.950740246253, 48584.757076214395, 48927.30899800545, 51316.0, 53991.0, 54833.451665484434, 68376.89738615698, 74832.62583687264, 78211.0, 78750.6062469684, 79766.60116699612, 11988.460519276829, 12013.519459221534, 12022.458989345676, 12282.570110046105, 12326.445309961253, 12970.535598236305, 13408.32958352059, 13816.93091534536, 14129.319701257464, 14533.200673963034, 10266.435346056038, 12110.939445302716, 12890.226586404846, 12966.675833104247, 13425.86574134246, 12872.292023938773, 13018.934905325414, 13162.80255796183, 13192.340382980621, 13857.584272471842, 12571.18287311322, 12606.621801345975, 12707.745845082862, 12929.54746583865, 13413.32933353348, 10818.404987725751, 10890.29213101146, 11489.0, 11525.135614829089, 11565.016973557005, 8955.820883582423, 10043.096121349114, 10369.63706270277, 10947.397893115955, 11066.802070691], "value": 7116.561140489822, "unit": "score"}], "name": "dromaeo_css", "value": 7018.9369036054395, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 169.0, 115.0, 114.0, 115.0, 116.0, 116.0, 115.0, 114.0, 115.0], "value": 124.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 139.0, 112.0, 143.0, 128.0, 141.0, 113.0, 107.0, 151.0, 141.0], "value": 135.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 182.0, 185.0, 182.0, 185.0, 185.0, 186.0, 182.0, 183.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 59.0, 89.0, 59.0, 59.0, 61.0, 58.0, 83.0, 57.0], "value": 66.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 72.0, 96.0, 71.0, 68.0, 68.0, 90.0, 68.0, 68.0, 91.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 86.0, 90.0, 124.0, 88.0, 85.0, 88.0, 85.0], "value": 90.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 112.0, 151.0, 151.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 107.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 80.0, 80.0, 82.0, 81.0, 84.0, 88.0, 83.0, 83.0, 83.0], "value": 82.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 54.0, 54.0, 58.0, 58.0, 59.0, 58.0, 59.0, 56.0], "value": 56.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 69.0, 78.0, 74.0, 73.0, 69.0, 71.0, 71.0, 70.0, 71.0], "value": 71.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 83.0, 84.0, 83.0, 82.0, 81.0, 83.0, 83.0, 85.0], "value": 83.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 139.0, 143.0, 155.0, 130.0, 163.0, 151.0, 153.0, 191.0, 167.0], "value": 153.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 166.0, 167.0, 168.0, 166.0, 166.0, 165.0, 166.0, 171.0, 171.0], "value": 167.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 67.0, 68.0, 68.0, 67.0, 65.0, 68.0, 67.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1467.3, "alertThreshold": 2.0}]} 07:27:30 INFO - Return code: 0 07:27:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:27:30 INFO - # TBPL SUCCESS # 07:27:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:27:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:27:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:27:30 INFO - 2016-12-21 07:27:30.918 screenresolution[1980:12253] starting screenresolution argv=screenresolution get 07:27:30 INFO - 2016-12-21 07:27:30.921 screenresolution[1980:12253] Display 0: 1600x1200x32@60 07:27:30 INFO - 2016-12-21 07:27:30.928 screenresolution[1981:12256] starting screenresolution argv=screenresolution list 07:27:30 INFO - Available Modes on Display 0 07:27:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:27:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:27:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:27:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:27:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:27:31 INFO - Intel Iris: 07:27:31 INFO - Chipset Model: Intel Iris 07:27:31 INFO - Type: GPU 07:27:31 INFO - Bus: Built-In 07:27:31 INFO - VRAM (Dynamic, Max): 1536 MB 07:27:31 INFO - Vendor: Intel (0x8086) 07:27:31 INFO - Device ID: 0x0a2e 07:27:31 INFO - Revision ID: 0x0009 07:27:31 INFO - Displays: 07:27:31 INFO - Display: 07:27:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:27:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:27:31 INFO - Display Serial Number: 1600x1200 60 07:27:31 INFO - Main Display: Yes 07:27:31 INFO - Mirror: Off 07:27:31 INFO - Online: Yes 07:27:31 INFO - Rotation: Supported 07:27:31 INFO - Return code: 0 07:27:31 INFO - Running post-action listener: _resource_record_post_action 07:27:31 INFO - [mozharness: 2016-12-21 15:27:31.174803Z] Finished run-tests step (success) 07:27:31 INFO - Running post-run listener: _resource_record_post_run 07:27:31 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 393216; Write bytes: 656380928; Read time: 8; Write time: 1889 07:27:31 INFO - TinderboxPrint: CPU usage
53.4% 07:27:31 INFO - TinderboxPrint: I/O read bytes / time
393,216 / 8 07:27:31 INFO - TinderboxPrint: I/O write bytes / time
656,380,928 / 1,889 07:27:31 INFO - TinderboxPrint: CPU idle
2,261.7 (46.6%) 07:27:31 INFO - TinderboxPrint: CPU system
973.4 (20.1%) 07:27:31 INFO - TinderboxPrint: CPU user
1,618.6 (33.3%) 07:27:31 INFO - TinderboxPrint: Swap in / out
348,991,488 / 0 07:27:31 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 338188800; Write bytes: 333698560; Read time: 26922; Write time: 697 07:27:31 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 393216; Write bytes: 304987648; Read time: 8; Write time: 1137 07:27:31 INFO - Running post-run listener: _upload_blobber_files 07:27:31 INFO - Blob upload gear active. 07:27:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:27:31 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 07:27:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:27:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:27:32 INFO - (blobuploader) - INFO - Open directory for files ... 07:27:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:27:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:27:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:27:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:27:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:27:32 INFO - (blobuploader) - INFO - Done attempting. 07:27:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:27:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:27:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:27:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:27:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:27:33 INFO - (blobuploader) - INFO - Done attempting. 07:27:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:27:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:27:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:27:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:27:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:27:36 INFO - (blobuploader) - INFO - Done attempting. 07:27:36 INFO - (blobuploader) - INFO - Iteration through files over. 07:27:36 INFO - Return code: 0 07:27:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:27:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:27:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/93f743e56f64aed068cf0a8f40a7cff337975e3dc120a3687e4cbe03e7e3df0ea3516cf9a45bd79c99240e443a8796b62413fd30786eabfeaf79571b439829af", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d3ac0b15655c63ba4513bfd7680c93659fb21af237f47ef3225bc4e62ed8a15857e1ddd231e4f028f9b8ff15b3f43f0bf0778ee810dd20e7013a8f6a60ec68bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e4ce1ad2ccfd5b42a3a795c78beace64814bfc99eea891e01c4d63614448726a1a548f8e530aba572a95cffc108f159d1f412fa95bfe4d5891b45e9ecfe3be6d"} 07:27:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:27:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:27:36 INFO - Contents: 07:27:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/93f743e56f64aed068cf0a8f40a7cff337975e3dc120a3687e4cbe03e7e3df0ea3516cf9a45bd79c99240e443a8796b62413fd30786eabfeaf79571b439829af", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d3ac0b15655c63ba4513bfd7680c93659fb21af237f47ef3225bc4e62ed8a15857e1ddd231e4f028f9b8ff15b3f43f0bf0778ee810dd20e7013a8f6a60ec68bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e4ce1ad2ccfd5b42a3a795c78beace64814bfc99eea891e01c4d63614448726a1a548f8e530aba572a95cffc108f159d1f412fa95bfe4d5891b45e9ecfe3be6d"} 07:27:36 INFO - Running post-run listener: copy_logs_to_upload_dir 07:27:36 INFO - Copying logs to upload dir... 07:27:36 INFO - mkdir: /builds/slave/test/build/upload/logs 07:27:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.100123 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-12-21 07:27:36.318870) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 07:27:36.322276) ========= 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.2TrofkS004/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.F0jn5NqNDY/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/93f743e56f64aed068cf0a8f40a7cff337975e3dc120a3687e4cbe03e7e3df0ea3516cf9a45bd79c99240e443a8796b62413fd30786eabfeaf79571b439829af", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d3ac0b15655c63ba4513bfd7680c93659fb21af237f47ef3225bc4e62ed8a15857e1ddd231e4f028f9b8ff15b3f43f0bf0778ee810dd20e7013a8f6a60ec68bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e4ce1ad2ccfd5b42a3a795c78beace64814bfc99eea891e01c4d63614448726a1a548f8e530aba572a95cffc108f159d1f412fa95bfe4d5891b45e9ecfe3be6d"} build_url:https://queue.taskcluster.net/v1/task/YpLX7sLsRsCq6G4nIOWMMg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011225 build_url: 'https://queue.taskcluster.net/v1/task/YpLX7sLsRsCq6G4nIOWMMg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/93f743e56f64aed068cf0a8f40a7cff337975e3dc120a3687e4cbe03e7e3df0ea3516cf9a45bd79c99240e443a8796b62413fd30786eabfeaf79571b439829af", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d3ac0b15655c63ba4513bfd7680c93659fb21af237f47ef3225bc4e62ed8a15857e1ddd231e4f028f9b8ff15b3f43f0bf0778ee810dd20e7013a8f6a60ec68bc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e4ce1ad2ccfd5b42a3a795c78beace64814bfc99eea891e01c4d63614448726a1a548f8e530aba572a95cffc108f159d1f412fa95bfe4d5891b45e9ecfe3be6d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 07:27:36.356929) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 07:27:36.357234) ========= 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.2TrofkS004/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.F0jn5NqNDY/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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.005328 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 07:27:36.405885) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 07:27:36.406205) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 07:27:36.406570) ========= ========= Total master_lag: 0.19 =========