builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0332 starttime: 1484736966.3 results: success (0) buildid: 20170118004007 builduid: 1fbdd8050bb5406baffab863361877d3 revision: fd66a1e49411faca500b26ba9ca7dd5a311ff6e5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-18 02:56:06.298154) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-18 02:56:06.298682) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-18 02:56:06.298997) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-18 02:56:06.326258) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 02:56:06.326591) ========= 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.ToVBQWsph6/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.vxzxfXIi35/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 --2017-01-18 02:56:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 200M=0s 2017-01-18 02:56:06 (200 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.165731 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 02:56:06.519545) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 02:56:06.520092) ========= 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.ToVBQWsph6/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.vxzxfXIi35/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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.024964 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 02:56:06.604489) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 02:56:06.604889) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fd66a1e49411faca500b26ba9ca7dd5a311ff6e5 --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 fd66a1e49411faca500b26ba9ca7dd5a311ff6e5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ToVBQWsph6/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.vxzxfXIi35/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 2017-01-18 02:56:06,705 truncating revision to first 12 chars 2017-01-18 02:56:06,705 Setting DEBUG logging. 2017-01-18 02:56:06,705 attempt 1/10 2017-01-18 02:56:06,705 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/fd66a1e49411?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-18 02:56:07,330 unpacking tar archive at: mozilla-aurora-fd66a1e49411/testing/mozharness/ program finished with exit code 0 elapsedTime=0.900710 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 02:56:07.529573) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-18 02:56:07.529915) ========= script_repo_revision: fd66a1e49411faca500b26ba9ca7dd5a311ff6e5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-18 02:56:07.530376) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-18 02:56:07.530688) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-18 02:56:07.550748) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-01-18 02:56:07.551080) ========= /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.ToVBQWsph6/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.vxzxfXIi35/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 02:56:07 INFO - MultiFileLogger online at 20170118 02:56:07 in /builds/slave/test 02:56:07 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 02:56:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:56:07 INFO - {'append_to_log': False, 02:56:07 INFO - 'base_work_dir': '/builds/slave/test', 02:56:07 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:56:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:56:07 INFO - 'branch': 'Mozilla-Aurora', 02:56:07 INFO - 'buildbot_json_path': 'buildprops.json', 02:56:07 INFO - 'config_files': ('talos/mac_config.py',), 02:56:07 INFO - 'default_actions': ('clobber', 02:56:07 INFO - 'read-buildbot-config', 02:56:07 INFO - 'download-and-extract', 02:56:07 INFO - 'populate-webroot', 02:56:07 INFO - 'create-virtualenv', 02:56:07 INFO - 'install', 02:56:07 INFO - 'run-tests'), 02:56:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:56:07 INFO - 'download_minidump_stackwalk': True, 02:56:07 INFO - 'download_symbols': 'ondemand', 02:56:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:56:07 INFO - 'tooltool.py': '/tools/tooltool.py', 02:56:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:56:07 INFO - '/tools/misc-python/virtualenv.py')}, 02:56:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:56:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:56:07 INFO - 'installer_path': 'installer.exe', 02:56:07 INFO - 'log_level': 'info', 02:56:07 INFO - 'log_name': 'talos', 02:56:07 INFO - 'log_to_console': True, 02:56:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:56:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:56:07 INFO - 'opt_config_files': (), 02:56:07 INFO - 'pip_index': False, 02:56:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:56:07 INFO - 'cmd': ('bash', 02:56:07 INFO - '-c', 02:56:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:56:07 INFO - 'enabled': True, 02:56:07 INFO - 'halt_on_failure': False, 02:56:07 INFO - 'name': 'check_screen_resolution'},), 02:56:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:56:07 INFO - 'cmd': ('bash', 02:56:07 INFO - '-c', 02:56:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:56:07 INFO - 'enabled': True, 02:56:07 INFO - 'halt_on_failure': False, 02:56:07 INFO - 'name': 'check_screen_resolution'},), 02:56:07 INFO - 'run_cmd_checks_enabled': True, 02:56:07 INFO - 'sps_profile': False, 02:56:07 INFO - 'sps_profile_interval': 0, 02:56:07 INFO - 'suite': 'dromaeojs', 02:56:07 INFO - 'system_bits': '32', 02:56:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:56:07 INFO - 'title': 't-yosemite-r7-0332', 02:56:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:56:07 INFO - 'use_talos_json': True, 02:56:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:56:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:56:07 INFO - 'work_dir': 'build'} 02:56:07 INFO - [mozharness: 2017-01-18 10:56:07.766393Z] Running clobber step. 02:56:07 INFO - Running pre-action listener: _resource_record_pre_action 02:56:07 INFO - Running main action method: clobber 02:56:07 INFO - rmtree: /builds/slave/test/build 02:56:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:56:10 INFO - Running post-action listener: _resource_record_post_action 02:56:10 INFO - [mozharness: 2017-01-18 10:56:10.205965Z] Finished clobber step (success) 02:56:10 INFO - [mozharness: 2017-01-18 10:56:10.206219Z] Running read-buildbot-config step. 02:56:10 INFO - Running pre-action listener: _resource_record_pre_action 02:56:10 INFO - Running main action method: read_buildbot_config 02:56:10 INFO - Using buildbot properties: 02:56:10 INFO - { 02:56:10 INFO - "project": "", 02:56:10 INFO - "product": "firefox", 02:56:10 INFO - "script_repo_revision": "production", 02:56:10 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:56:10 INFO - "repository": "", 02:56:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:56:10 INFO - "buildid": "20170118004007", 02:56:10 INFO - "stage_platform": "macosx64", 02:56:10 INFO - "basedir": "/builds/slave/test", 02:56:10 INFO - "nightly_build": "True", 02:56:10 INFO - "buildnumber": 2, 02:56:10 INFO - "slavename": "t-yosemite-r7-0332", 02:56:10 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 02:56:10 INFO - "platform": "yosemite_r7", 02:56:10 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:56:10 INFO - "branch": "mozilla-aurora", 02:56:10 INFO - "repo_path": "releases/mozilla-aurora", 02:56:10 INFO - "revision": "fd66a1e49411faca500b26ba9ca7dd5a311ff6e5", 02:56:10 INFO - "pgo_build": "False", 02:56:10 INFO - "builduid": "1fbdd8050bb5406baffab863361877d3", 02:56:10 INFO - "slavebuilddir": "test" 02:56:10 INFO - } 02:56:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/Rez5mHwMQUqZOnNOuERCiQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 02:56:10 INFO - Running post-action listener: _resource_record_post_action 02:56:10 INFO - [mozharness: 2017-01-18 10:56:10.211987Z] Finished read-buildbot-config step (success) 02:56:10 INFO - [mozharness: 2017-01-18 10:56:10.212216Z] Running download-and-extract step. 02:56:10 INFO - Running pre-action listener: _resource_record_pre_action 02:56:10 INFO - Running main action method: download_and_extract 02:56:10 INFO - mkdir: /builds/slave/test/build/tests 02:56: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')]} 02:56:10 INFO - trying https://queue.taskcluster.net/v1/task/Rez5mHwMQUqZOnNOuERCiQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 02:56:10 INFO - Downloading https://queue.taskcluster.net/v1/task/Rez5mHwMQUqZOnNOuERCiQ/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 02:56:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Rez5mHwMQUqZOnNOuERCiQ/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 02:56:11 INFO - Downloaded 1199 bytes. 02:56:11 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:56:11 INFO - Using the following test package requirements: 02:56:11 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:56:11 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:56:11 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 02:56:11 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:56:11 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 02:56:11 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:56:11 INFO - u'jsshell-mac.zip'], 02:56:11 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:56:11 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 02:56:11 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:56:11 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:56:11 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 02:56:11 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:56:11 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 02:56:11 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:56:11 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 02:56:11 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:56:11 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 02:56:11 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:56:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Rez5mHwMQUqZOnNOuERCiQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:56:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rez5mHwMQUqZOnNOuERCiQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:56:11 INFO - Fetch https://queue.taskcluster.net/v1/task/Rez5mHwMQUqZOnNOuERCiQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:56:12 INFO - Http code: 200 02:56:12 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 02:56:12 INFO - x-amz-cf-id: v54_3ubnPIIW5KdksiN3txZr6-ctHkZqGXcEpwtFg4R-cvEptK2kfA== 02:56:12 INFO - x-amz-version-id: g.DkRtTIx3XdQlom3OiLLEO7fPTKgWRr 02:56:12 INFO - Expected file size: 16276104 02:56:12 INFO - Obtained file size: 16276104 02:56:18 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 02:56:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Rez5mHwMQUqZOnNOuERCiQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:56:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rez5mHwMQUqZOnNOuERCiQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:56:18 INFO - Fetch https://queue.taskcluster.net/v1/task/Rez5mHwMQUqZOnNOuERCiQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:56:19 INFO - Http code: 200 02:56:19 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 02:56:19 INFO - x-amz-cf-id: olNCqLAZCwX2MFKjQD8Co-SoYsqxLqo2BBDoaYrLVP5EXJalNLWELA== 02:56:19 INFO - x-amz-version-id: g.DkRtTIx3XdQlom3OiLLEO7fPTKgWRr 02:56:19 INFO - Expected file size: 16276104 02:56:19 INFO - Obtained file size: 16276104 02:56:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Rez5mHwMQUqZOnNOuERCiQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 02:56:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rez5mHwMQUqZOnNOuERCiQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:56:25 INFO - Fetch https://queue.taskcluster.net/v1/task/Rez5mHwMQUqZOnNOuERCiQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 02:56:26 INFO - Http code: 200 02:56:26 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 02:56:26 INFO - x-amz-cf-id: NXfNBNXP5_CCOdDKrTuSjJtPf7Ta5dDb83nSYutgrygHR5wH9nivbw== 02:56:26 INFO - x-amz-version-id: nkQuakfLlhv8nZdGRf.V3Q3HW1lqfBo. 02:56:27 INFO - Expected file size: 14409038 02:56:27 INFO - Obtained file size: 14409038 02:56:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:56:27 INFO - trying https://queue.taskcluster.net/v1/task/Rez5mHwMQUqZOnNOuERCiQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:56:27 INFO - Downloading https://queue.taskcluster.net/v1/task/Rez5mHwMQUqZOnNOuERCiQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 02:56:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Rez5mHwMQUqZOnNOuERCiQ/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 02:56:30 INFO - Downloaded 110061247 bytes. 02:56:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Rez5mHwMQUqZOnNOuERCiQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:56:30 INFO - mkdir: /builds/slave/test/properties 02:56:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:56:30 INFO - Writing to file /builds/slave/test/properties/build_url 02:56:30 INFO - Contents: 02:56:30 INFO - build_url:https://queue.taskcluster.net/v1/task/Rez5mHwMQUqZOnNOuERCiQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:56:31 INFO - Running post-action listener: _resource_record_post_action 02:56:31 INFO - Running post-action listener: set_extra_try_arguments 02:56:31 INFO - [mozharness: 2017-01-18 10:56:31.010672Z] Finished download-and-extract step (success) 02:56:31 INFO - [mozharness: 2017-01-18 10:56:31.010796Z] Running populate-webroot step. 02:56:31 INFO - Running pre-action listener: _resource_record_pre_action 02:56:31 INFO - Running main action method: populate_webroot 02:56:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:56:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:56:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:56:31 INFO - '--tppagecycles', 02:56:31 INFO - '7'], 02:56:31 INFO - 'tests': ['tsvgm']}}, 02:56:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:56:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:56:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:56:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:56:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:56:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:56:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:56:31 INFO - 'talos_options': ['--disable-e10s'], 02:56:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:56:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:56:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:56:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:56:31 INFO - 'talos_options': ['--disable-e10s'], 02:56:31 INFO - 'tests': ['damp', 'tps']}, 02:56:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:56:31 INFO - 'tests': ['damp', 'tps']}, 02:56:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:56:31 INFO - 'tests': ['dromaeo_dom']}, 02:56:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:56:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:56:31 INFO - 'tests': ['basic_compositor_video']}, 02:56:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:56:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:56:31 INFO - 'tests': ['a11yr', 02:56:31 INFO - 'ts_paint', 02:56:31 INFO - 'tpaint', 02:56:31 INFO - 'sessionrestore', 02:56:31 INFO - 'sessionrestore_no_auto_restore', 02:56:31 INFO - 'tabpaint']}, 02:56:31 INFO - 'other-e10s': {'tests': ['a11yr', 02:56:31 INFO - 'ts_paint', 02:56:31 INFO - 'tpaint', 02:56:31 INFO - 'sessionrestore', 02:56:31 INFO - 'sessionrestore_no_auto_restore', 02:56:31 INFO - 'tabpaint']}, 02:56:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:56:31 INFO - 'ts_paint', 02:56:31 INFO - 'tpaint', 02:56:31 INFO - 'sessionrestore', 02:56:31 INFO - 'sessionrestore_no_auto_restore', 02:56:31 INFO - 'tabpaint']}, 02:56:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:56:31 INFO - 'ts_paint', 02:56:31 INFO - 'tpaint', 02:56:31 INFO - 'sessionrestore', 02:56:31 INFO - 'sessionrestore_no_auto_restore', 02:56:31 INFO - 'tabpaint']}, 02:56:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:56:31 INFO - 'tests': ['a11yr', 02:56:31 INFO - 'ts_paint', 02:56:31 INFO - 'tpaint', 02:56:31 INFO - 'sessionrestore', 02:56:31 INFO - 'sessionrestore_no_auto_restore', 02:56:31 INFO - 'tabpaint']}, 02:56:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:56:31 INFO - 'tests': ['a11yr', 02:56:31 INFO - 'ts_paint', 02:56:31 INFO - 'tpaint', 02:56:31 INFO - 'sessionrestore', 02:56:31 INFO - 'sessionrestore_no_auto_restore', 02:56:31 INFO - 'tabpaint']}, 02:56:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:56:31 INFO - 'tests': ['tsvgx', 02:56:31 INFO - 'tsvgr_opacity', 02:56:31 INFO - 'tart', 02:56:31 INFO - 'tscrollx', 02:56:31 INFO - 'cart']}, 02:56:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:56:31 INFO - 'tsvgr_opacity', 02:56:31 INFO - 'tart', 02:56:31 INFO - 'tscrollx', 02:56:31 INFO - 'cart']}, 02:56:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:56:31 INFO - 'talos_options': ['--disable-e10s'], 02:56:31 INFO - 'tests': ['tp5o']}, 02:56:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:56:31 INFO - 'tests': ['tp5o']}, 02:56:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:56:31 INFO - 'talos_options': ['--disable-e10s', 02:56:31 INFO - '--xperf_path', 02:56:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:56:31 INFO - 'tests': ['tp5n']}, 02:56:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:56:31 INFO - 'talos_options': ['--xperf_path', 02:56:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:56:31 INFO - 'tests': ['tp5n']}}, 02:56:31 INFO - 'talos.zip': {'path': '', 02:56:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:56:31 INFO - Running post-action listener: _resource_record_post_action 02:56:31 INFO - [mozharness: 2017-01-18 10:56:31.028976Z] Finished populate-webroot step (success) 02:56:31 INFO - [mozharness: 2017-01-18 10:56:31.029057Z] Running create-virtualenv step. 02:56:31 INFO - Running pre-action listener: _resource_record_pre_action 02:56:31 INFO - Running main action method: create_virtualenv 02:56:31 INFO - Creating virtualenv /builds/slave/test/build/venv 02:56:31 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 02:56:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:56:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:56:31 INFO - Using real prefix '/tools/python27' 02:56:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:56:31 INFO - Installing distribute.............................................................................................................................................................................................done. 02:56:34 INFO - Installing pip.................done. 02:56:34 INFO - Return code: 0 02:56:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:56:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:56:35 INFO - Reading from file tmpfile_stdout 02:56:35 INFO - Output received: 02:56:35 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) 02:56:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:56:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:56:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:56:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:35 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 0x1046c4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10463a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa12bd72200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044d8d30>, '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 0x10433edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044d0b20>, '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.ToVBQWsph6/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.vxzxfXIi35/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 02:56:35 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 02:56:35 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 02:56:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ToVBQWsph6/Render', 02:56:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:56:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:56:35 INFO - 'HOME': '/Users/cltbld', 02:56:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:56:35 INFO - 'LOGNAME': 'cltbld', 02:56:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:35 INFO - 'MOZ_NO_REMOTE': '1', 02:56:35 INFO - 'NO_EM_RESTART': '1', 02:56:35 INFO - 'PAGER': '/bin/cat', 02:56:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:56:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:35 INFO - 'PWD': '/builds/slave/test', 02:56:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:56:35 INFO - 'SHELL': '/bin/bash', 02:56:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vxzxfXIi35/Listeners', 02:56:35 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 02:56:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:56:35 INFO - 'USER': 'cltbld', 02:56:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:56:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:56:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:35 INFO - 'XPC_FLAGS': '0x0', 02:56:35 INFO - 'XPC_SERVICE_NAME': '0', 02:56:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:56:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:56:35 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 02:56:35 INFO - Cleaning up... 02:56:35 INFO - Return code: 0 02:56:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:56:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:56:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:56:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:35 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 0x1046c4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10463a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa12bd72200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044d8d30>, '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 0x10433edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044d0b20>, '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.ToVBQWsph6/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.vxzxfXIi35/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 02:56:35 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 02:56:35 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 02:56:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ToVBQWsph6/Render', 02:56:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:56:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:56:35 INFO - 'HOME': '/Users/cltbld', 02:56:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:56:35 INFO - 'LOGNAME': 'cltbld', 02:56:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:35 INFO - 'MOZ_NO_REMOTE': '1', 02:56:35 INFO - 'NO_EM_RESTART': '1', 02:56:35 INFO - 'PAGER': '/bin/cat', 02:56:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:56:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:35 INFO - 'PWD': '/builds/slave/test', 02:56:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:56:35 INFO - 'SHELL': '/bin/bash', 02:56:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vxzxfXIi35/Listeners', 02:56:35 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 02:56:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:56:35 INFO - 'USER': 'cltbld', 02:56:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:56:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:56:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:35 INFO - 'XPC_FLAGS': '0x0', 02:56:35 INFO - 'XPC_SERVICE_NAME': '0', 02:56:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:56:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:56:35 INFO - Downloading/unpacking psutil>=3.1.1 02:56:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:56:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:56:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:56:39 INFO - Installing collected packages: psutil 02:56:39 INFO - Running setup.py install for psutil 02:56:39 INFO - building 'psutil._psutil_osx' extension 02:56:39 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 02:56:40 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 02:56:40 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 02:56:40 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 02:56:40 INFO - building 'psutil._psutil_posix' extension 02:56:40 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 02:56:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:56:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:56:40 INFO - ^ 02:56:40 INFO - 1 warning generated. 02:56:40 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 02:56:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:56:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:56:40 INFO - Successfully installed psutil 02:56:40 INFO - Cleaning up... 02:56:40 INFO - Return code: 0 02:56:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:56: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')]} 02:56:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56: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')]} 02:56:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1046c4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10463a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa12bd72200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044d8d30>, '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 0x10433edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044d0b20>, '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.ToVBQWsph6/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.vxzxfXIi35/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 02:56:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:56:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:56:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ToVBQWsph6/Render', 02:56:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:56:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:56:40 INFO - 'HOME': '/Users/cltbld', 02:56:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:56:40 INFO - 'LOGNAME': 'cltbld', 02:56:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:40 INFO - 'MOZ_NO_REMOTE': '1', 02:56:40 INFO - 'NO_EM_RESTART': '1', 02:56:40 INFO - 'PAGER': '/bin/cat', 02:56:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:56:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:40 INFO - 'PWD': '/builds/slave/test', 02:56:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:56:40 INFO - 'SHELL': '/bin/bash', 02:56:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vxzxfXIi35/Listeners', 02:56:40 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 02:56:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:56:40 INFO - 'USER': 'cltbld', 02:56:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:56:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:56:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:40 INFO - 'XPC_FLAGS': '0x0', 02:56:40 INFO - 'XPC_SERVICE_NAME': '0', 02:56:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:56:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:56:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:56:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:56:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:56:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:56:43 INFO - Installing collected packages: mozsystemmonitor 02:56:43 INFO - Running setup.py install for mozsystemmonitor 02:56:44 INFO - Successfully installed mozsystemmonitor 02:56:44 INFO - Cleaning up... 02:56:44 INFO - Return code: 0 02:56:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:56:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:56:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:56:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:44 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 0x1046c4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10463a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa12bd72200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044d8d30>, '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 0x10433edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044d0b20>, '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.ToVBQWsph6/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.vxzxfXIi35/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 02:56:44 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 02:56:44 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 02:56:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ToVBQWsph6/Render', 02:56:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:56:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:56:44 INFO - 'HOME': '/Users/cltbld', 02:56:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:56:44 INFO - 'LOGNAME': 'cltbld', 02:56:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:44 INFO - 'MOZ_NO_REMOTE': '1', 02:56:44 INFO - 'NO_EM_RESTART': '1', 02:56:44 INFO - 'PAGER': '/bin/cat', 02:56:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:56:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:44 INFO - 'PWD': '/builds/slave/test', 02:56:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:56:44 INFO - 'SHELL': '/bin/bash', 02:56:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vxzxfXIi35/Listeners', 02:56:44 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 02:56:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:56:44 INFO - 'USER': 'cltbld', 02:56:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:56:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:56:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:44 INFO - 'XPC_FLAGS': '0x0', 02:56:44 INFO - 'XPC_SERVICE_NAME': '0', 02:56:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:56:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:56:44 INFO - Downloading/unpacking jsonschema==2.5.1 02:56:44 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 02:56:44 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 02:56:44 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 02:56:44 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 02:56:44 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 02:56:44 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 02:56:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:56:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:56:47 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 02:56:47 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 02:56:47 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 02:56:47 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 02:56:47 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 02:56:47 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 02:56:47 INFO - Downloading functools32-3.2.3-2.tar.gz 02:56:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:56:47 INFO - warning: no files found matching '*.txt' 02:56:47 INFO - no previously-included directories found matching 'build' 02:56:47 INFO - no previously-included directories found matching 'dist' 02:56:47 INFO - no previously-included directories found matching '.git*' 02:56:47 INFO - Installing collected packages: jsonschema, functools32 02:56:47 INFO - Running setup.py install for functools32 02:56:47 INFO - warning: no files found matching '*.txt' 02:56:47 INFO - no previously-included directories found matching 'build' 02:56:47 INFO - no previously-included directories found matching 'dist' 02:56:47 INFO - no previously-included directories found matching '.git*' 02:56:47 INFO - Successfully installed jsonschema functools32 02:56:47 INFO - Cleaning up... 02:56:47 INFO - Return code: 0 02:56:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:56:47 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')]} 02:56:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:47 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')]} 02:56:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:47 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 0x1046c4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10463a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa12bd72200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044d8d30>, '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 0x10433edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044d0b20>, '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.ToVBQWsph6/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.vxzxfXIi35/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 02:56:47 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 02:56:47 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 02:56:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ToVBQWsph6/Render', 02:56:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:56:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:56:47 INFO - 'HOME': '/Users/cltbld', 02:56:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:56:47 INFO - 'LOGNAME': 'cltbld', 02:56:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:47 INFO - 'MOZ_NO_REMOTE': '1', 02:56:47 INFO - 'NO_EM_RESTART': '1', 02:56:47 INFO - 'PAGER': '/bin/cat', 02:56:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:56:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:47 INFO - 'PWD': '/builds/slave/test', 02:56:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:56:47 INFO - 'SHELL': '/bin/bash', 02:56:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vxzxfXIi35/Listeners', 02:56:47 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 02:56:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:56:47 INFO - 'USER': 'cltbld', 02:56:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:56:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:56:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:47 INFO - 'XPC_FLAGS': '0x0', 02:56:47 INFO - 'XPC_SERVICE_NAME': '0', 02:56:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:56:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:56:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:56:48 INFO - Cleaning up... 02:56:48 INFO - Return code: 0 02:56:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:56: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')]} 02:56:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56: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')]} 02:56:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56: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', '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 0x1046c4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10463a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa12bd72200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044d8d30>, '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 0x10433edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044d0b20>, '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.ToVBQWsph6/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.vxzxfXIi35/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 02:56: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:56: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 blobuploader==1.2.4 02:56:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ToVBQWsph6/Render', 02:56:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:56:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:56:48 INFO - 'HOME': '/Users/cltbld', 02:56:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:56:48 INFO - 'LOGNAME': 'cltbld', 02:56:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:48 INFO - 'MOZ_NO_REMOTE': '1', 02:56:48 INFO - 'NO_EM_RESTART': '1', 02:56:48 INFO - 'PAGER': '/bin/cat', 02:56:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:56:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:48 INFO - 'PWD': '/builds/slave/test', 02:56:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:56:48 INFO - 'SHELL': '/bin/bash', 02:56:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vxzxfXIi35/Listeners', 02:56:48 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 02:56:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:56:48 INFO - 'USER': 'cltbld', 02:56:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:56:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:56:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:48 INFO - 'XPC_FLAGS': '0x0', 02:56:48 INFO - 'XPC_SERVICE_NAME': '0', 02:56:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:56:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:56:48 INFO - Downloading/unpacking blobuploader==1.2.4 02:56:48 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 02:56:48 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 02:56:48 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 02:56:48 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 02:56:48 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 02:56:48 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 02:56:50 INFO - Downloading blobuploader-1.2.4.tar.gz 02:56:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:56:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:56:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:56:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:56:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:56:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:56:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:56:52 INFO - Downloading docopt-0.6.1.tar.gz 02:56:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:56:52 INFO - Installing collected packages: blobuploader, requests, docopt 02:56:52 INFO - Running setup.py install for blobuploader 02:56:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:56:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:56:52 INFO - Running setup.py install for requests 02:56:52 INFO - Running setup.py install for docopt 02:56:53 INFO - Successfully installed blobuploader requests docopt 02:56:53 INFO - Cleaning up... 02:56:53 INFO - Return code: 0 02:56:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:56: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')]} 02:56:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56: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')]} 02:56:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:53 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 0x1046c4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10463a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa12bd72200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044d8d30>, '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 0x10433edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044d0b20>, '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.ToVBQWsph6/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.vxzxfXIi35/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 02:56:53 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 02:56:53 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 02:56:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ToVBQWsph6/Render', 02:56:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:56:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:56:53 INFO - 'HOME': '/Users/cltbld', 02:56:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:56:53 INFO - 'LOGNAME': 'cltbld', 02:56:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:53 INFO - 'MOZ_NO_REMOTE': '1', 02:56:53 INFO - 'NO_EM_RESTART': '1', 02:56:53 INFO - 'PAGER': '/bin/cat', 02:56:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:56:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:53 INFO - 'PWD': '/builds/slave/test', 02:56:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:56:53 INFO - 'SHELL': '/bin/bash', 02:56:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vxzxfXIi35/Listeners', 02:56:53 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 02:56:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:56:53 INFO - 'USER': 'cltbld', 02:56:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:56:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:56:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:53 INFO - 'XPC_FLAGS': '0x0', 02:56:53 INFO - 'XPC_SERVICE_NAME': '0', 02:56:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:56:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:56:53 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-xbSxnO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:56:53 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-bQUXwD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:56:53 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-86Qnrq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:56:53 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-vsWESs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:56:53 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-dBC5XR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:56:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:56:53 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-sKJ2dH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:56:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:56:54 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-QLjhaq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:56:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:56:54 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-HcibcU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:56:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:56:54 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-h77Ql1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:56:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:56:54 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-wlB6HL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:56:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:56:54 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-Cx7RWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:56:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:56:54 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-IiV4hI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:56:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:56:54 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-OF3PWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:56:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:56:54 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-zt6GGd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:56:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:56:54 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-2rZiyf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:56:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:56:55 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-ynuDbP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:56:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:56:55 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-FFEXxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:56:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:56:55 INFO - Running setup.py install for manifestparser 02:56:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:56:55 INFO - Running setup.py install for mozcrash 02:56:55 INFO - Running setup.py install for mozdebug 02:56:55 INFO - Running setup.py install for mozdevice 02:56:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:56:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:56:55 INFO - Running setup.py install for mozfile 02:56:55 INFO - Running setup.py install for mozhttpd 02:56:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:56:56 INFO - Running setup.py install for mozinfo 02:56:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:56:56 INFO - Running setup.py install for mozInstall 02:56:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:56:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:56:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:56:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:56:56 INFO - Running setup.py install for mozleak 02:56:56 INFO - Running setup.py install for mozlog 02:56:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:56:56 INFO - Running setup.py install for moznetwork 02:56:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:56:56 INFO - Running setup.py install for mozprocess 02:56:57 INFO - Running setup.py install for mozprofile 02:56:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:56:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:56:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:56:57 INFO - Running setup.py install for mozrunner 02:56:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:56:57 INFO - Running setup.py install for mozscreenshot 02:56:57 INFO - Running setup.py install for moztest 02:56:57 INFO - Running setup.py install for mozversion 02:56:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:56:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:56:57 INFO - Cleaning up... 02:56:57 INFO - Return code: 0 02:56:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:56: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')]} 02:56:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:56:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56: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')]} 02:56:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:56:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:56:57 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 0x1046c4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10463a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa12bd72200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044d8d30>, '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 0x10433edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044d0b20>, '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.ToVBQWsph6/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.vxzxfXIi35/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 02:56:57 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 02:56:57 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 02:56:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ToVBQWsph6/Render', 02:56:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:56:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:56:57 INFO - 'HOME': '/Users/cltbld', 02:56:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:56:57 INFO - 'LOGNAME': 'cltbld', 02:56:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:57 INFO - 'MOZ_NO_REMOTE': '1', 02:56:57 INFO - 'NO_EM_RESTART': '1', 02:56:57 INFO - 'PAGER': '/bin/cat', 02:56:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:56:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:57 INFO - 'PWD': '/builds/slave/test', 02:56:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:56:57 INFO - 'SHELL': '/bin/bash', 02:56:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vxzxfXIi35/Listeners', 02:56:57 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 02:56:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:56:57 INFO - 'USER': 'cltbld', 02:56:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:56:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:56:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:57 INFO - 'XPC_FLAGS': '0x0', 02:56:57 INFO - 'XPC_SERVICE_NAME': '0', 02:56:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:56:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:56:58 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-JJjfT7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:56:58 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)) 02:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:56:58 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-u6_Ojt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:56:58 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)) 02:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:56:58 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-YEzfRc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:56:58 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)) 02:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:56:58 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-SxPajQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:56:58 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)) 02:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:56:58 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-ik8qzv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:56:58 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)) 02:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:56:58 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-HrsCM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:56:58 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)) 02:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:56:58 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-ZJiQDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:56:58 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)) 02:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:56:58 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-L5MWOZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:56:58 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)) 02:56:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:56:58 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-Wgq0RH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:56:59 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)) 02:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:56:59 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-tZbKVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:56:59 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)) 02:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:56:59 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-PnNdh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:56:59 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)) 02:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:56:59 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-7jOwyh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:56:59 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)) 02:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:56:59 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-O5u_rJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:56:59 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)) 02:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:56:59 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-w44Jp1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:56:59 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)) 02:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:56:59 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-Dnm8ql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:56:59 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)) 02:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:56:59 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-ueL9Us-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:56:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 02:56:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:56:59 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-U4zmU5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:56:59 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)) 02:56:59 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)) 02:56:59 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)) 02:56:59 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)) 02:56:59 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)) 02:56:59 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)) 02:56:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:56:59 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 02:56:59 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 02:56:59 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 02:56:59 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 02:56:59 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 02:56:59 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 02:57:02 INFO - Downloading blessings-1.6.tar.gz 02:57:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:57:02 INFO - Installing collected packages: blessings 02:57:02 INFO - Running setup.py install for blessings 02:57:02 INFO - Successfully installed blessings 02:57:02 INFO - Cleaning up... 02:57:02 INFO - Return code: 0 02:57:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:57:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:57:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:57:03 INFO - Reading from file tmpfile_stdout 02:57:03 INFO - Current package versions: 02:57:03 INFO - blessings == 1.6 02:57:03 INFO - blobuploader == 1.2.4 02:57:03 INFO - docopt == 0.6.1 02:57:03 INFO - functools32 == 3.2.3-2 02:57:03 INFO - jsonschema == 2.5.1 02:57:03 INFO - manifestparser == 1.1 02:57:03 INFO - mozInstall == 1.12 02:57:03 INFO - mozcrash == 1.0 02:57:03 INFO - mozdebug == 0.1 02:57:03 INFO - mozdevice == 0.48 02:57:03 INFO - mozfile == 1.2 02:57:03 INFO - mozhttpd == 0.7 02:57:03 INFO - mozinfo == 0.9 02:57:03 INFO - mozleak == 0.1 02:57:03 INFO - mozlog == 3.4 02:57:03 INFO - moznetwork == 0.27 02:57:03 INFO - mozprocess == 0.23 02:57:03 INFO - mozprofile == 0.28 02:57:03 INFO - mozrunner == 6.13 02:57:03 INFO - mozscreenshot == 0.1 02:57:03 INFO - mozsystemmonitor == 0.3 02:57:03 INFO - moztest == 0.8 02:57:03 INFO - mozversion == 1.4 02:57:03 INFO - psutil == 3.1.1 02:57:03 INFO - requests == 1.2.3 02:57:03 INFO - wsgiref == 0.1.2 02:57:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:57:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:03 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 0x1046c4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10463a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa12bd72200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044d8d30>, '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 0x10433edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044d0b20>, '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.ToVBQWsph6/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.vxzxfXIi35/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 02:57:03 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 02:57:03 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 02:57:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ToVBQWsph6/Render', 02:57:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:03 INFO - 'HOME': '/Users/cltbld', 02:57:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:03 INFO - 'LOGNAME': 'cltbld', 02:57:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:03 INFO - 'MOZ_NO_REMOTE': '1', 02:57:03 INFO - 'NO_EM_RESTART': '1', 02:57:03 INFO - 'PAGER': '/bin/cat', 02:57:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:03 INFO - 'PWD': '/builds/slave/test', 02:57:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:03 INFO - 'SHELL': '/bin/bash', 02:57:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vxzxfXIi35/Listeners', 02:57:03 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 02:57:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:03 INFO - 'USER': 'cltbld', 02:57:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:03 INFO - 'XPC_FLAGS': '0x0', 02:57:03 INFO - 'XPC_SERVICE_NAME': '0', 02:57:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:03 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)) 02:57:03 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)) 02:57:03 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)) 02:57:03 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)) 02:57:03 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)) 02:57:03 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)) 02:57:03 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)) 02:57:03 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)) 02:57:03 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)) 02:57:03 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)) 02:57:03 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)) 02:57:03 INFO - Cleaning up... 02:57:03 INFO - Return code: 0 02:57:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:57:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:03 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 0x1046c4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10463a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa12bd72200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044d8d30>, '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 0x10433edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044d0b20>, '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.ToVBQWsph6/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.vxzxfXIi35/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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 02:57:03 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 02:57:03 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 02:57:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ToVBQWsph6/Render', 02:57:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:03 INFO - 'HOME': '/Users/cltbld', 02:57:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:03 INFO - 'LOGNAME': 'cltbld', 02:57:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:03 INFO - 'MOZ_NO_REMOTE': '1', 02:57:03 INFO - 'NO_EM_RESTART': '1', 02:57:03 INFO - 'PAGER': '/bin/cat', 02:57:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:03 INFO - 'PWD': '/builds/slave/test', 02:57:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:03 INFO - 'SHELL': '/bin/bash', 02:57:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vxzxfXIi35/Listeners', 02:57:03 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 02:57:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:03 INFO - 'USER': 'cltbld', 02:57:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:03 INFO - 'XPC_FLAGS': '0x0', 02:57:03 INFO - 'XPC_SERVICE_NAME': '0', 02:57:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:57:03 INFO - Cleaning up... 02:57:03 INFO - Return code: 0 02:57:03 INFO - Running post-action listener: _resource_record_post_action 02:57:03 INFO - Running post-action listener: _start_resource_monitoring 02:57:03 INFO - Starting resource monitoring. 02:57:03 INFO - [mozharness: 2017-01-18 10:57:03.749282Z] Finished create-virtualenv step (success) 02:57:03 INFO - [mozharness: 2017-01-18 10:57:03.750351Z] Running install step. 02:57:03 INFO - Running pre-action listener: _resource_record_pre_action 02:57:03 INFO - Running main action method: install 02:57:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:57:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:57:04 INFO - Reading from file tmpfile_stdout 02:57:04 INFO - Detecting whether we're running mozinstall >=1.0... 02:57:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:57:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:57:04 INFO - Reading from file tmpfile_stdout 02:57:04 INFO - Output received: 02:57:04 INFO - Usage: mozinstall [options] installer 02:57:04 INFO - Options: 02:57:04 INFO - -h, --help show this help message and exit 02:57:04 INFO - -d DEST, --destination=DEST 02:57:04 INFO - Directory to install application into. [default: 02:57:04 INFO - "/builds/slave/test"] 02:57:04 INFO - --app=APP Application being installed. [default: firefox] 02:57:04 INFO - mkdir: /builds/slave/test/build/application 02:57:04 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'] 02:57:04 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 02:57:34 INFO - Reading from file tmpfile_stdout 02:57:34 INFO - Output received: 02:57:34 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:57:34 INFO - Running post-action listener: _resource_record_post_action 02:57:34 INFO - [mozharness: 2017-01-18 10:57:34.883120Z] Finished install step (success) 02:57:34 INFO - [mozharness: 2017-01-18 10:57:34.883232Z] Running run-tests step. 02:57:34 INFO - Running pre-action listener: _resource_record_pre_action 02:57:34 INFO - Running main action method: run_tests 02:57:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:57:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:57:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:57:34 INFO - 2017-01-18 02:57:34.897 screenresolution[1630:8412] starting screenresolution argv=screenresolution get 02:57:34 INFO - 2017-01-18 02:57:34.900 screenresolution[1630:8412] Display 0: 1600x1200x32@60 02:57:34 INFO - 2017-01-18 02:57:34.906 screenresolution[1631:8414] starting screenresolution argv=screenresolution list 02:57:34 INFO - Available Modes on Display 0 02:57:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:57:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:57:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:57:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:57:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:57:35 INFO - Intel Iris: 02:57:35 INFO - Chipset Model: Intel Iris 02:57:35 INFO - Type: GPU 02:57:35 INFO - Bus: Built-In 02:57:35 INFO - VRAM (Dynamic, Max): 1536 MB 02:57:35 INFO - Vendor: Intel (0x8086) 02:57:35 INFO - Device ID: 0x0a2e 02:57:35 INFO - Revision ID: 0x0009 02:57:35 INFO - Displays: 02:57:35 INFO - Display: 02:57:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:57:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:57:35 INFO - Display Serial Number: 1600x1200 60 02:57:35 INFO - Main Display: Yes 02:57:35 INFO - Mirror: Off 02:57:35 INFO - Online: Yes 02:57:35 INFO - Rotation: Supported 02:57:35 INFO - Return code: 0 02:57:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:57:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:57:35 INFO - Python 2.7.3 02:57:35 INFO - Return code: 0 02:57:35 INFO - grabbing minidump binary from tooltool 02:57:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:35 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 0x1044d8d30>, '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 0x10433edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1044d0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:57:35 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 02:57:35 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 02:57:35 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 02:57:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:57:35 INFO - Return code: 0 02:57:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:57:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:57:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:57:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:57:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:57:35 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/Rez5mHwMQUqZOnNOuERCiQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0332', '--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 02:57:35 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/Rez5mHwMQUqZOnNOuERCiQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0332 --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 02:57:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ToVBQWsph6/Render', 02:57:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:35 INFO - 'HOME': '/Users/cltbld', 02:57:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:35 INFO - 'LOGNAME': 'cltbld', 02:57:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:57:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:57:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:35 INFO - 'MOZ_NO_REMOTE': '1', 02:57:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:57:35 INFO - 'NO_EM_RESTART': '1', 02:57:35 INFO - 'PAGER': '/bin/cat', 02:57:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:35 INFO - 'PWD': '/builds/slave/test', 02:57:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:57:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:35 INFO - 'SHELL': '/bin/bash', 02:57:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vxzxfXIi35/Listeners', 02:57:35 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 02:57:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:35 INFO - 'USER': 'cltbld', 02:57:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:35 INFO - 'XPC_FLAGS': '0x0', 02:57:35 INFO - 'XPC_SERVICE_NAME': '0', 02:57:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:35 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/Rez5mHwMQUqZOnNOuERCiQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0332', '--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 02:57:35 INFO - mozversion application_buildid: 20170118004007 02:57:35 INFO - mozversion application_changeset: fd66a1e49411faca500b26ba9ca7dd5a311ff6e5 02:57:35 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:57:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:57:35 INFO - mozversion application_name: Firefox 02:57:35 INFO - mozversion application_remotingname: firefox-dev 02:57:35 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:57:35 INFO - mozversion application_vendor: Mozilla 02:57:35 INFO - mozversion application_version: 52.0a2 02:57:35 INFO - mozversion platform_buildid: 20170118004007 02:57:35 INFO - mozversion platform_changeset: fd66a1e49411faca500b26ba9ca7dd5a311ff6e5 02:57:35 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:57:35 INFO - mozversion platform_version: 52.0a2 02:57:35 INFO - using testdate: 1484737055 02:57:35 INFO - actual date: 1484737055 02:57:35 INFO - starting webserver on 'localhost:49240' 02:57:35 INFO - SUITE-START | Running 2 tests 02:57:35 INFO - TEST-START | dromaeo_css 02:57:35 INFO - operating with platform_type : mac_ 02:57:35 INFO - Initialising browser for dromaeo_css test... 02:57:35 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/tmpu96KS_/profile http://localhost:49240/getInfo.html) 02:57:38 INFO - PROCESS | 1641 | __metrics Screen width/height:1600/1200 02:57:38 INFO - PROCESS | 1641 | colorDepth:24 02:57:38 INFO - PROCESS | 1641 | Browser inner width/height: 1024/689 02:57:38 INFO - PROCESS | 1641 | __metrics 02:57:38 INFO - PROCESS | 1641 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:57:38 INFO - PROCESS | 1641 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:57:39 INFO - TEST-INFO | 1641: exit 0 02:57:39 INFO - Browser initialized. 02:57:39 INFO - Running cycle 1/1 for dromaeo_css test... 02:57:39 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/tmpu96KS_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:57:51 INFO - PROCESS | 1645 | RSS: Main: 221782016 02:57:51 INFO - PROCESS | 1645 | 03:00:55 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 03:00:57 INFO - PROCESS | 1645 | RSS: Main: 370192384 03:00:57 INFO - PROCESS | 1645 | 03:04:00 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 03:04:00 INFO - PROCESS | 1645 | RSS: Main: 347099136 03:04:00 INFO - PROCESS | 1645 | 03:07:03 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 03:07:03 INFO - PROCESS | 1645 | RSS: Main: 315650048 03:07:03 INFO - PROCESS | 1645 | 03:10:06 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 03:10:06 INFO - PROCESS | 1645 | RSS: Main: 308461568 03:10:06 INFO - PROCESS | 1645 | 03:13:10 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 03:13:10 INFO - PROCESS | 1645 | RSS: Main: 306921472 03:13:10 INFO - PROCESS | 1645 | 03:16:13 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 03:16:14 INFO - PROCESS | 1645 | RSS: Main: 358260736 03:16:14 INFO - PROCESS | 1645 | 03:16:14 INFO - PROCESS | 1645 | __start_tp_report 03:16:14 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 03:16:14 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 03:16:14 INFO - PROCESS | 1645 | |i|pagename|runs| 03:16:14 INFO - PROCESS | 1645 | |0;dojo.html;3345.7992520448774;3357.496375543669;3368.730501559874;3373.89878303651;3432.1419645088727;5726.713664316783;5783.334916484606;5788.131780232962;5790.623609465381;5791.507721843648;5723.713814309278;5734.99999999999;5815.418458154193;5845.7953971611005;5854.443827836349;5252.947470525284;5294.867628309285;5322.680639161644;5342.893142137155;5389.892202155974;4092.283850326196;5226.451222621627;5234.110201265775;5289.338832645919;5394.056040192951;5236.083685355066;5299.019681358942;5324.254604355393;5362.624616276862;5379.892402151954;5334.093204155303;5337.733113344337;5348.5186333230195;5350.49170328822;5355.036093503168;5144.0226356992425;5261.86845328866;5303.920441193385;5317.042932272189;5346.117890548055;5664.830055098354;5694.629849059798;5740.196372507852;5791.478766910998;5804.1293805929445;6227.252729672423;6236.189295391616;6278.748850045993;6279.0895320178315;6288.559800813945;6276.937230627681;6306.590071645374;6325.367463253684;6329.082283068975;6350.745970161188;5706.999999999958;5774.855628609234;5802.303723553158;5821.388754180834;5869.178315035899;6285.434310911948;6296.779612713624;6315.873682526416;6323.9051414228825;6351.142595749607;6208.565400421927;6234.1583886174785;6238.251409830849;6242.937570624235;6247.937520624827;6139.8908086453375;6207.751689932352;6211.09939058834;6282.371762823681;6321.841953951188;6277.278113016971;6293;6302.275238347557;6305.653189074553;6325.082862984841;6253.499720022387;6263.091851681481;6285.465735412449;6315.431611155018;6328;4864.246041863516;4922.803087876445;4997.7251251180805;4998.975005124951;5025.422076461254;4399.5160532341415;4457.77711114443;4471.306947423154;4472.105578884236;4474.149911516802;5891.499222566044;5893.351731309553;5896.616719913192;5897.52819774417;5917.171595976567;6201.906971395432;6245.656488893063;6253.78111766086;6262.2172228471445;6273.937260627426;5683.318001839806;5744.167095771089;5757.280339957505;5839.824805255849;5905.936931352312;5419.403865574784;5463.863403414946;5471.042567550663;5486.506214440719;5524;6370.522210834113;6400.935990640127;6411.967940160176;6415.582987105823;6466.127072845105;6167.660778657217;6276.184096067482;6281.654509001866;6285.120083188266;6317.4630156437215;6108.389161083856;6285.214348206474;6326.778562750282;6341.714622841891;6428.646424446701;6272.18461599989;6274.435300822948;6284.74861005573;6291.559590828598;6304.5271604628915;6244.094606282155;6264.561480696308;6300.7479700811455;6304.369563043659;6314.242290925118;6025.126356678345;6091.969540152271;6109.236345456818;6114.5414093942245;6153.446189842935;6180.505559555315;6219.906701399392;6222.191115155001;6225.346338634378;6226.813195604139;5843.386444423444;6069.2716873975405;6077.544184186292;6080.4831589313635;6103.084537319438;6022.939770602238;6088.847778805388;6103.6032657877095;6154.661493617894;6235.688215589294;4642.628589712883;5386.757595908115;5857.47282744555;5967;6021.277446706424 03:16:14 INFO - PROCESS | 1645 | |1;ext.html;465.5088881230302;500.6295341447329;518.885845114075;531.5269410224901;551.1264645536824;29707.85146074269;31773;31799.841000794993;31851.68148318519;39294.60705392945;33150.33699326012;41298.58701412985;41670.37494437586;41868.65050795937;41886.58113418865;9253;9415.152636262736;9418.387804792701;9432.339736218468;9449.858252126223;8363.874541881876;8615.827683446327;9317.208037316836;9421.905780942207;9450;11146.442677866115;11392;11434.199606027581;11564.363959982198;11593;10219.540120694586;11198.272112312674;11207.831882521768;11304.943475282571;11335.433228338592;11105;11117.72205694856;11142.665720028332;11186.384748838811;11234.719132021683;39074.41388379176;39201.80399097986;39227;39246.607533924864;39259.214815703526;3146.08763458599;3208.679132086796;3244.707976282146;3432.433648448003;3835.3096442640312;28914.855425722948;29944;29980.400391992258;30457.543136852964;30728.078157655305;2775.111964171466;3103.5499852521484;3108.689131086896;3191.1064901827526;3283.885064022748;454651;454814;456155;456804;462972;32709.836450817595;33774.49338259928;59970;60167.796644066875;60359.69820150959;31715.57279922409;33011.504827427605;34328;34784.30431391358;34833.30333393318;13020;13358.864496517905;14073.929630351782;14136.363863626162;14166.787498187536;12233.877661223452;12333.753324933452;12337.753244935051;12340.691482713004;12357.196782209128;7278.96360518194;7428.517146385468;7449.404047676173;7502.699892004259;7713.6914523419555;3023.8639261233297;3177;3255.348930213919;3295.6210035846184;3296.9175770605925;840.6007146605435;892.830362231174;930.6742640076054;937.7561833923229;957.7174733453647;28274.151775446768;28499.287517811805;28664.71335286662;29174.7082529172;29604.55593166104;843.9113893041265;996.4668902137466;1049.4700176411015;1077.6820837852852;1104.7679987202616;15153;15339.846601534065;15369;15421.383144674312;15434.845651543565;642680;651947;652021;654608;655259;47761.76119119382;56597.43402566004;57058.7147064262;57084.71457642685;57107.71692123108;1218.3725381428571;1300.74635446089;1302.9674258143623;1333.240053169684;1345.3542299696005;28980.420391592474;29197.562036569892;29629.555556666237;29993.70006299909;30780.692193078678;41600.58399415967;49751;50046.749766250934;50149.74925125497;50280.24579631235;533223;537222;701487.4925625339;858960;875362;40161.19677606373;40620;40833.59166408298;40950.795246023576;41220.79389603033;44211;44403.11193776171;44889.77555112204;44928;45221.54778452173;35328.29343413169;35761.64238357687;36225.60084321849;36756.89729308125;37172.07069823168;32979.50530741993;34925.65074349224;36460.635393646786;36466.81766591256;37463.43804842875;36298.27403451969;36480.81759591185;36832.63167368292;37127.44308835315;37345.06637334089;31134.68865311318;32050.83974580112;32640;32972.835135825124;33137.33725325528;23906.880465597562;24279.635805462578;24332.87833560821;24700.752992469847;24902.750972490045 03:16:14 INFO - PROCESS | 1645 | |2;jquery.html;344.17569920041484;366.6315353070164;380.79817696620785;382.1287464580755;402.1053156726284;13698.794518082224;14293.356798944047;19129.617407651855;19524.926129062897;20512.76923384598;19405.611887762236;19831.702524462144;19973.800261997374;20049.097790599422;20069.698954515658;4941.135301322263;5235;5285.973570132144;5333.306670132877;5338.893222135555;3859.1702783901455;4092.6930480214105;5290.444503327153;5325.840224793263;5338.839834804942;4389.978050109745;4426.933595996063;6518.217813862319;6521.673916304189;6544.70548825304;5639.915401268984;5862.38444963277;5874.118882167666;6530.575512591667;6550.213974323064;5731.684757338345;5733.971330143323;5737.426257374271;5738.340090889517;5751.568632352549;13047.217166970013;13140.277284749334;13170.802437963439;13352.732945341137;13378.331083445886;2249.3701763506083;2297.931062068141;2315.4790172211287;2343.61330380487;2374.7150341958904;9101.63593456261;9262.166405023512;9988.500574971222;10129.797404051878;11241.887581124247;1722.7071397862394;1795.542136755132;1811.1759149586906;1827.3147569661378;1831.8992455414948;112810.43594781973;113764;115164.42417787858;115967.68064638744;118633;9720.562574684156;11375.829362559569;11511.48198331077;11534.94232528832;11548.845115488552;12820.166689165175;13565.59303220905;13624.250666213255;13691.520796772264;14364.425422982964;8248.381371397048;8354.415190936576;8398.664053437917;8417.116202798741;8509.574521273911;8263.049749278909;8292.58537073144;8321.750347489464;8471.95764021176;8489.787755306166;4501.279795232747;4542.931856022163;4562.498125206224;4619.445666520039;4678.111158879802;1665.700173968673;1880.8495320374336;1884.8680592358403;1903.3433465454673;1953.716711076886;855.9015713192938;861.1001503428931;891.7235656946367;900.3337530227584;904.8914130304406;20247.29134480286;21040.053197605837;21122.788772112388;21186.364409067446;21265;900.2572877376191;913.3878704087291;924.7780532672244;927.2303987690201;990.7374545745383;6395.808125756234;11175.608853690082;11280.435978201058;11302.434878256054;11322.433878306052;590167;599356;610887;611917;617103;124665;126530.7346926519;130436.69563304246;131831;134127.32936335256;951.1107114847501;969.8642190093393;1006.039232532944;1006.0744115413837;1010.7169992402139;18479.027009193087;18956.43130706081;19053.809461905203;19054.237830486625;19216.807831922062;54407.727961361525;54905.176422352895;56106.43893561175;56472.71763641156;56536.43463565312;76162;77496.06259843548;79715;79842;80536.59731701305;7823.139454660097;8054.597270136587;8108.878366824384;8405.285550728013;8424.95787521083;9434.669786557712;12713.237205767684;12768.170068945861;12800;12820.23078615286;10294.897051029393;10365.170786337225;10527.210459215743;10761;11358.602448914578;9057.77355566116;10939.398333091438;10999.23005389647;11034.668959931216;11084.778304434027;11096.833547496955;11156.55373785072;11160.330380177214;11210.49552770111;11668.241564298294;8066.153053929253;8087.757367278991;8234.382421318545;8236.711715089943;8248.752537423887;9387.530623468936;9447.95276023643;9661.9033809661;9682.27382946296;11162 03:16:14 INFO - PROCESS | 1645 | |3;mootools.html;1273.5224290890917;1440.7910852926325;1446.956591302261;1594.561495588713;1787.3654852527352;7478.813029674261;7909.169537198598;8138.0641226258995;8380.790480237982;8407.369447291447;8192.590370481466;8299;8306.169383061691;8317.168283171679;8320.66717331306;2305.654151877215;2324.895379707909;2326.9650955235684;2327.453048533593;2337.836351455399;2217.1463986365206;2249.3139592424304;2265.762094980028;2268.7164104486938;2272.125231785758;2296.0586159674535;2309.030207312933;2318.5594737;2326.581215381231;2326.8952897119584;2269.988650056756;2276.7950884420397;2307.0541078158044;2311.884405779713;2315.6410756332793;2306.4464528513204;2330.93007209784;2331.137479132715;2338.1582630253097;2339.847909885852;8214.219649133323;8391.16088391156;8393.24460798531;8406.873896891551;8412.453190542596;5021.121303771825;5218.478152184789;5228.137357336035;5245.344331958505;5260.500252476008;3276.6723327667282;3495.7028652564563;3562.2341196642847;3578.58846232684;3585.766925149883;1042.8227201375785;1046.9790604187874;1089.3572792052648;1092.5902786455079;1104.5913012185463;9893.10962013415;9948.403095814274;9988.950055249677;10004.14964727999;10022.148117409955;3929.528456585228;4084.5499847554283;4239.576042395797;4415.116976604692;4425.114977004612;8648.524331161721;8652.091530389345;8664.610092545725;8665.393422460493;8698.47809131454;5004.149294619923;5083.796648134034;5090.745462726849;5093.694378337312;5102.260172275;4030.717849750548;4247.193033323659;4377.124575084996;4409.713368631029;4792.7363994980615;3591.964080359215;3622.2755448910325;3700.4264339027495;3701.056230661218;3707.2770809692393;1114.2868564118971;1127.7011591928147;1142.6457798082622;1154.3362566524281;1159.4898244772273;772.3049255669943;786.8150984518634;844.5777111444278;862.0603542139098;888.648983651467;4687.835925742583;5274.26160337553;5333.359996800409;5386.434424385383;5561.221428999943;402.41649608068417;439.9978000110043;440.5814476247514;443.72267332916925;453.32228318663806;3112.595362602847;3258.1365938026443;3486.1284678830293;3661.41417373219;3762.9247415051545;9763.414195148314;10259;10267.383956962607;10269.281150319406;10279.794404111875;10141.29010969225;10141.746456338651;10196.38821670702;10212.795744085226;10222;6183.288921774054;7750.302472777477;7828.373730101578;7832;7857.4499785014505;5509.807156749494;5524.1989911463415;5655.349634792052;5728.856778580569;5737.34020587621;9180.403273787182;9362.438253704799;9433.764155896157;9486.478243696663;9509.144177023823;8705.3035757138;8759.16787905148;9035.367524273237;9394.295427842802;9443.763905902408;7016.22821489646;7265.20082790882;7338.082739657543;7338.266173382619;7353.963230184029;7547.245275472628;7878.133405325295;7992.84014319722;8066.87899681517;8075.152109028702;7050.682719277543;7160.64196790148;7170.211276759448;7224.13310402755;7353.448491363276;7018.052562903943;7033.9296607033275;7052.788416347517;7163.10461192351;7171.784846454615;6623.138991931018;6660.400563949268;6684.598924064571;6750.257471678014;6764.8985265222045;4637.76811159434;4690.859274221779;4737.928931066106;4738.739369334719;4823.662343635911;4150.813213405464;4702.130105930414;4723.220668589782;4817.13291607514;4903.6077113831525 03:16:14 INFO - PROCESS | 1645 | |4;prototype.html;300.8315343407691;308.92585779412934;309.70887365876075;312.57801967344074;314.22856886344016;4340.652747780178;4435.512093669694;5059.215821547657;5289.312389389379;5323.9467605323935;5126.974365128169;5141.768620412081;5146.897062058757;5167.819126330579;5192.221166824968;3766.5856755756913;3878.631530004659;3889.358255887775;3935.583190051579;4047.7166598338126;3386.3735208986295;3422.5892892852894;3985.2428038672706;4024.223285932712;4092.5498195198506;4008.3185858403995;4021.8391264349393;4037.535683396403;4038.5961403859674;4081.2041651877894;3536.752427330088;3612.193903048476;3779.1874746929266;3782.583915769263;4039.474868267121;2939.83830889301;3158.3525377297597;3526.5616024376286;3529.5705239378094;3534.3814832404482;5124.948750512484;5125.20676231451;5127.769250383742;5131.820386286499;5131.897362052775;3832.559255685604;4420.602145806893;4451.710638808467;4617.261238201905;4632.536746325374;4904.288878112654;5412.485813847678;5430.15832545956;5457.645253058538;5495.642783219078;3410.7271418286477;3858.7105967052585;3896.3571010783185;3905.7656540607654;3913.2956067907767;5180.844574662766;5267.86740850719;5819.682780814937;5862.032764593837;5904.1734157218025;3240.902772916816;3287.4411350070545;3475.113845969312;3871.6902647788675;3881.6118388160958;3619.167591453928;3673.5959044505084;3896;3918.961475209072;3935.704822138351;4359.629431498358;4465.955340446619;4523.163214805271;4528.796204170821;4584.954150458453;4660.161170989187;5057.342545469139;5366.672662948997;5459.972700136554;5569.860753481195;1163.4996951311018;1219.0921208308969;1221.1757063981954;1230.4004793768065;1272.910896237273;818.1777313799594;858.759547326737;860.1785295043219;866.9523176225368;868.7263511993701;4305.051040037584;4333.566643335705;4610.492845786961;4975.253711943237;5140.845774626767;4859.173940430135;5146.6911985281;5426.620136590401;5567.443255674481;5578.912112138094;4946.427857514144;5174.767135478913;5203.2195170723935;5234.9476505235225;5239.161734122522;2468.568000599888;2545.478176973716;2622.7770639495475;2700.756931876101;2825.675047369538;4399.714018588781;4957.231629097496;5015.398152221756;5286.444923283077;5615.354234263031;4911.729854857946;5354.625176237704;5545.251391062155;5583.581231407661;5627.606067575231;5250.868728281824;5503.559715222773;5931.555133365015;5985.802839432044;6021.939780602225;4664.766761661971;5061.645684802029;5319.616899606208;5463.890722185454;5766.250387449605;4773.71357718538;4989.058643671459;5410.918836217381;5695.487406133468;5729.4843464088435;5284.339457567804;5383.542398895981;5717.599188198918;5981.730822112929;6019.307779605401;3405.4210784166257;3803.8668646597234;3898.6881049515405;3904.769997450794;3905.902352441212;5416.2688037114485;5594.38461769197;5707.600467967202;5747.683877386784;5801.94198058014;4663.743494107721;5133.204353325241;5604.719764011865;5611.691356975405;5618.438156184512;4448.799804008762;5577.52591029767;5599.0683214291;5605.635633683797;5610.49505544503;5207.947920520746;5395.433479484755;5433.673979561239;5518.696471693935;5572.3034620672415;5079.161938280143;5238.76425560843;5418.756155972912;5563.58273129525;5585.245991791218;5025.422076461254;5264.394594621667;5341.97329013368;5412.7564259607625;5427.3215848019 03:16:14 INFO - PROCESS | 1645 | |5;yui.html;650.3528988656286;697.8360085379932;759.2559291893944;774.5197977254103;776.022212012864;11251;13462.057655964074;14245.14529128253;14518.927405362985;14557.636059098515;12628.873711262884;12752.362381880916;12886.613401597944;12947.028972827029;13035.283059431727;667.7896462614285;832.0057531250159;841.7306461932184;843.472829481574;846.2341580869323;693.4591019005159;697.7906628011601;702.7821375373625;703.9612821294827;704.4294121761365;860.496609483453;890.1321211818457;890.6036813617943;891.5542228885557;892.629558733125;888.5290795878195;888.595688961521;889.7197382824389;891.7191084808264;893.6604090445654;518.9636725429184;661.5137873662854;661.9739403923946;662.0499583098222;662.8773676869746;11459.025982791472;12487.687807804787;12965.675858103528;12995.935020324932;13272.402741876638;13300.467981280737;13584.660383490393;13711.108777929403;13862.93068534651;13883.305834708326;10776.40729759863;11080.722981925435;11196.944015279953;11258.04306633937;11313.943430282878;3618.8585607940445;3716.163863130801;3718.033311339071;3768.246350729865;3808.504894363715;89915;90631.64052539282;90683.54658226733;90866;91814.08185918188;9911.15755160805;10286.794264114676;10340.741481463023;10388.688339349832;10696.41169735672;18471.90764046198;19595.90202049009;19697.901510492644;19742.60514789696;19760.209591616174;7588.924110758932;7590.924090759132;7638.312551870247;7686.346660533917;7773.144954055049;3503.5795704515112;4148.23257697327;4154.273002224599;4182.414461975326;4219.810108545123;3376.274101068233;3496.877609283714;3498.1429549760473;3502.1419752160373;3508.1755787389943;636.5734957578434;641.3618449642636;646.679893452744;654.254150268694;660.0890770736353;557.3506864502809;564.7938502446577;588.0268156201494;638.409471239102;651.4137276451232;8300.958495207486;8819;8950.418222815626;8995.59215984176;9059.184673379295;575.4475703324855;615.6213928433981;619.9770378874892;633.8161933039366;674.942629876466;4261.829526818892;4379.824807007748;4380.67144964129;4415.227335216388;4496.482904465963;63961;93681.0631893686;95421;112296;118315;73439.63280183564;82060;99828;103321.48339258255;107584;87276.99999999873;87594;89368;92466;93160.53419732858;7986.520808751494;8689.913100869164;9364.859527106963;9458.716238512856;9671.70984870455;38987.41518877276;41696.79151604223;48614.513854862416;50349;50585.747071264406;66934;69278;70293;71045;71568.64215678888;11929;11942;12076.396180191132;12289;12366.62900112998;12864.866469136261;12955.676108097374;13407.597772066854;13739.793903091662;13809.930950345184;12777.744445111231;12829.93585032069;13472.393742281816;13624.93187534056;13899;12118.62259473252;12360.876391235972;12814.167079139826;13206.471741130246;13810.93094534521;12614.810777838524;12817.615471535495;12851.678708032374;12876.163049401759;13316.600501984956;10940.12479001662;11087.22389432732;11172.273802202832;11252.549898003988;11670;9527.428354298765;10932.726681833017;10944.343339399662;11270.323780573191;11359.204855660024 03:16:14 INFO - PROCESS | 1645 | __end_tp_report 03:16:14 INFO - PROCESS | 1645 | __start_cc_report 03:16:14 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,1751 03:16:14 INFO - PROCESS | 1645 | __end_cc_report 03:16:14 INFO - PROCESS | 1645 | __startTimestamp1484738174088__endTimestamp 03:16:14 INFO - PROCESS | 1645 | 03:16:14 INFO - PROCESS | 1645 | ------- Summary: start ------- 03:16:14 INFO - PROCESS | 1645 | Number of tests: 6 03:16:14 INFO - PROCESS | 1645 | 03:16:14 INFO - PROCESS | 1645 | [#0] dojo.html Cycles:165 Average:5792.57 Median:6025.13 stddev:657.59 (10.9%) stddev-sans-first:630.97 03:16:14 INFO - PROCESS | 1645 | Values: 3345.8 3357.5 3368.7 3373.9 3432.1 5726.7 5783.3 5788.1 5790.6 5791.5 5723.7 5735.0 5815.4 5845.8 5854.4 5252.9 5294.9 5322.7 5342.9 5389.9 4092.3 5226.5 5234.1 5289.3 5394.1 5236.1 5299.0 5324.3 5362.6 5379.9 5334.1 5337.7 5348.5 5350.5 5355.0 5144.0 5261.9 5303.9 5317.0 5346.1 5664.8 5694.6 5740.2 5791.5 5804.1 6227.3 6236.2 6278.7 6279.1 6288.6 6276.9 6306.6 6325.4 6329.1 6350.7 5707.0 5774.9 5802.3 5821.4 5869.2 6285.4 6296.8 6315.9 6323.9 6351.1 6208.6 6234.2 6238.3 6242.9 6247.9 6139.9 6207.8 6211.1 6282.4 6321.8 6277.3 6293.0 6302.3 6305.7 6325.1 6253.5 6263.1 6285.5 6315.4 6328.0 4864.2 4922.8 4997.7 4999.0 5025.4 4399.5 4457.8 4471.3 4472.1 4474.1 5891.5 5893.4 5896.6 5897.5 5917.2 6201.9 6245.7 6253.8 6262.2 6273.9 5683.3 5744.2 5757.3 5839.8 5905.9 5419.4 5463.9 5471.0 5486.5 5524.0 6370.5 6400.9 6412.0 6415.6 6466.1 6167.7 6276.2 6281.7 6285.1 6317.5 6108.4 6285.2 6326.8 6341.7 6428.6 6272.2 6274.4 6284.7 6291.6 6304.5 6244.1 6264.6 6300.7 6304.4 6314.2 6025.1 6092.0 6109.2 6114.5 6153.4 6180.5 6219.9 6222.2 6225.3 6226.8 5843.4 6069.3 6077.5 6080.5 6103.1 6022.9 6088.8 6103.6 6154.7 6235.7 4642.6 5386.8 5857.5 5967.0 6021.3 03:16:14 INFO - PROCESS | 1645 | 03:16:14 INFO - PROCESS | 1645 | [#1] ext.html Cycles:180 Average:71174.25 Median:35126.97 stddev:166384.14 (473.7%) stddev-sans-first:166766.20 03:16:14 INFO - PROCESS | 1645 | Values: 465.5 500.6 518.9 531.5 551.1 29707.9 31773.0 31799.8 31851.7 39294.6 33150.3 41298.6 41670.4 41868.7 41886.6 9253.0 9415.2 9418.4 9432.3 9449.9 8363.9 8615.8 9317.2 9421.9 9450.0 11146.4 11392.0 11434.2 11564.4 11593.0 10219.5 11198.3 11207.8 11304.9 11335.4 11105.0 11117.7 11142.7 11186.4 11234.7 39074.4 39201.8 39227.0 39246.6 39259.2 3146.1 3208.7 3244.7 3432.4 3835.3 28914.9 29944.0 29980.4 30457.5 30728.1 2775.1 3103.5 3108.7 3191.1 3283.9 454651.0 454814.0 456155.0 456804.0 462972.0 32709.8 33774.5 59970.0 60167.8 60359.7 31715.6 33011.5 34328.0 34784.3 34833.3 13020.0 13358.9 14073.9 14136.4 14166.8 12233.9 12333.8 12337.8 12340.7 12357.2 7279.0 7428.5 7449.4 7502.7 7713.7 3023.9 3177.0 3255.3 3295.6 3296.9 840.6 892.8 930.7 937.8 957.7 28274.2 28499.3 28664.7 29174.7 29604.6 843.9 996.5 1049.5 1077.7 1104.8 15153.0 15339.8 15369.0 15421.4 15434.8 642680.0 651947.0 652021.0 654608.0 655259.0 47761.8 56597.4 57058.7 57084.7 57107.7 1218.4 1300.7 1303.0 1333.2 1345.4 28980.4 29197.6 29629.6 29993.7 30780.7 41600.6 49751.0 50046.7 50149.7 50280.2 533223.0 537222.0 701487.5 858960.0 875362.0 40161.2 40620.0 40833.6 40950.8 41220.8 44211.0 44403.1 44889.8 44928.0 45221.5 35328.3 35761.6 36225.6 36756.9 37172.1 32979.5 34925.7 36460.6 36466.8 37463.4 36298.3 36480.8 36832.6 37127.4 37345.1 31134.7 32050.8 32640.0 32972.8 33137.3 23906.9 24279.6 24332.9 24700.8 24902.8 03:16:14 INFO - PROCESS | 1645 | 03:16:14 INFO - PROCESS | 1645 | [#2] jquery.html Cycles:180 Average:34705.53 Median:2120.65 stddev:101177.42 (4771.1%) stddev-sans-first:101428.35 03:16:14 INFO - PROCESS | 1645 | Values: 344.2 366.6 380.8 382.1 402.1 13698.8 14293.4 19129.6 19524.9 20512.8 19405.6 19831.7 19973.8 20049.1 20069.7 4941.1 5235.0 5286.0 5333.3 5338.9 3859.2 4092.7 5290.4 5325.8 5338.8 4390.0 4426.9 6518.2 6521.7 6544.7 5639.9 5862.4 5874.1 6530.6 6550.2 5731.7 5734.0 5737.4 5738.3 5751.6 13047.2 13140.3 13170.8 13352.7 13378.3 2249.4 2297.9 2315.5 2343.6 2374.7 9101.6 9262.2 9988.5 10129.8 11241.9 1722.7 1795.5 1811.2 1827.3 1831.9 112810.4 113764.0 115164.4 115967.7 118633.0 9720.6 11375.8 11511.5 11534.9 11548.8 12820.2 13565.6 13624.3 13691.5 14364.4 8248.4 8354.4 8398.7 8417.1 8509.6 8263.0 8292.6 8321.8 8472.0 8489.8 4501.3 4542.9 4562.5 4619.4 4678.1 1665.7 1880.8 1884.9 1903.3 1953.7 855.9 861.1 891.7 900.3 904.9 20247.3 21040.1 21122.8 21186.4 21265.0 900.3 913.4 924.8 927.2 990.7 6395.8 11175.6 11280.4 11302.4 11322.4 590167.0 599356.0 610887.0 611917.0 617103.0 124665.0 126530.7 130436.7 131831.0 134127.3 951.1 969.9 1006.0 1006.1 1010.7 18479.0 18956.4 19053.8 19054.2 19216.8 54407.7 54905.2 56106.4 56472.7 56536.4 76162.0 77496.1 79715.0 79842.0 80536.6 7823.1 8054.6 8108.9 8405.3 8425.0 9434.7 12713.2 12768.2 12800.0 12820.2 10294.9 10365.2 10527.2 10761.0 11358.6 9057.8 10939.4 10999.2 11034.7 11084.8 11096.8 11156.6 11160.3 11210.5 11668.2 8066.2 8087.8 8234.4 8236.7 8248.8 9387.5 9448.0 9661.9 9682.3 11162.0 03:16:14 INFO - PROCESS | 1645 | 03:16:14 INFO - PROCESS | 1645 | [#3] mootools.html Cycles:180 Average:5335.02 Median:4863.64 stddev:2957.87 (60.8%) stddev-sans-first:2950.41 03:16:14 INFO - PROCESS | 1645 | Values: 1273.5 1440.8 1447.0 1594.6 1787.4 7478.8 7909.2 8138.1 8380.8 8407.4 8192.6 8299.0 8306.2 8317.2 8320.7 2305.7 2324.9 2327.0 2327.5 2337.8 2217.1 2249.3 2265.8 2268.7 2272.1 2296.1 2309.0 2318.6 2326.6 2326.9 2270.0 2276.8 2307.1 2311.9 2315.6 2306.4 2330.9 2331.1 2338.2 2339.8 8214.2 8391.2 8393.2 8406.9 8412.5 5021.1 5218.5 5228.1 5245.3 5260.5 3276.7 3495.7 3562.2 3578.6 3585.8 1042.8 1047.0 1089.4 1092.6 1104.6 9893.1 9948.4 9989.0 10004.1 10022.1 3929.5 4084.5 4239.6 4415.1 4425.1 8648.5 8652.1 8664.6 8665.4 8698.5 5004.1 5083.8 5090.7 5093.7 5102.3 4030.7 4247.2 4377.1 4409.7 4792.7 3592.0 3622.3 3700.4 3701.1 3707.3 1114.3 1127.7 1142.6 1154.3 1159.5 772.3 786.8 844.6 862.1 888.6 4687.8 5274.3 5333.4 5386.4 5561.2 402.4 440.0 440.6 443.7 453.3 3112.6 3258.1 3486.1 3661.4 3762.9 9763.4 10259.0 10267.4 10269.3 10279.8 10141.3 10141.7 10196.4 10212.8 10222.0 6183.3 7750.3 7828.4 7832.0 7857.4 5509.8 5524.2 5655.3 5728.9 5737.3 9180.4 9362.4 9433.8 9486.5 9509.1 8705.3 8759.2 9035.4 9394.3 9443.8 7016.2 7265.2 7338.1 7338.3 7354.0 7547.2 7878.1 7992.8 8066.9 8075.2 7050.7 7160.6 7170.2 7224.1 7353.4 7018.1 7033.9 7052.8 7163.1 7171.8 6623.1 6660.4 6684.6 6750.3 6764.9 4637.8 4690.9 4737.9 4738.7 4823.7 4150.8 4702.1 4723.2 4817.1 4903.6 03:16:14 INFO - PROCESS | 1645 | 03:16:14 INFO - PROCESS | 1645 | [#4] prototype.html Cycles:180 Average:4417.79 Median:5058.28 stddev:1371.80 (27.1%) stddev-sans-first:1340.39 03:16:14 INFO - PROCESS | 1645 | Values: 300.8 308.9 309.7 312.6 314.2 4340.7 4435.5 5059.2 5289.3 5323.9 5127.0 5141.8 5146.9 5167.8 5192.2 3766.6 3878.6 3889.4 3935.6 4047.7 3386.4 3422.6 3985.2 4024.2 4092.5 4008.3 4021.8 4037.5 4038.6 4081.2 3536.8 3612.2 3779.2 3782.6 4039.5 2939.8 3158.4 3526.6 3529.6 3534.4 5124.9 5125.2 5127.8 5131.8 5131.9 3832.6 4420.6 4451.7 4617.3 4632.5 4904.3 5412.5 5430.2 5457.6 5495.6 3410.7 3858.7 3896.4 3905.8 3913.3 5180.8 5267.9 5819.7 5862.0 5904.2 3240.9 3287.4 3475.1 3871.7 3881.6 3619.2 3673.6 3896.0 3919.0 3935.7 4359.6 4466.0 4523.2 4528.8 4585.0 4660.2 5057.3 5366.7 5460.0 5569.9 1163.5 1219.1 1221.2 1230.4 1272.9 818.2 858.8 860.2 867.0 868.7 4305.1 4333.6 4610.5 4975.3 5140.8 4859.2 5146.7 5426.6 5567.4 5578.9 4946.4 5174.8 5203.2 5234.9 5239.2 2468.6 2545.5 2622.8 2700.8 2825.7 4399.7 4957.2 5015.4 5286.4 5615.4 4911.7 5354.6 5545.3 5583.6 5627.6 5250.9 5503.6 5931.6 5985.8 6021.9 4664.8 5061.6 5319.6 5463.9 5766.3 4773.7 4989.1 5410.9 5695.5 5729.5 5284.3 5383.5 5717.6 5981.7 6019.3 3405.4 3803.9 3898.7 3904.8 3905.9 5416.3 5594.4 5707.6 5747.7 5801.9 4663.7 5133.2 5604.7 5611.7 5618.4 4448.8 5577.5 5599.1 5605.6 5610.5 5207.9 5395.4 5433.7 5518.7 5572.3 5079.2 5238.8 5418.8 5563.6 5585.2 5025.4 5264.4 5342.0 5412.8 5427.3 03:16:14 INFO - PROCESS | 1645 | 03:16:14 INFO - PROCESS | 1645 | [#5] yui.html Cycles:180 Average:19847.86 Median:4380.25 stddev:29158.86 (665.7%) stddev-sans-first:29205.03 03:16:14 INFO - PROCESS | 1645 | Values: 650.4 697.8 759.3 774.5 776.0 11251.0 13462.1 14245.1 14518.9 14557.6 12628.9 12752.4 12886.6 12947.0 13035.3 667.8 832.0 841.7 843.5 846.2 693.5 697.8 702.8 704.0 704.4 860.5 890.1 890.6 891.6 892.6 888.5 888.6 889.7 891.7 893.7 519.0 661.5 662.0 662.0 662.9 11459.0 12487.7 12965.7 12995.9 13272.4 13300.5 13584.7 13711.1 13862.9 13883.3 10776.4 11080.7 11196.9 11258.0 11313.9 3618.9 3716.2 3718.0 3768.2 3808.5 89915.0 90631.6 90683.5 90866.0 91814.1 9911.2 10286.8 10340.7 10388.7 10696.4 18471.9 19595.9 19697.9 19742.6 19760.2 7588.9 7590.9 7638.3 7686.3 7773.1 3503.6 4148.2 4154.3 4182.4 4219.8 3376.3 3496.9 3498.1 3502.1 3508.2 636.6 641.4 646.7 654.3 660.1 557.4 564.8 588.0 638.4 651.4 8301.0 8819.0 8950.4 8995.6 9059.2 575.4 615.6 620.0 633.8 674.9 4261.8 4379.8 4380.7 4415.2 4496.5 63961.0 93681.1 95421.0 112296.0 118315.0 73439.6 82060.0 99828.0 103321.5 107584.0 87277.0 87594.0 89368.0 92466.0 93160.5 7986.5 8689.9 9364.9 9458.7 9671.7 38987.4 41696.8 48614.5 50349.0 50585.7 66934.0 69278.0 70293.0 71045.0 71568.6 11929.0 11942.0 12076.4 12289.0 12366.6 12864.9 12955.7 13407.6 13739.8 13809.9 12777.7 12829.9 13472.4 13624.9 13899.0 12118.6 12360.9 12814.2 13206.5 13810.9 12614.8 12817.6 12851.7 12876.2 13316.6 10940.1 11087.2 11172.3 11252.5 11670.0 9527.4 10932.7 10944.3 11270.3 11359.2 03:16:14 INFO - PROCESS | 1645 | -------- Summary: end -------- 03:16:14 INFO - PROCESS | 1645 | 03:16:14 INFO - TEST-INFO | 1645: exit 0 03:16:14 INFO - TEST-OK | dromaeo_css | took 1119261ms 03:16:14 INFO - TEST-START | kraken 03:16:14 INFO - operating with platform_type : mac_ 03:16:14 INFO - Initialising browser for kraken test... 03:16:14 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/tmpzHXxGX/profile http://localhost:49240/getInfo.html) 03:16:17 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 03:16:17 INFO - PROCESS | 1651 | colorDepth:24 03:16:17 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 03:16:17 INFO - PROCESS | 1651 | __metrics 03:16:17 INFO - PROCESS | 1651 | 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" 03:16:17 INFO - PROCESS | 1651 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:16:18 INFO - TEST-INFO | 1651: exit 0 03:16:18 INFO - Browser initialized. 03:16:18 INFO - Running cycle 1/1 for kraken test... 03:16:18 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/tmpzHXxGX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:16:30 INFO - PROCESS | 1653 | RSS: Main: 221769728 03:16:30 INFO - PROCESS | 1653 | 03:16:33 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 03:16:34 INFO - PROCESS | 1653 | RSS: Main: 271601664 03:16:34 INFO - PROCESS | 1653 | 03:16:36 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 03:16:37 INFO - PROCESS | 1653 | RSS: Main: 282611712 03:16:37 INFO - PROCESS | 1653 | 03:16:40 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 03:16:41 INFO - PROCESS | 1653 | RSS: Main: 379854848 03:16:41 INFO - PROCESS | 1653 | 03:16:43 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 03:16:44 INFO - PROCESS | 1653 | RSS: Main: 281939968 03:16:44 INFO - PROCESS | 1653 | 03:16:46 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:16:46 INFO - PROCESS | 1653 | RSS: Main: 282796032 03:16:46 INFO - PROCESS | 1653 | 03:16:49 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 03:16:50 INFO - PROCESS | 1653 | RSS: Main: 323547136 03:16:50 INFO - PROCESS | 1653 | 03:16:52 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 03:16:53 INFO - PROCESS | 1653 | RSS: Main: 326053888 03:16:53 INFO - PROCESS | 1653 | 03:16:56 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 03:16:56 INFO - PROCESS | 1653 | RSS: Main: 325615616 03:16:56 INFO - PROCESS | 1653 | 03:16:58 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:16:59 INFO - PROCESS | 1653 | RSS: Main: 298512384 03:16:59 INFO - PROCESS | 1653 | 03:17:00 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:17:01 INFO - PROCESS | 1653 | RSS: Main: 299147264 03:17:01 INFO - PROCESS | 1653 | 03:17:03 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:17:04 INFO - PROCESS | 1653 | RSS: Main: 301780992 03:17:04 INFO - PROCESS | 1653 | 03:17:07 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:17:07 INFO - PROCESS | 1653 | RSS: Main: 313294848 03:17:07 INFO - PROCESS | 1653 | 03:17:10 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:17:11 INFO - PROCESS | 1653 | RSS: Main: 313544704 03:17:11 INFO - PROCESS | 1653 | 03:17:13 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 03:17:13 INFO - PROCESS | 1653 | RSS: Main: 357281792 03:17:13 INFO - PROCESS | 1653 | 03:17:13 INFO - PROCESS | 1653 | __start_tp_report 03:17:13 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 03:17:13 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 03:17:13 INFO - PROCESS | 1653 | |i|pagename|runs| 03:17:13 INFO - PROCESS | 1653 | |0;ai-astar;117;115;115;115;165;137;115;115;113;114 03:17:13 INFO - PROCESS | 1653 | |1;audio-beat-detection;145;110;111;143;111;112;110;106;139;110 03:17:13 INFO - PROCESS | 1653 | |2;audio-dft;183;187;182;183;181;182;183;185;183;181 03:17:13 INFO - PROCESS | 1653 | |3;audio-fft;79;59;58;108;87;82;52;59;110;81 03:17:13 INFO - PROCESS | 1653 | |4;audio-oscillator;87;69;93;70;69;69;89;69;69;91 03:17:13 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;87;85;87;87;87;84;88;85;87;84 03:17:13 INFO - PROCESS | 1653 | |6;imaging-darkroom;89;93;93;94;93;93;93;92;92;93 03:17:13 INFO - PROCESS | 1653 | |7;imaging-desaturate;83;81;81;82;81;83;85;83;81;80 03:17:13 INFO - PROCESS | 1653 | |8;json-parse-financial;53;53;52;52;58;58;58;57;56;57 03:17:13 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;65;72;78;75;73;69;69;69;69;70 03:17:13 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;84;82;83;85;83;82;84;84;84;88 03:17:13 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;141;135;141;146;156;145;144;146;180;146 03:17:13 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;162;164;165;161;163;158;164;164;164;162 03:17:13 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;68;67;68;70;65;83;66;68;66;66 03:17:13 INFO - PROCESS | 1653 | __end_tp_report 03:17:13 INFO - PROCESS | 1653 | __start_cc_report 03:17:13 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,1920 03:17:13 INFO - PROCESS | 1653 | __end_cc_report 03:17:13 INFO - PROCESS | 1653 | __startTimestamp1484738233350__endTimestamp 03:17:13 INFO - PROCESS | 1653 | 03:17:13 INFO - PROCESS | 1653 | ------- Summary: start ------- 03:17:13 INFO - PROCESS | 1653 | Number of tests: 14 03:17:13 INFO - PROCESS | 1653 | 03:17:13 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:122.10 Median:115.00 stddev:16.63 (14.5%) stddev-sans-first:17.54 03:17:13 INFO - PROCESS | 1653 | Values: 117.0 115.0 115.0 115.0 165.0 137.0 115.0 115.0 113.0 114.0 03:17:13 INFO - PROCESS | 1653 | 03:17:13 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:119.70 Median:111.50 stddev:15.76 (14.1%) stddev-sans-first:13.81 03:17:13 INFO - PROCESS | 1653 | Values: 145.0 110.0 111.0 143.0 111.0 112.0 110.0 106.0 139.0 110.0 03:17:13 INFO - PROCESS | 1653 | 03:17:13 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:183.00 Median:183.00 stddev:1.83 (1.0%) stddev-sans-first:1.94 03:17:13 INFO - PROCESS | 1653 | Values: 183.0 187.0 182.0 183.0 181.0 182.0 183.0 185.0 183.0 181.0 03:17:13 INFO - PROCESS | 1653 | 03:17:13 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:77.50 Median:69.00 stddev:20.62 (29.9%) stddev-sans-first:21.86 03:17:13 INFO - PROCESS | 1653 | Values: 79.0 59.0 58.0 108.0 87.0 82.0 52.0 59.0 110.0 81.0 03:17:13 INFO - PROCESS | 1653 | 03:17:13 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:77.50 Median:78.50 stddev:10.87 (13.8%) stddev-sans-first:10.97 03:17:13 INFO - PROCESS | 1653 | Values: 87.0 69.0 93.0 70.0 69.0 69.0 89.0 69.0 69.0 91.0 03:17:13 INFO - PROCESS | 1653 | 03:17:13 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:86.10 Median:87.00 stddev:1.45 (1.7%) stddev-sans-first:1.50 03:17:13 INFO - PROCESS | 1653 | Values: 87.0 85.0 87.0 87.0 87.0 84.0 88.0 85.0 87.0 84.0 03:17:13 INFO - PROCESS | 1653 | 03:17:13 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:92.50 Median:93.00 stddev:1.35 (1.5%) stddev-sans-first:0.60 03:17:13 INFO - PROCESS | 1653 | Values: 89.0 93.0 93.0 94.0 93.0 93.0 93.0 92.0 92.0 93.0 03:17:13 INFO - PROCESS | 1653 | 03:17:13 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:82.00 Median:82.50 stddev:1.49 (1.8%) stddev-sans-first:1.54 03:17:13 INFO - PROCESS | 1653 | Values: 83.0 81.0 81.0 82.0 81.0 83.0 85.0 83.0 81.0 80.0 03:17:13 INFO - PROCESS | 1653 | 03:17:13 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:55.40 Median:57.00 stddev:2.59 (4.5%) stddev-sans-first:2.60 03:17:13 INFO - PROCESS | 1653 | Values: 53.0 53.0 52.0 52.0 58.0 58.0 58.0 57.0 56.0 57.0 03:17:13 INFO - PROCESS | 1653 | 03:17:13 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:71.00 stddev:3.70 (5.2%) stddev-sans-first:3.24 03:17:13 INFO - PROCESS | 1653 | Values: 65.0 72.0 78.0 75.0 73.0 69.0 69.0 69.0 69.0 70.0 03:17:13 INFO - PROCESS | 1653 | 03:17:13 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:83.90 Median:84.00 stddev:1.73 (2.1%) stddev-sans-first:1.83 03:17:13 INFO - PROCESS | 1653 | Values: 84.0 82.0 83.0 85.0 83.0 82.0 84.0 84.0 84.0 88.0 03:17:13 INFO - PROCESS | 1653 | 03:17:13 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:148.00 Median:146.00 stddev:12.44 (8.5%) stddev-sans-first:12.93 03:17:13 INFO - PROCESS | 1653 | Values: 141.0 135.0 141.0 146.0 156.0 145.0 144.0 146.0 180.0 146.0 03:17:13 INFO - PROCESS | 1653 | 03:17:13 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.70 Median:164.00 stddev:2.06 (1.3%) stddev-sans-first:2.17 03:17:13 INFO - PROCESS | 1653 | Values: 162.0 164.0 165.0 161.0 163.0 158.0 164.0 164.0 164.0 162.0 03:17:13 INFO - PROCESS | 1653 | 03:17:13 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:68.00 stddev:5.23 (7.7%) stddev-sans-first:5.54 03:17:13 INFO - PROCESS | 1653 | Values: 68.0 67.0 68.0 70.0 65.0 83.0 66.0 68.0 66.0 66.0 03:17:13 INFO - PROCESS | 1653 | -------- Summary: end -------- 03:17:13 INFO - PROCESS | 1653 | 03:17:14 INFO - TEST-INFO | 1653: exit 0 03:17:14 INFO - TEST-OK | kraken | took 59676ms 03:17:14 INFO - SUITE-END | took 1178s 03:17:14 INFO - Completed test suite (00:19:39) 03:17:14 INFO - javascript benchmark 03:17:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3345.7992520448774, 3357.496375543669, 3368.730501559874, 3373.89878303651, 3432.1419645088727, 5726.713664316783, 5783.334916484606, 5788.131780232962, 5790.623609465381, 5791.507721843648, 5723.713814309278, 5734.99999999999, 5815.418458154193, 5845.7953971611005, 5854.443827836349, 5252.947470525284, 5294.867628309285, 5322.680639161644, 5342.893142137155, 5389.892202155974, 4092.283850326196, 5226.451222621627, 5234.110201265775, 5289.338832645919, 5394.056040192951, 5236.083685355066, 5299.019681358942, 5324.254604355393, 5362.624616276862, 5379.892402151954, 5334.093204155303, 5337.733113344337, 5348.5186333230195, 5350.49170328822, 5355.036093503168, 5144.0226356992425, 5261.86845328866, 5303.920441193385, 5317.042932272189, 5346.117890548055, 5664.830055098354, 5694.629849059798, 5740.196372507852, 5791.478766910998, 5804.1293805929445, 6227.252729672423, 6236.189295391616, 6278.748850045993, 6279.0895320178315, 6288.559800813945, 6276.937230627681, 6306.590071645374, 6325.367463253684, 6329.082283068975, 6350.745970161188, 5706.999999999958, 5774.855628609234, 5802.303723553158, 5821.388754180834, 5869.178315035899, 6285.434310911948, 6296.779612713624, 6315.873682526416, 6323.9051414228825, 6351.142595749607, 6208.565400421927, 6234.1583886174785, 6238.251409830849, 6242.937570624235, 6247.937520624827, 6139.8908086453375, 6207.751689932352, 6211.09939058834, 6282.371762823681, 6321.841953951188, 6277.278113016971, 6293.0, 6302.275238347557, 6305.653189074553, 6325.082862984841, 6253.499720022387, 6263.091851681481, 6285.465735412449, 6315.431611155018, 6328.0, 4864.246041863516, 4922.803087876445, 4997.7251251180805, 4998.975005124951, 5025.422076461254, 4399.5160532341415, 4457.77711114443, 4471.306947423154, 4472.105578884236, 4474.149911516802, 5891.499222566044, 5893.351731309553, 5896.616719913192, 5897.52819774417, 5917.171595976567, 6201.906971395432, 6245.656488893063, 6253.78111766086, 6262.2172228471445, 6273.937260627426, 5683.318001839806, 5744.167095771089, 5757.280339957505, 5839.824805255849, 5905.936931352312, 5419.403865574784, 5463.863403414946, 5471.042567550663, 5486.506214440719, 5524.0, 6370.522210834113, 6400.935990640127, 6411.967940160176, 6415.582987105823, 6466.127072845105, 6167.660778657217, 6276.184096067482, 6281.654509001866, 6285.120083188266, 6317.4630156437215, 6108.389161083856, 6285.214348206474, 6326.778562750282, 6341.714622841891, 6428.646424446701, 6272.18461599989, 6274.435300822948, 6284.74861005573, 6291.559590828598, 6304.5271604628915, 6244.094606282155, 6264.561480696308, 6300.7479700811455, 6304.369563043659, 6314.242290925118, 6025.126356678345, 6091.969540152271, 6109.236345456818, 6114.5414093942245, 6153.446189842935, 6180.505559555315, 6219.906701399392, 6222.191115155001, 6225.346338634378, 6226.813195604139, 5843.386444423444, 6069.2716873975405, 6077.544184186292, 6080.4831589313635, 6103.084537319438, 6022.939770602238, 6088.847778805388, 6103.6032657877095, 6154.661493617894, 6235.688215589294, 4642.628589712883, 5386.757595908115, 5857.47282744555, 5967.0, 6021.277446706424], "value": 5750.008474196154, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [465.5088881230302, 500.6295341447329, 518.885845114075, 531.5269410224901, 551.1264645536824, 29707.85146074269, 31773.0, 31799.841000794993, 31851.68148318519, 39294.60705392945, 33150.33699326012, 41298.58701412985, 41670.37494437586, 41868.65050795937, 41886.58113418865, 9253.0, 9415.152636262736, 9418.387804792701, 9432.339736218468, 9449.858252126223, 8363.874541881876, 8615.827683446327, 9317.208037316836, 9421.905780942207, 9450.0, 11146.442677866115, 11392.0, 11434.199606027581, 11564.363959982198, 11593.0, 10219.540120694586, 11198.272112312674, 11207.831882521768, 11304.943475282571, 11335.433228338592, 11105.0, 11117.72205694856, 11142.665720028332, 11186.384748838811, 11234.719132021683, 39074.41388379176, 39201.80399097986, 39227.0, 39246.607533924864, 39259.214815703526, 3146.08763458599, 3208.679132086796, 3244.707976282146, 3432.433648448003, 3835.3096442640312, 28914.855425722948, 29944.0, 29980.400391992258, 30457.543136852964, 30728.078157655305, 2775.111964171466, 3103.5499852521484, 3108.689131086896, 3191.1064901827526, 3283.885064022748, 454651.0, 454814.0, 456155.0, 456804.0, 462972.0, 32709.836450817595, 33774.49338259928, 59970.0, 60167.796644066875, 60359.69820150959, 31715.57279922409, 33011.504827427605, 34328.0, 34784.30431391358, 34833.30333393318, 13020.0, 13358.864496517905, 14073.929630351782, 14136.363863626162, 14166.787498187536, 12233.877661223452, 12333.753324933452, 12337.753244935051, 12340.691482713004, 12357.196782209128, 7278.96360518194, 7428.517146385468, 7449.404047676173, 7502.699892004259, 7713.6914523419555, 3023.8639261233297, 3177.0, 3255.348930213919, 3295.6210035846184, 3296.9175770605925, 840.6007146605435, 892.830362231174, 930.6742640076054, 937.7561833923229, 957.7174733453647, 28274.151775446768, 28499.287517811805, 28664.71335286662, 29174.7082529172, 29604.55593166104, 843.9113893041265, 996.4668902137466, 1049.4700176411015, 1077.6820837852852, 1104.7679987202616, 15153.0, 15339.846601534065, 15369.0, 15421.383144674312, 15434.845651543565, 642680.0, 651947.0, 652021.0, 654608.0, 655259.0, 47761.76119119382, 56597.43402566004, 57058.7147064262, 57084.71457642685, 57107.71692123108, 1218.3725381428571, 1300.74635446089, 1302.9674258143623, 1333.240053169684, 1345.3542299696005, 28980.420391592474, 29197.562036569892, 29629.555556666237, 29993.70006299909, 30780.692193078678, 41600.58399415967, 49751.0, 50046.749766250934, 50149.74925125497, 50280.24579631235, 533223.0, 537222.0, 701487.4925625339, 858960.0, 875362.0, 40161.19677606373, 40620.0, 40833.59166408298, 40950.795246023576, 41220.79389603033, 44211.0, 44403.11193776171, 44889.77555112204, 44928.0, 45221.54778452173, 35328.29343413169, 35761.64238357687, 36225.60084321849, 36756.89729308125, 37172.07069823168, 32979.50530741993, 34925.65074349224, 36460.635393646786, 36466.81766591256, 37463.43804842875, 36298.27403451969, 36480.81759591185, 36832.63167368292, 37127.44308835315, 37345.06637334089, 31134.68865311318, 32050.83974580112, 32640.0, 32972.835135825124, 33137.33725325528, 23906.880465597562, 24279.635805462578, 24332.87833560821, 24700.752992469847, 24902.750972490045], "value": 18490.428644050127, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.17569920041484, 366.6315353070164, 380.79817696620785, 382.1287464580755, 402.1053156726284, 13698.794518082224, 14293.356798944047, 19129.617407651855, 19524.926129062897, 20512.76923384598, 19405.611887762236, 19831.702524462144, 19973.800261997374, 20049.097790599422, 20069.698954515658, 4941.135301322263, 5235.0, 5285.973570132144, 5333.306670132877, 5338.893222135555, 3859.1702783901455, 4092.6930480214105, 5290.444503327153, 5325.840224793263, 5338.839834804942, 4389.978050109745, 4426.933595996063, 6518.217813862319, 6521.673916304189, 6544.70548825304, 5639.915401268984, 5862.38444963277, 5874.118882167666, 6530.575512591667, 6550.213974323064, 5731.684757338345, 5733.971330143323, 5737.426257374271, 5738.340090889517, 5751.568632352549, 13047.217166970013, 13140.277284749334, 13170.802437963439, 13352.732945341137, 13378.331083445886, 2249.3701763506083, 2297.931062068141, 2315.4790172211287, 2343.61330380487, 2374.7150341958904, 9101.63593456261, 9262.166405023512, 9988.500574971222, 10129.797404051878, 11241.887581124247, 1722.7071397862394, 1795.542136755132, 1811.1759149586906, 1827.3147569661378, 1831.8992455414948, 112810.43594781973, 113764.0, 115164.42417787858, 115967.68064638744, 118633.0, 9720.562574684156, 11375.829362559569, 11511.48198331077, 11534.94232528832, 11548.845115488552, 12820.166689165175, 13565.59303220905, 13624.250666213255, 13691.520796772264, 14364.425422982964, 8248.381371397048, 8354.415190936576, 8398.664053437917, 8417.116202798741, 8509.574521273911, 8263.049749278909, 8292.58537073144, 8321.750347489464, 8471.95764021176, 8489.787755306166, 4501.279795232747, 4542.931856022163, 4562.498125206224, 4619.445666520039, 4678.111158879802, 1665.700173968673, 1880.8495320374336, 1884.8680592358403, 1903.3433465454673, 1953.716711076886, 855.9015713192938, 861.1001503428931, 891.7235656946367, 900.3337530227584, 904.8914130304406, 20247.29134480286, 21040.053197605837, 21122.788772112388, 21186.364409067446, 21265.0, 900.2572877376191, 913.3878704087291, 924.7780532672244, 927.2303987690201, 990.7374545745383, 6395.808125756234, 11175.608853690082, 11280.435978201058, 11302.434878256054, 11322.433878306052, 590167.0, 599356.0, 610887.0, 611917.0, 617103.0, 124665.0, 126530.7346926519, 130436.69563304246, 131831.0, 134127.32936335256, 951.1107114847501, 969.8642190093393, 1006.039232532944, 1006.0744115413837, 1010.7169992402139, 18479.027009193087, 18956.43130706081, 19053.809461905203, 19054.237830486625, 19216.807831922062, 54407.727961361525, 54905.176422352895, 56106.43893561175, 56472.71763641156, 56536.43463565312, 76162.0, 77496.06259843548, 79715.0, 79842.0, 80536.59731701305, 7823.139454660097, 8054.597270136587, 8108.878366824384, 8405.285550728013, 8424.95787521083, 9434.669786557712, 12713.237205767684, 12768.170068945861, 12800.0, 12820.23078615286, 10294.897051029393, 10365.170786337225, 10527.210459215743, 10761.0, 11358.602448914578, 9057.77355566116, 10939.398333091438, 10999.23005389647, 11034.668959931216, 11084.778304434027, 11096.833547496955, 11156.55373785072, 11160.330380177214, 11210.49552770111, 11668.241564298294, 8066.153053929253, 8087.757367278991, 8234.382421318545, 8236.711715089943, 8248.752537423887, 9387.530623468936, 9447.95276023643, 9661.9033809661, 9682.27382946296, 11162.0], "value": 9163.832044955805, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1273.5224290890917, 1440.7910852926325, 1446.956591302261, 1594.561495588713, 1787.3654852527352, 7478.813029674261, 7909.169537198598, 8138.0641226258995, 8380.790480237982, 8407.369447291447, 8192.590370481466, 8299.0, 8306.169383061691, 8317.168283171679, 8320.66717331306, 2305.654151877215, 2324.895379707909, 2326.9650955235684, 2327.453048533593, 2337.836351455399, 2217.1463986365206, 2249.3139592424304, 2265.762094980028, 2268.7164104486938, 2272.125231785758, 2296.0586159674535, 2309.030207312933, 2318.5594737, 2326.581215381231, 2326.8952897119584, 2269.988650056756, 2276.7950884420397, 2307.0541078158044, 2311.884405779713, 2315.6410756332793, 2306.4464528513204, 2330.93007209784, 2331.137479132715, 2338.1582630253097, 2339.847909885852, 8214.219649133323, 8391.16088391156, 8393.24460798531, 8406.873896891551, 8412.453190542596, 5021.121303771825, 5218.478152184789, 5228.137357336035, 5245.344331958505, 5260.500252476008, 3276.6723327667282, 3495.7028652564563, 3562.2341196642847, 3578.58846232684, 3585.766925149883, 1042.8227201375785, 1046.9790604187874, 1089.3572792052648, 1092.5902786455079, 1104.5913012185463, 9893.10962013415, 9948.403095814274, 9988.950055249677, 10004.14964727999, 10022.148117409955, 3929.528456585228, 4084.5499847554283, 4239.576042395797, 4415.116976604692, 4425.114977004612, 8648.524331161721, 8652.091530389345, 8664.610092545725, 8665.393422460493, 8698.47809131454, 5004.149294619923, 5083.796648134034, 5090.745462726849, 5093.694378337312, 5102.260172275, 4030.717849750548, 4247.193033323659, 4377.124575084996, 4409.713368631029, 4792.7363994980615, 3591.964080359215, 3622.2755448910325, 3700.4264339027495, 3701.056230661218, 3707.2770809692393, 1114.2868564118971, 1127.7011591928147, 1142.6457798082622, 1154.3362566524281, 1159.4898244772273, 772.3049255669943, 786.8150984518634, 844.5777111444278, 862.0603542139098, 888.648983651467, 4687.835925742583, 5274.26160337553, 5333.359996800409, 5386.434424385383, 5561.221428999943, 402.41649608068417, 439.9978000110043, 440.5814476247514, 443.72267332916925, 453.32228318663806, 3112.595362602847, 3258.1365938026443, 3486.1284678830293, 3661.41417373219, 3762.9247415051545, 9763.414195148314, 10259.0, 10267.383956962607, 10269.281150319406, 10279.794404111875, 10141.29010969225, 10141.746456338651, 10196.38821670702, 10212.795744085226, 10222.0, 6183.288921774054, 7750.302472777477, 7828.373730101578, 7832.0, 7857.4499785014505, 5509.807156749494, 5524.1989911463415, 5655.349634792052, 5728.856778580569, 5737.34020587621, 9180.403273787182, 9362.438253704799, 9433.764155896157, 9486.478243696663, 9509.144177023823, 8705.3035757138, 8759.16787905148, 9035.367524273237, 9394.295427842802, 9443.763905902408, 7016.22821489646, 7265.20082790882, 7338.082739657543, 7338.266173382619, 7353.963230184029, 7547.245275472628, 7878.133405325295, 7992.84014319722, 8066.87899681517, 8075.152109028702, 7050.682719277543, 7160.64196790148, 7170.211276759448, 7224.13310402755, 7353.448491363276, 7018.052562903943, 7033.9296607033275, 7052.788416347517, 7163.10461192351, 7171.784846454615, 6623.138991931018, 6660.400563949268, 6684.598924064571, 6750.257471678014, 6764.8985265222045, 4637.76811159434, 4690.859274221779, 4737.928931066106, 4738.739369334719, 4823.662343635911, 4150.813213405464, 4702.130105930414, 4723.220668589782, 4817.13291607514, 4903.6077113831525], "value": 4239.478202390779, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.8315343407691, 308.92585779412934, 309.70887365876075, 312.57801967344074, 314.22856886344016, 4340.652747780178, 4435.512093669694, 5059.215821547657, 5289.312389389379, 5323.9467605323935, 5126.974365128169, 5141.768620412081, 5146.897062058757, 5167.819126330579, 5192.221166824968, 3766.5856755756913, 3878.631530004659, 3889.358255887775, 3935.583190051579, 4047.7166598338126, 3386.3735208986295, 3422.5892892852894, 3985.2428038672706, 4024.223285932712, 4092.5498195198506, 4008.3185858403995, 4021.8391264349393, 4037.535683396403, 4038.5961403859674, 4081.2041651877894, 3536.752427330088, 3612.193903048476, 3779.1874746929266, 3782.583915769263, 4039.474868267121, 2939.83830889301, 3158.3525377297597, 3526.5616024376286, 3529.5705239378094, 3534.3814832404482, 5124.948750512484, 5125.20676231451, 5127.769250383742, 5131.820386286499, 5131.897362052775, 3832.559255685604, 4420.602145806893, 4451.710638808467, 4617.261238201905, 4632.536746325374, 4904.288878112654, 5412.485813847678, 5430.15832545956, 5457.645253058538, 5495.642783219078, 3410.7271418286477, 3858.7105967052585, 3896.3571010783185, 3905.7656540607654, 3913.2956067907767, 5180.844574662766, 5267.86740850719, 5819.682780814937, 5862.032764593837, 5904.1734157218025, 3240.902772916816, 3287.4411350070545, 3475.113845969312, 3871.6902647788675, 3881.6118388160958, 3619.167591453928, 3673.5959044505084, 3896.0, 3918.961475209072, 3935.704822138351, 4359.629431498358, 4465.955340446619, 4523.163214805271, 4528.796204170821, 4584.954150458453, 4660.161170989187, 5057.342545469139, 5366.672662948997, 5459.972700136554, 5569.860753481195, 1163.4996951311018, 1219.0921208308969, 1221.1757063981954, 1230.4004793768065, 1272.910896237273, 818.1777313799594, 858.759547326737, 860.1785295043219, 866.9523176225368, 868.7263511993701, 4305.051040037584, 4333.566643335705, 4610.492845786961, 4975.253711943237, 5140.845774626767, 4859.173940430135, 5146.6911985281, 5426.620136590401, 5567.443255674481, 5578.912112138094, 4946.427857514144, 5174.767135478913, 5203.2195170723935, 5234.9476505235225, 5239.161734122522, 2468.568000599888, 2545.478176973716, 2622.7770639495475, 2700.756931876101, 2825.675047369538, 4399.714018588781, 4957.231629097496, 5015.398152221756, 5286.444923283077, 5615.354234263031, 4911.729854857946, 5354.625176237704, 5545.251391062155, 5583.581231407661, 5627.606067575231, 5250.868728281824, 5503.559715222773, 5931.555133365015, 5985.802839432044, 6021.939780602225, 4664.766761661971, 5061.645684802029, 5319.616899606208, 5463.890722185454, 5766.250387449605, 4773.71357718538, 4989.058643671459, 5410.918836217381, 5695.487406133468, 5729.4843464088435, 5284.339457567804, 5383.542398895981, 5717.599188198918, 5981.730822112929, 6019.307779605401, 3405.4210784166257, 3803.8668646597234, 3898.6881049515405, 3904.769997450794, 3905.902352441212, 5416.2688037114485, 5594.38461769197, 5707.600467967202, 5747.683877386784, 5801.94198058014, 4663.743494107721, 5133.204353325241, 5604.719764011865, 5611.691356975405, 5618.438156184512, 4448.799804008762, 5577.52591029767, 5599.0683214291, 5605.635633683797, 5610.49505544503, 5207.947920520746, 5395.433479484755, 5433.673979561239, 5518.696471693935, 5572.3034620672415, 5079.161938280143, 5238.76425560843, 5418.756155972912, 5563.58273129525, 5585.245991791218, 5025.422076461254, 5264.394594621667, 5341.97329013368, 5412.7564259607625, 5427.3215848019], "value": 3979.5272108371123, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [650.3528988656286, 697.8360085379932, 759.2559291893944, 774.5197977254103, 776.022212012864, 11251.0, 13462.057655964074, 14245.14529128253, 14518.927405362985, 14557.636059098515, 12628.873711262884, 12752.362381880916, 12886.613401597944, 12947.028972827029, 13035.283059431727, 667.7896462614285, 832.0057531250159, 841.7306461932184, 843.472829481574, 846.2341580869323, 693.4591019005159, 697.7906628011601, 702.7821375373625, 703.9612821294827, 704.4294121761365, 860.496609483453, 890.1321211818457, 890.6036813617943, 891.5542228885557, 892.629558733125, 888.5290795878195, 888.595688961521, 889.7197382824389, 891.7191084808264, 893.6604090445654, 518.9636725429184, 661.5137873662854, 661.9739403923946, 662.0499583098222, 662.8773676869746, 11459.025982791472, 12487.687807804787, 12965.675858103528, 12995.935020324932, 13272.402741876638, 13300.467981280737, 13584.660383490393, 13711.108777929403, 13862.93068534651, 13883.305834708326, 10776.40729759863, 11080.722981925435, 11196.944015279953, 11258.04306633937, 11313.943430282878, 3618.8585607940445, 3716.163863130801, 3718.033311339071, 3768.246350729865, 3808.504894363715, 89915.0, 90631.64052539282, 90683.54658226733, 90866.0, 91814.08185918188, 9911.15755160805, 10286.794264114676, 10340.741481463023, 10388.688339349832, 10696.41169735672, 18471.90764046198, 19595.90202049009, 19697.901510492644, 19742.60514789696, 19760.209591616174, 7588.924110758932, 7590.924090759132, 7638.312551870247, 7686.346660533917, 7773.144954055049, 3503.5795704515112, 4148.23257697327, 4154.273002224599, 4182.414461975326, 4219.810108545123, 3376.274101068233, 3496.877609283714, 3498.1429549760473, 3502.1419752160373, 3508.1755787389943, 636.5734957578434, 641.3618449642636, 646.679893452744, 654.254150268694, 660.0890770736353, 557.3506864502809, 564.7938502446577, 588.0268156201494, 638.409471239102, 651.4137276451232, 8300.958495207486, 8819.0, 8950.418222815626, 8995.59215984176, 9059.184673379295, 575.4475703324855, 615.6213928433981, 619.9770378874892, 633.8161933039366, 674.942629876466, 4261.829526818892, 4379.824807007748, 4380.67144964129, 4415.227335216388, 4496.482904465963, 63961.0, 93681.0631893686, 95421.0, 112296.0, 118315.0, 73439.63280183564, 82060.0, 99828.0, 103321.48339258255, 107584.0, 87276.99999999873, 87594.0, 89368.0, 92466.0, 93160.53419732858, 7986.520808751494, 8689.913100869164, 9364.859527106963, 9458.716238512856, 9671.70984870455, 38987.41518877276, 41696.79151604223, 48614.513854862416, 50349.0, 50585.747071264406, 66934.0, 69278.0, 70293.0, 71045.0, 71568.64215678888, 11929.0, 11942.0, 12076.396180191132, 12289.0, 12366.62900112998, 12864.866469136261, 12955.676108097374, 13407.597772066854, 13739.793903091662, 13809.930950345184, 12777.744445111231, 12829.93585032069, 13472.393742281816, 13624.93187534056, 13899.0, 12118.62259473252, 12360.876391235972, 12814.167079139826, 13206.471741130246, 13810.93094534521, 12614.810777838524, 12817.615471535495, 12851.678708032374, 12876.163049401759, 13316.600501984956, 10940.12479001662, 11087.22389432732, 11172.273802202832, 11252.549898003988, 11670.0, 9527.428354298765, 10932.726681833017, 10944.343339399662, 11270.323780573191, 11359.204855660024], "value": 7120.9354277294315, "unit": "score"}], "name": "dromaeo_css", "value": 6994.1775369800025, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 115.0, 115.0, 115.0, 165.0, 137.0, 115.0, 115.0, 113.0, 114.0], "value": 122.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 110.0, 111.0, 143.0, 111.0, 112.0, 110.0, 106.0, 139.0, 110.0], "value": 119.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 187.0, 182.0, 183.0, 181.0, 182.0, 183.0, 185.0, 183.0, 181.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 58.0, 108.0, 87.0, 82.0, 52.0, 59.0, 110.0, 81.0], "value": 77.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 69.0, 93.0, 70.0, 69.0, 69.0, 89.0, 69.0, 69.0, 91.0], "value": 77.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 87.0, 87.0, 84.0, 88.0, 85.0, 87.0, 84.0], "value": 86.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 93.0, 93.0, 94.0, 93.0, 93.0, 93.0, 92.0, 92.0, 93.0], "value": 92.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 81.0, 82.0, 81.0, 83.0, 85.0, 83.0, 81.0, 80.0], "value": 82.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 52.0, 52.0, 58.0, 58.0, 58.0, 57.0, 56.0, 57.0], "value": 55.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 78.0, 75.0, 73.0, 69.0, 69.0, 69.0, 69.0, 70.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 83.0, 85.0, 83.0, 82.0, 84.0, 84.0, 84.0, 88.0], "value": 83.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 135.0, 141.0, 146.0, 156.0, 145.0, 144.0, 146.0, 180.0, 146.0], "value": 148.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 165.0, 161.0, 163.0, 158.0, 164.0, 164.0, 164.0, 162.0], "value": 162.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 70.0, 65.0, 83.0, 66.0, 68.0, 66.0, 66.0], "value": 68.7, "unit": "score"}], "name": "kraken", "value": 1430.0, "alertThreshold": 2.0}]} 03:17:14 INFO - Return code: 0 03:17:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:17:14 INFO - # TBPL SUCCESS # 03:17:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:17:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:17:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:17:14 INFO - 2017-01-18 03:17:14.696 screenresolution[1657:10715] starting screenresolution argv=screenresolution get 03:17:14 INFO - 2017-01-18 03:17:14.700 screenresolution[1657:10715] Display 0: 1600x1200x32@60 03:17:14 INFO - 2017-01-18 03:17:14.706 screenresolution[1658:10718] starting screenresolution argv=screenresolution list 03:17:14 INFO - Available Modes on Display 0 03:17:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:17:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:17:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:17:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:17:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:17:14 INFO - Intel Iris: 03:17:14 INFO - Chipset Model: Intel Iris 03:17:14 INFO - Type: GPU 03:17:14 INFO - Bus: Built-In 03:17:14 INFO - VRAM (Dynamic, Max): 1536 MB 03:17:14 INFO - Vendor: Intel (0x8086) 03:17:14 INFO - Device ID: 0x0a2e 03:17:14 INFO - Revision ID: 0x0009 03:17:14 INFO - Displays: 03:17:14 INFO - Display: 03:17:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:17:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:17:14 INFO - Display Serial Number: 1600x1200 60 03:17:14 INFO - Main Display: Yes 03:17:14 INFO - Mirror: Off 03:17:14 INFO - Online: Yes 03:17:14 INFO - Rotation: Supported 03:17:14 INFO - Return code: 0 03:17:14 INFO - Running post-action listener: _resource_record_post_action 03:17:14 INFO - [mozharness: 2017-01-18 11:17:14.952944Z] Finished run-tests step (success) 03:17:14 INFO - Running post-run listener: _resource_record_post_run 03:17:15 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 32607232; Write bytes: 655226368; Read time: 201; Write time: 1861 03:17:15 INFO - TinderboxPrint: CPU usage
53.6% 03:17:15 INFO - TinderboxPrint: I/O read bytes / time
32,607,232 / 201 03:17:15 INFO - TinderboxPrint: I/O write bytes / time
655,226,368 / 1,861 03:17:15 INFO - TinderboxPrint: CPU idle
2,247.0 (46.4%) 03:17:15 INFO - TinderboxPrint: CPU system
967.8 (20.0%) 03:17:15 INFO - TinderboxPrint: CPU user
1,626.8 (33.6%) 03:17:15 INFO - TinderboxPrint: Swap in / out
378,138,624 / 0 03:17:15 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 350132736; Write bytes: 352744960; Read time: 26895; Write time: 743 03:17:15 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 29182976; Write bytes: 300969984; Read time: 177; Write time: 1112 03:17:15 INFO - Running post-run listener: _upload_blobber_files 03:17:15 INFO - Blob upload gear active. 03:17:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:17:15 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 03:17:15 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'] 03:17:15 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 03:17:15 INFO - (blobuploader) - INFO - Open directory for files ... 03:17:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:17:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:17:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:17:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:17:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:17:16 INFO - (blobuploader) - INFO - Done attempting. 03:17:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:17:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:17:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:17:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:17:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:17:17 INFO - (blobuploader) - INFO - Done attempting. 03:17:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:17:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:17:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:17:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:17:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:17:20 INFO - (blobuploader) - INFO - Done attempting. 03:17:20 INFO - (blobuploader) - INFO - Iteration through files over. 03:17:20 INFO - Return code: 0 03:17:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:17:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:17:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aab5666e9329accf468eed12b11937b01429836369db2d18f6ef5f353284bdcf1492c88986d6c825cc329faaf5be61401cadcd13510ac71044e436ed6ff5e08b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5665f6fc28e56f9911e1aec8f3cb4064e7e834e3080db32587ce574823575b37ca5705728c37b21d2b0dfb0aa7547a69b4eb5204faf214c1438f1520d0447c6b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2acb8728d1c2fdb8e8556868839d346b54504e9ca05eb6b7a408aa56a15ccd0aff7a7056577b8148f1ba0883f27bd99d974924ba76c7d64e77887cfffe7c24c5"} 03:17:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:17:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:17:20 INFO - Contents: 03:17:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aab5666e9329accf468eed12b11937b01429836369db2d18f6ef5f353284bdcf1492c88986d6c825cc329faaf5be61401cadcd13510ac71044e436ed6ff5e08b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5665f6fc28e56f9911e1aec8f3cb4064e7e834e3080db32587ce574823575b37ca5705728c37b21d2b0dfb0aa7547a69b4eb5204faf214c1438f1520d0447c6b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2acb8728d1c2fdb8e8556868839d346b54504e9ca05eb6b7a408aa56a15ccd0aff7a7056577b8148f1ba0883f27bd99d974924ba76c7d64e77887cfffe7c24c5"} 03:17:20 INFO - Running post-run listener: copy_logs_to_upload_dir 03:17:20 INFO - Copying logs to upload dir... 03:17:20 INFO - mkdir: /builds/slave/test/build/upload/logs 03:17:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.736656 ========= master_lag: 4.84 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-01-18 03:17:25.132025) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-18 03:17:25.139716) ========= 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.ToVBQWsph6/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.vxzxfXIi35/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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/aab5666e9329accf468eed12b11937b01429836369db2d18f6ef5f353284bdcf1492c88986d6c825cc329faaf5be61401cadcd13510ac71044e436ed6ff5e08b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5665f6fc28e56f9911e1aec8f3cb4064e7e834e3080db32587ce574823575b37ca5705728c37b21d2b0dfb0aa7547a69b4eb5204faf214c1438f1520d0447c6b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2acb8728d1c2fdb8e8556868839d346b54504e9ca05eb6b7a408aa56a15ccd0aff7a7056577b8148f1ba0883f27bd99d974924ba76c7d64e77887cfffe7c24c5"} build_url:https://queue.taskcluster.net/v1/task/Rez5mHwMQUqZOnNOuERCiQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011611 build_url: 'https://queue.taskcluster.net/v1/task/Rez5mHwMQUqZOnNOuERCiQ/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/aab5666e9329accf468eed12b11937b01429836369db2d18f6ef5f353284bdcf1492c88986d6c825cc329faaf5be61401cadcd13510ac71044e436ed6ff5e08b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5665f6fc28e56f9911e1aec8f3cb4064e7e834e3080db32587ce574823575b37ca5705728c37b21d2b0dfb0aa7547a69b4eb5204faf214c1438f1520d0447c6b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2acb8728d1c2fdb8e8556868839d346b54504e9ca05eb6b7a408aa56a15ccd0aff7a7056577b8148f1ba0883f27bd99d974924ba76c7d64e77887cfffe7c24c5"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-18 03:17:25.183743) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 03:17:25.184070) ========= 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.ToVBQWsph6/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.vxzxfXIi35/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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.005206 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 03:17:25.235327) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-18 03:17:25.235695) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-18 03:17:25.236351) ========= ========= Total master_lag: 5.03 =========