builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0273 starttime: 1484941529.11 results: success (0) buildid: 20170120104124 builduid: 20eedb6855f2492a93f6283de17eb9e6 revision: 086c474cfefbe660922ce3d588dea7213559b24a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-20 11:45:29.108130) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-20 11:45:29.109166) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-20 11:45:29.109868) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-20 11:45:29.140784) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 11:45:29.141515) ========= 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.EIYh0SyZlN/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.5x0lyg9xIx/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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-20 11:45:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 131M=0s 2017-01-20 11:45:29 (131 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.378029 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 11:45:29.548760) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 11:45:29.549505) ========= 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.EIYh0SyZlN/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.5x0lyg9xIx/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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.024472 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 11:45:29.600730) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 11:45:29.601427) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 086c474cfefbe660922ce3d588dea7213559b24a --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 086c474cfefbe660922ce3d588dea7213559b24a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.EIYh0SyZlN/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.5x0lyg9xIx/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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-20 11:45:29,722 truncating revision to first 12 chars 2017-01-20 11:45:29,722 Setting DEBUG logging. 2017-01-20 11:45:29,722 attempt 1/10 2017-01-20 11:45:29,722 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/086c474cfefb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-20 11:45:30,037 unpacking tar archive at: mozilla-aurora-086c474cfefb/testing/mozharness/ program finished with exit code 0 elapsedTime=0.612636 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 11:45:30.243060) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-20 11:45:30.243857) ========= script_repo_revision: 086c474cfefbe660922ce3d588dea7213559b24a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-20 11:45:30.244832) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-20 11:45:30.245500) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-20 11:45:30.267293) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-01-20 11:45:30.268003) ========= /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.EIYh0SyZlN/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.5x0lyg9xIx/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 11:45:30 INFO - MultiFileLogger online at 20170120 11:45:30 in /builds/slave/test 11:45:30 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 11:45:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:45:30 INFO - {'append_to_log': False, 11:45:30 INFO - 'base_work_dir': '/builds/slave/test', 11:45:30 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 11:45:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:45:30 INFO - 'branch': 'Mozilla-Aurora', 11:45:30 INFO - 'buildbot_json_path': 'buildprops.json', 11:45:30 INFO - 'config_files': ('talos/mac_config.py',), 11:45:30 INFO - 'default_actions': ('clobber', 11:45:30 INFO - 'read-buildbot-config', 11:45:30 INFO - 'download-and-extract', 11:45:30 INFO - 'populate-webroot', 11:45:30 INFO - 'create-virtualenv', 11:45:30 INFO - 'install', 11:45:30 INFO - 'run-tests'), 11:45:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:45:30 INFO - 'download_minidump_stackwalk': True, 11:45:30 INFO - 'download_symbols': 'ondemand', 11:45:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:45:30 INFO - 'tooltool.py': '/tools/tooltool.py', 11:45:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:45:30 INFO - '/tools/misc-python/virtualenv.py')}, 11:45:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:45:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:45:30 INFO - 'installer_path': 'installer.exe', 11:45:30 INFO - 'log_level': 'info', 11:45:30 INFO - 'log_name': 'talos', 11:45:30 INFO - 'log_to_console': True, 11:45:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:45:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:45:30 INFO - 'opt_config_files': (), 11:45:30 INFO - 'pip_index': False, 11:45:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:45:30 INFO - 'cmd': ('bash', 11:45:30 INFO - '-c', 11:45:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:45:30 INFO - 'enabled': True, 11:45:30 INFO - 'halt_on_failure': False, 11:45:30 INFO - 'name': 'check_screen_resolution'},), 11:45:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:45:30 INFO - 'cmd': ('bash', 11:45:30 INFO - '-c', 11:45:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:45:30 INFO - 'enabled': True, 11:45:30 INFO - 'halt_on_failure': False, 11:45:30 INFO - 'name': 'check_screen_resolution'},), 11:45:30 INFO - 'run_cmd_checks_enabled': True, 11:45:30 INFO - 'sps_profile': False, 11:45:30 INFO - 'sps_profile_interval': 0, 11:45:30 INFO - 'suite': 'dromaeojs', 11:45:30 INFO - 'system_bits': '32', 11:45:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:45:30 INFO - 'title': 't-yosemite-r7-0273', 11:45:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:45:30 INFO - 'use_talos_json': True, 11:45:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:45:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:45:30 INFO - 'work_dir': 'build'} 11:45:30 INFO - [mozharness: 2017-01-20 19:45:30.497362Z] Running clobber step. 11:45:30 INFO - Running pre-action listener: _resource_record_pre_action 11:45:30 INFO - Running main action method: clobber 11:45:30 INFO - rmtree: /builds/slave/test/build 11:45:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:45:32 INFO - Running post-action listener: _resource_record_post_action 11:45:32 INFO - [mozharness: 2017-01-20 19:45:32.925183Z] Finished clobber step (success) 11:45:32 INFO - [mozharness: 2017-01-20 19:45:32.925315Z] Running read-buildbot-config step. 11:45:32 INFO - Running pre-action listener: _resource_record_pre_action 11:45:32 INFO - Running main action method: read_buildbot_config 11:45:32 INFO - Using buildbot properties: 11:45:32 INFO - { 11:45:32 INFO - "project": "", 11:45:32 INFO - "product": "firefox", 11:45:32 INFO - "script_repo_revision": "production", 11:45:32 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 11:45:32 INFO - "repository": "", 11:45:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 11:45:32 INFO - "buildid": "20170120104124", 11:45:32 INFO - "pgo_build": "False", 11:45:32 INFO - "basedir": "/builds/slave/test", 11:45:32 INFO - "buildnumber": 25, 11:45:32 INFO - "slavename": "t-yosemite-r7-0273", 11:45:32 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 11:45:32 INFO - "platform": "yosemite_r7", 11:45:32 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 11:45:32 INFO - "branch": "mozilla-aurora", 11:45:32 INFO - "repo_path": "releases/mozilla-aurora", 11:45:32 INFO - "revision": "086c474cfefbe660922ce3d588dea7213559b24a", 11:45:32 INFO - "stage_platform": "macosx64", 11:45:32 INFO - "builduid": "20eedb6855f2492a93f6283de17eb9e6", 11:45:32 INFO - "slavebuilddir": "test" 11:45:32 INFO - } 11:45:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/NBz51HwdRoqPR2RstrCxpA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 11:45:32 INFO - Running post-action listener: _resource_record_post_action 11:45:32 INFO - [mozharness: 2017-01-20 19:45:32.928406Z] Finished read-buildbot-config step (success) 11:45:32 INFO - [mozharness: 2017-01-20 19:45:32.928535Z] Running download-and-extract step. 11:45:32 INFO - Running pre-action listener: _resource_record_pre_action 11:45:32 INFO - Running main action method: download_and_extract 11:45:32 INFO - mkdir: /builds/slave/test/build/tests 11:45:32 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')]} 11:45:32 INFO - trying https://queue.taskcluster.net/v1/task/NBz51HwdRoqPR2RstrCxpA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 11:45:32 INFO - Downloading https://queue.taskcluster.net/v1/task/NBz51HwdRoqPR2RstrCxpA/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 11:45:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NBz51HwdRoqPR2RstrCxpA/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 11:45:34 INFO - Downloaded 1199 bytes. 11:45:34 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 11:45:34 INFO - Using the following test package requirements: 11:45:34 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 11:45:34 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:45:34 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 11:45:34 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:45:34 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 11:45:34 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:45:34 INFO - u'jsshell-mac.zip'], 11:45:34 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:45:34 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 11:45:34 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 11:45:34 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:45:34 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 11:45:34 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:45:34 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 11:45:34 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:45:34 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 11:45:34 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:45:34 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 11:45:34 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 11:45:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NBz51HwdRoqPR2RstrCxpA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 11:45:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NBz51HwdRoqPR2RstrCxpA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:45:34 INFO - Fetch https://queue.taskcluster.net/v1/task/NBz51HwdRoqPR2RstrCxpA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 11:45:34 INFO - Http code: 200 11:45:34 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 11:45:34 INFO - x-amz-cf-id: ir-L4XFOZLTlz0eMgwYYG1jKr3S4DBBFCgHmiJGlZivTu0gMHdmxYg== 11:45:34 INFO - x-amz-version-id: Sqwjg3Haa_TKL7lXCX5wk6DZzx8VzRKP 11:45:35 INFO - Expected file size: 16276104 11:45:35 INFO - Obtained file size: 16276104 11:45:41 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 11:45:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NBz51HwdRoqPR2RstrCxpA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 11:45:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NBz51HwdRoqPR2RstrCxpA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:45:41 INFO - Fetch https://queue.taskcluster.net/v1/task/NBz51HwdRoqPR2RstrCxpA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 11:45:42 INFO - Http code: 200 11:45:42 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 11:45:42 INFO - x-amz-cf-id: CU52RafSc6YsUnNNprZku2zrbUKQ8Z7nY4AfgD7ywm_C0lyMX61S7Q== 11:45:42 INFO - x-amz-version-id: Sqwjg3Haa_TKL7lXCX5wk6DZzx8VzRKP 11:45:42 INFO - Expected file size: 16276104 11:45:42 INFO - Obtained file size: 16276104 11:45:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NBz51HwdRoqPR2RstrCxpA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 11:45:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NBz51HwdRoqPR2RstrCxpA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 11:45:48 INFO - Fetch https://queue.taskcluster.net/v1/task/NBz51HwdRoqPR2RstrCxpA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 11:45:49 INFO - Http code: 200 11:45:49 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 11:45:49 INFO - x-amz-cf-id: _HjQ4ZptusveOHFOsJ_t_Vy8iq2g91237_cn_av5jKWsilcXm6vI4w== 11:45:49 INFO - x-amz-version-id: T5Z1xc7axvgSJPk8ZiShGoHuyMJtq_Hk 11:45:49 INFO - Expected file size: 14409038 11:45:49 INFO - Obtained file size: 14409038 11:45:50 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')]} 11:45:50 INFO - trying https://queue.taskcluster.net/v1/task/NBz51HwdRoqPR2RstrCxpA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 11:45:50 INFO - Downloading https://queue.taskcluster.net/v1/task/NBz51HwdRoqPR2RstrCxpA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 11:45:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NBz51HwdRoqPR2RstrCxpA/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 11:45:52 INFO - Downloaded 109972270 bytes. 11:45:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NBz51HwdRoqPR2RstrCxpA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 11:45:52 INFO - mkdir: /builds/slave/test/properties 11:45:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:45:52 INFO - Writing to file /builds/slave/test/properties/build_url 11:45:52 INFO - Contents: 11:45:52 INFO - build_url:https://queue.taskcluster.net/v1/task/NBz51HwdRoqPR2RstrCxpA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 11:45:53 INFO - Running post-action listener: _resource_record_post_action 11:45:53 INFO - Running post-action listener: set_extra_try_arguments 11:45:53 INFO - [mozharness: 2017-01-20 19:45:53.690684Z] Finished download-and-extract step (success) 11:45:53 INFO - [mozharness: 2017-01-20 19:45:53.690806Z] Running populate-webroot step. 11:45:53 INFO - Running pre-action listener: _resource_record_pre_action 11:45:53 INFO - Running main action method: populate_webroot 11:45:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:45:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:45:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:45:53 INFO - '--tppagecycles', 11:45:53 INFO - '7'], 11:45:53 INFO - 'tests': ['tsvgm']}}, 11:45:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:45:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:45:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:45:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:45:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:45:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:45:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:45:53 INFO - 'talos_options': ['--disable-e10s'], 11:45:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:45:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:45:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:45:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:45:53 INFO - 'talos_options': ['--disable-e10s'], 11:45:53 INFO - 'tests': ['damp', 'tps']}, 11:45:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:45:53 INFO - 'tests': ['damp', 'tps']}, 11:45:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:45:53 INFO - 'tests': ['dromaeo_dom']}, 11:45:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:45:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:45:53 INFO - 'tests': ['basic_compositor_video']}, 11:45:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:45:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:45:53 INFO - 'tests': ['a11yr', 11:45:53 INFO - 'ts_paint', 11:45:53 INFO - 'tpaint', 11:45:53 INFO - 'sessionrestore', 11:45:53 INFO - 'sessionrestore_no_auto_restore', 11:45:53 INFO - 'tabpaint']}, 11:45:53 INFO - 'other-e10s': {'tests': ['a11yr', 11:45:53 INFO - 'ts_paint', 11:45:53 INFO - 'tpaint', 11:45:53 INFO - 'sessionrestore', 11:45:53 INFO - 'sessionrestore_no_auto_restore', 11:45:53 INFO - 'tabpaint']}, 11:45:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:45:53 INFO - 'ts_paint', 11:45:53 INFO - 'tpaint', 11:45:53 INFO - 'sessionrestore', 11:45:53 INFO - 'sessionrestore_no_auto_restore', 11:45:53 INFO - 'tabpaint']}, 11:45:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:45:53 INFO - 'ts_paint', 11:45:53 INFO - 'tpaint', 11:45:53 INFO - 'sessionrestore', 11:45:53 INFO - 'sessionrestore_no_auto_restore', 11:45:53 INFO - 'tabpaint']}, 11:45:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:45:53 INFO - 'tests': ['a11yr', 11:45:53 INFO - 'ts_paint', 11:45:53 INFO - 'tpaint', 11:45:53 INFO - 'sessionrestore', 11:45:53 INFO - 'sessionrestore_no_auto_restore', 11:45:53 INFO - 'tabpaint']}, 11:45:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:45:53 INFO - 'tests': ['a11yr', 11:45:53 INFO - 'ts_paint', 11:45:53 INFO - 'tpaint', 11:45:53 INFO - 'sessionrestore', 11:45:53 INFO - 'sessionrestore_no_auto_restore', 11:45:53 INFO - 'tabpaint']}, 11:45:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:45:53 INFO - 'tests': ['tsvgx', 11:45:53 INFO - 'tsvgr_opacity', 11:45:53 INFO - 'tart', 11:45:53 INFO - 'tscrollx', 11:45:53 INFO - 'cart']}, 11:45:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:45:53 INFO - 'tsvgr_opacity', 11:45:53 INFO - 'tart', 11:45:53 INFO - 'tscrollx', 11:45:53 INFO - 'cart']}, 11:45:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:45:53 INFO - 'talos_options': ['--disable-e10s'], 11:45:53 INFO - 'tests': ['tp5o']}, 11:45:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:45:53 INFO - 'tests': ['tp5o']}, 11:45:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:45:53 INFO - 'talos_options': ['--disable-e10s', 11:45:53 INFO - '--xperf_path', 11:45:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:45:53 INFO - 'tests': ['tp5n']}, 11:45:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:45:53 INFO - 'talos_options': ['--xperf_path', 11:45:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:45:53 INFO - 'tests': ['tp5n']}}, 11:45:53 INFO - 'talos.zip': {'path': '', 11:45:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:45:53 INFO - Running post-action listener: _resource_record_post_action 11:45:53 INFO - [mozharness: 2017-01-20 19:45:53.709703Z] Finished populate-webroot step (success) 11:45:53 INFO - [mozharness: 2017-01-20 19:45:53.709809Z] Running create-virtualenv step. 11:45:53 INFO - Running pre-action listener: _resource_record_pre_action 11:45:53 INFO - Running main action method: create_virtualenv 11:45:53 INFO - Creating virtualenv /builds/slave/test/build/venv 11:45:53 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 11:45:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:45:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:45:53 INFO - Using real prefix '/tools/python27' 11:45:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:45:54 INFO - Installing distribute.............................................................................................................................................................................................done. 11:45:57 INFO - Installing pip.................done. 11:45:57 INFO - Return code: 0 11:45:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:45:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:45:57 INFO - Reading from file tmpfile_stdout 11:45:57 INFO - Output received: 11:45:57 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) 11:45:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:45: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')]} 11:45:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:45:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:45: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')]} 11:45:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:45:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:45:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ee77490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eded570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa548564cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec8bd30>, '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 0x10eaf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec83b20>, '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.EIYh0SyZlN/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.5x0lyg9xIx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 11:45:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:45:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:45:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EIYh0SyZlN/Render', 11:45:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:45:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:45:57 INFO - 'HOME': '/Users/cltbld', 11:45:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:45:57 INFO - 'LOGNAME': 'cltbld', 11:45:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:45:57 INFO - 'MOZ_NO_REMOTE': '1', 11:45:57 INFO - 'NO_EM_RESTART': '1', 11:45:57 INFO - 'PAGER': '/bin/cat', 11:45:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:45:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:45:57 INFO - 'PWD': '/builds/slave/test', 11:45:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:45:57 INFO - 'SHELL': '/bin/bash', 11:45:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5x0lyg9xIx/Listeners', 11:45:57 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 11:45:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:45:57 INFO - 'USER': 'cltbld', 11:45:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:45:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:45:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:45:57 INFO - 'XPC_FLAGS': '0x0', 11:45:57 INFO - 'XPC_SERVICE_NAME': '0', 11:45:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:45:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:45:57 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 11:45:57 INFO - Cleaning up... 11:45:57 INFO - Return code: 0 11:45:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:45: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')]} 11:45:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:45:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:45: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')]} 11:45:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:45:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:45:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ee77490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eded570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa548564cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec8bd30>, '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 0x10eaf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec83b20>, '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.EIYh0SyZlN/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.5x0lyg9xIx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 11:45:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:45:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:45:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EIYh0SyZlN/Render', 11:45:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:45:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:45:57 INFO - 'HOME': '/Users/cltbld', 11:45:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:45:57 INFO - 'LOGNAME': 'cltbld', 11:45:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:45:57 INFO - 'MOZ_NO_REMOTE': '1', 11:45:57 INFO - 'NO_EM_RESTART': '1', 11:45:57 INFO - 'PAGER': '/bin/cat', 11:45:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:45:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:45:57 INFO - 'PWD': '/builds/slave/test', 11:45:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:45:57 INFO - 'SHELL': '/bin/bash', 11:45:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5x0lyg9xIx/Listeners', 11:45:57 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 11:45:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:45:57 INFO - 'USER': 'cltbld', 11:45:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:45:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:45:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:45:57 INFO - 'XPC_FLAGS': '0x0', 11:45:57 INFO - 'XPC_SERVICE_NAME': '0', 11:45:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:45:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:45:58 INFO - Downloading/unpacking psutil>=3.1.1 11:45:58 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 11:45:58 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 11:45:58 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 11:45:58 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 11:45:58 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 11:45:58 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 11:46:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:46:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:46:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:46:01 INFO - Installing collected packages: psutil 11:46:01 INFO - Running setup.py install for psutil 11:46:01 INFO - building 'psutil._psutil_osx' extension 11:46:01 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 11:46:02 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 11:46:02 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 11:46:02 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 11:46:02 INFO - building 'psutil._psutil_posix' extension 11:46:02 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 11:46:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:46:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:46:02 INFO - ^ 11:46:02 INFO - 1 warning generated. 11:46:02 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 11:46:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:46:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:46:02 INFO - Successfully installed psutil 11:46:02 INFO - Cleaning up... 11:46:02 INFO - Return code: 0 11:46:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:46:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:46:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:46:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:46:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:46:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:02 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 0x10ee77490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eded570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa548564cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec8bd30>, '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 0x10eaf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec83b20>, '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.EIYh0SyZlN/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.5x0lyg9xIx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 11:46:02 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 11:46:02 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 11:46:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EIYh0SyZlN/Render', 11:46:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:46:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:46:02 INFO - 'HOME': '/Users/cltbld', 11:46:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:46:02 INFO - 'LOGNAME': 'cltbld', 11:46:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:46:02 INFO - 'MOZ_NO_REMOTE': '1', 11:46:02 INFO - 'NO_EM_RESTART': '1', 11:46:02 INFO - 'PAGER': '/bin/cat', 11:46:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:46:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:46:02 INFO - 'PWD': '/builds/slave/test', 11:46:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:46:02 INFO - 'SHELL': '/bin/bash', 11:46:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5x0lyg9xIx/Listeners', 11:46:02 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 11:46:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:46:02 INFO - 'USER': 'cltbld', 11:46:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:46:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:46:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:46:02 INFO - 'XPC_FLAGS': '0x0', 11:46:02 INFO - 'XPC_SERVICE_NAME': '0', 11:46:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:46:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:46:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:46:03 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 11:46:03 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 11:46:03 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 11:46:03 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 11:46:03 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 11:46:03 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 11:46:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:46:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:46:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:46:05 INFO - Installing collected packages: mozsystemmonitor 11:46:05 INFO - Running setup.py install for mozsystemmonitor 11:46:06 INFO - Successfully installed mozsystemmonitor 11:46:06 INFO - Cleaning up... 11:46:06 INFO - Return code: 0 11:46:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:46:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:46:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:46:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:46:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:46:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:06 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 0x10ee77490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eded570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa548564cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec8bd30>, '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 0x10eaf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec83b20>, '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.EIYh0SyZlN/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.5x0lyg9xIx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 11:46:06 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 11:46:06 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 11:46:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EIYh0SyZlN/Render', 11:46:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:46:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:46:06 INFO - 'HOME': '/Users/cltbld', 11:46:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:46:06 INFO - 'LOGNAME': 'cltbld', 11:46:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:46:06 INFO - 'MOZ_NO_REMOTE': '1', 11:46:06 INFO - 'NO_EM_RESTART': '1', 11:46:06 INFO - 'PAGER': '/bin/cat', 11:46:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:46:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:46:06 INFO - 'PWD': '/builds/slave/test', 11:46:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:46:06 INFO - 'SHELL': '/bin/bash', 11:46:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5x0lyg9xIx/Listeners', 11:46:06 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 11:46:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:46:06 INFO - 'USER': 'cltbld', 11:46:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:46:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:46:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:46:06 INFO - 'XPC_FLAGS': '0x0', 11:46:06 INFO - 'XPC_SERVICE_NAME': '0', 11:46:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:46:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:46:06 INFO - Downloading/unpacking jsonschema==2.5.1 11:46:06 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 11:46:06 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 11:46:06 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 11:46:06 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 11:46:06 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 11:46:06 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 11:46:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:46:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:46:09 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 11:46:09 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 11:46:09 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 11:46:09 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 11:46:09 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 11:46:09 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 11:46:09 INFO - Downloading functools32-3.2.3-2.tar.gz 11:46:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:46:09 INFO - warning: no files found matching '*.txt' 11:46:09 INFO - no previously-included directories found matching 'build' 11:46:09 INFO - no previously-included directories found matching 'dist' 11:46:09 INFO - no previously-included directories found matching '.git*' 11:46:09 INFO - Installing collected packages: jsonschema, functools32 11:46:09 INFO - Running setup.py install for functools32 11:46:09 INFO - warning: no files found matching '*.txt' 11:46:09 INFO - no previously-included directories found matching 'build' 11:46:09 INFO - no previously-included directories found matching 'dist' 11:46:09 INFO - no previously-included directories found matching '.git*' 11:46:09 INFO - Successfully installed jsonschema functools32 11:46:09 INFO - Cleaning up... 11:46:09 INFO - Return code: 0 11:46:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:46:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:46:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:46:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:46:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:46:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:09 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 0x10ee77490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eded570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa548564cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec8bd30>, '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 0x10eaf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec83b20>, '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.EIYh0SyZlN/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.5x0lyg9xIx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 11:46:09 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 11:46:09 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 11:46:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EIYh0SyZlN/Render', 11:46:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:46:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:46:09 INFO - 'HOME': '/Users/cltbld', 11:46:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:46:09 INFO - 'LOGNAME': 'cltbld', 11:46:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:46:09 INFO - 'MOZ_NO_REMOTE': '1', 11:46:09 INFO - 'NO_EM_RESTART': '1', 11:46:09 INFO - 'PAGER': '/bin/cat', 11:46:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:46:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:46:09 INFO - 'PWD': '/builds/slave/test', 11:46:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:46:09 INFO - 'SHELL': '/bin/bash', 11:46:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5x0lyg9xIx/Listeners', 11:46:09 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 11:46:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:46:09 INFO - 'USER': 'cltbld', 11:46:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:46:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:46:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:46:09 INFO - 'XPC_FLAGS': '0x0', 11:46:09 INFO - 'XPC_SERVICE_NAME': '0', 11:46:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:46:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:46:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:46:10 INFO - Cleaning up... 11:46:10 INFO - Return code: 0 11:46:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:46: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')]} 11:46:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:46:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46: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')]} 11:46:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:46:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:10 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 0x10ee77490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eded570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa548564cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec8bd30>, '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 0x10eaf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec83b20>, '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.EIYh0SyZlN/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.5x0lyg9xIx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 11:46:10 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 11:46:10 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 11:46:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EIYh0SyZlN/Render', 11:46:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:46:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:46:10 INFO - 'HOME': '/Users/cltbld', 11:46:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:46:10 INFO - 'LOGNAME': 'cltbld', 11:46:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:46:10 INFO - 'MOZ_NO_REMOTE': '1', 11:46:10 INFO - 'NO_EM_RESTART': '1', 11:46:10 INFO - 'PAGER': '/bin/cat', 11:46:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:46:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:46:10 INFO - 'PWD': '/builds/slave/test', 11:46:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:46:10 INFO - 'SHELL': '/bin/bash', 11:46:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5x0lyg9xIx/Listeners', 11:46:10 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 11:46:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:46:10 INFO - 'USER': 'cltbld', 11:46:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:46:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:46:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:46:10 INFO - 'XPC_FLAGS': '0x0', 11:46:10 INFO - 'XPC_SERVICE_NAME': '0', 11:46:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:46:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:46:10 INFO - Downloading/unpacking blobuploader==1.2.4 11:46:10 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 11:46:10 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 11:46:10 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 11:46:10 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 11:46:10 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 11:46:10 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 11:46:12 INFO - Downloading blobuploader-1.2.4.tar.gz 11:46:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:46:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:46:13 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 11:46:13 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 11:46:13 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 11:46:13 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 11:46:13 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 11:46:13 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 11:46:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:46:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:46:13 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 11:46:13 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 11:46:13 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 11:46:13 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 11:46:13 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 11:46:13 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 11:46:14 INFO - Downloading docopt-0.6.1.tar.gz 11:46:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:46:14 INFO - Installing collected packages: blobuploader, requests, docopt 11:46:14 INFO - Running setup.py install for blobuploader 11:46:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:46:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:46:14 INFO - Running setup.py install for requests 11:46:14 INFO - Running setup.py install for docopt 11:46:15 INFO - Successfully installed blobuploader requests docopt 11:46:15 INFO - Cleaning up... 11:46:15 INFO - Return code: 0 11:46:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:46:15 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')]} 11:46:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:46:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:15 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')]} 11:46:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:46:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:15 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 0x10ee77490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eded570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa548564cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec8bd30>, '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 0x10eaf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec83b20>, '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.EIYh0SyZlN/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.5x0lyg9xIx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 11:46:15 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 11:46:15 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 11:46:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EIYh0SyZlN/Render', 11:46:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:46:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:46:15 INFO - 'HOME': '/Users/cltbld', 11:46:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:46:15 INFO - 'LOGNAME': 'cltbld', 11:46:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:46:15 INFO - 'MOZ_NO_REMOTE': '1', 11:46:15 INFO - 'NO_EM_RESTART': '1', 11:46:15 INFO - 'PAGER': '/bin/cat', 11:46:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:46:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:46:15 INFO - 'PWD': '/builds/slave/test', 11:46:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:46:15 INFO - 'SHELL': '/bin/bash', 11:46:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5x0lyg9xIx/Listeners', 11:46:15 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 11:46:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:46:15 INFO - 'USER': 'cltbld', 11:46:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:46:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:46:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:46:15 INFO - 'XPC_FLAGS': '0x0', 11:46:15 INFO - 'XPC_SERVICE_NAME': '0', 11:46:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:46:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:46:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:46:15 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-izkWuJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:46:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:46:15 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-s0zV3p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:46:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:46:15 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-I9fDAr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:46:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:46:15 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-QPN6e3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:46:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:46:15 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-nxSAjB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:46:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:46:16 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-ONmO1R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:46:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:46:16 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-a5Ls3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:46:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:46:16 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-BLAiHd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:46:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:46:16 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-S3w1Ei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:46:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:46:16 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-Yynhm7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:46:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:46:16 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-G2Dhff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:46:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:46:16 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-bOuBn2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:46:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:46:16 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-w0a3eW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:46:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:46:16 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-xBGmzy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:46:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:46:16 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-AGPFPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:46:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:46:17 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-ZA7Jku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:46:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:46:17 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-BnkQjC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:46:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:46:17 INFO - Running setup.py install for manifestparser 11:46:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:46:17 INFO - Running setup.py install for mozcrash 11:46:17 INFO - Running setup.py install for mozdebug 11:46:17 INFO - Running setup.py install for mozdevice 11:46:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:46:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:46:17 INFO - Running setup.py install for mozfile 11:46:18 INFO - Running setup.py install for mozhttpd 11:46:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:46:18 INFO - Running setup.py install for mozinfo 11:46:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:46:18 INFO - Running setup.py install for mozInstall 11:46:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:46:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:46:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:46:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:46:18 INFO - Running setup.py install for mozleak 11:46:18 INFO - Running setup.py install for mozlog 11:46:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:46:18 INFO - Running setup.py install for moznetwork 11:46:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:46:18 INFO - Running setup.py install for mozprocess 11:46:19 INFO - Running setup.py install for mozprofile 11:46:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:46:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:46:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:46:19 INFO - Running setup.py install for mozrunner 11:46:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:46:19 INFO - Running setup.py install for mozscreenshot 11:46:19 INFO - Running setup.py install for moztest 11:46:19 INFO - Running setup.py install for mozversion 11:46:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:46:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:46:19 INFO - Cleaning up... 11:46:19 INFO - Return code: 0 11:46:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:46:19 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')]} 11:46:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:46:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:19 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')]} 11:46:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:46:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:19 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 0x10ee77490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eded570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa548564cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec8bd30>, '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 0x10eaf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec83b20>, '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.EIYh0SyZlN/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.5x0lyg9xIx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 11:46:19 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 11:46:19 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 11:46:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EIYh0SyZlN/Render', 11:46:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:46:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:46:19 INFO - 'HOME': '/Users/cltbld', 11:46:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:46:19 INFO - 'LOGNAME': 'cltbld', 11:46:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:46:19 INFO - 'MOZ_NO_REMOTE': '1', 11:46:19 INFO - 'NO_EM_RESTART': '1', 11:46:19 INFO - 'PAGER': '/bin/cat', 11:46:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:46:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:46:19 INFO - 'PWD': '/builds/slave/test', 11:46:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:46:19 INFO - 'SHELL': '/bin/bash', 11:46:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5x0lyg9xIx/Listeners', 11:46:19 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 11:46:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:46:19 INFO - 'USER': 'cltbld', 11:46:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:46:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:46:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:46:19 INFO - 'XPC_FLAGS': '0x0', 11:46:19 INFO - 'XPC_SERVICE_NAME': '0', 11:46:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:46:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:46:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:46:20 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-50RclH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:46:20 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)) 11:46:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:46:20 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-fVhCL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:46:20 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)) 11:46:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:46:20 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-rMog4a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:46:20 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)) 11:46:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:46:20 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-tUB6ad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:46:20 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)) 11:46:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:46:20 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-phacPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:46:20 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)) 11:46:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:46:20 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-rkzjsc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:46:20 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)) 11:46:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:46:20 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-C2RlQA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:46:20 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)) 11:46:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:46:20 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-tJKLLy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:46:21 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)) 11:46:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:46:21 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-3I4KZ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:46:21 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)) 11:46:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:46:21 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-lTFul0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:46:21 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)) 11:46:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:46:21 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-pvgS7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:46:21 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)) 11:46:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:46:21 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-gZs6ME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:46:21 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)) 11:46:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:46:21 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-mqKxEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:46:21 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)) 11:46:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:46:21 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-vTEqVT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:46:21 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)) 11:46:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:46:21 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-8SpDZ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:46:21 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)) 11:46:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:46:21 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-TclwU8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:46:21 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)) 11:46:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:46:21 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-b0rARb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:46:22 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)) 11:46:22 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)) 11:46:22 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)) 11:46:22 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)) 11:46:22 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)) 11:46:22 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)) 11:46:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:46:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:46:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:46:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:46:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:46:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:46:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:46:24 INFO - Downloading blessings-1.6.tar.gz 11:46:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:46:24 INFO - Installing collected packages: blessings 11:46:24 INFO - Running setup.py install for blessings 11:46:25 INFO - Successfully installed blessings 11:46:25 INFO - Cleaning up... 11:46:25 INFO - Return code: 0 11:46:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:46:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:46:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:46:25 INFO - Reading from file tmpfile_stdout 11:46:25 INFO - Current package versions: 11:46:25 INFO - blessings == 1.6 11:46:25 INFO - blobuploader == 1.2.4 11:46:25 INFO - docopt == 0.6.1 11:46:25 INFO - functools32 == 3.2.3-2 11:46:25 INFO - jsonschema == 2.5.1 11:46:25 INFO - manifestparser == 1.1 11:46:25 INFO - mozInstall == 1.12 11:46:25 INFO - mozcrash == 1.0 11:46:25 INFO - mozdebug == 0.1 11:46:25 INFO - mozdevice == 0.48 11:46:25 INFO - mozfile == 1.2 11:46:25 INFO - mozhttpd == 0.7 11:46:25 INFO - mozinfo == 0.9 11:46:25 INFO - mozleak == 0.1 11:46:25 INFO - mozlog == 3.4 11:46:25 INFO - moznetwork == 0.27 11:46:25 INFO - mozprocess == 0.23 11:46:25 INFO - mozprofile == 0.28 11:46:25 INFO - mozrunner == 6.13 11:46:25 INFO - mozscreenshot == 0.1 11:46:25 INFO - mozsystemmonitor == 0.3 11:46:25 INFO - moztest == 0.8 11:46:25 INFO - mozversion == 1.4 11:46:25 INFO - psutil == 3.1.1 11:46:25 INFO - requests == 1.2.3 11:46:25 INFO - wsgiref == 0.1.2 11:46:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:46:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:46:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:46:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:26 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')]} 11:46:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:46:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:26 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 0x10ee77490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eded570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa548564cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec8bd30>, '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 0x10eaf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec83b20>, '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.EIYh0SyZlN/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.5x0lyg9xIx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 11:46:26 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 11:46:26 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 11:46:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EIYh0SyZlN/Render', 11:46:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:46:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:46:26 INFO - 'HOME': '/Users/cltbld', 11:46:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:46:26 INFO - 'LOGNAME': 'cltbld', 11:46:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:46:26 INFO - 'MOZ_NO_REMOTE': '1', 11:46:26 INFO - 'NO_EM_RESTART': '1', 11:46:26 INFO - 'PAGER': '/bin/cat', 11:46:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:46:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:46:26 INFO - 'PWD': '/builds/slave/test', 11:46:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:46:26 INFO - 'SHELL': '/bin/bash', 11:46:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5x0lyg9xIx/Listeners', 11:46:26 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 11:46:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:46:26 INFO - 'USER': 'cltbld', 11:46:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:46:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:46:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:46:26 INFO - 'XPC_FLAGS': '0x0', 11:46:26 INFO - 'XPC_SERVICE_NAME': '0', 11:46:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:46:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:46:26 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)) 11:46:26 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)) 11:46:26 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)) 11:46:26 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)) 11:46:26 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)) 11:46:26 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)) 11:46:26 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)) 11:46:26 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)) 11:46:26 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)) 11:46:26 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)) 11:46:26 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)) 11:46:26 INFO - Cleaning up... 11:46:26 INFO - Return code: 0 11:46:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:46:26 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')]} 11:46:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:46:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:26 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')]} 11:46:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:46:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:46:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ee77490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eded570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa548564cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec8bd30>, '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 0x10eaf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec83b20>, '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.EIYh0SyZlN/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.5x0lyg9xIx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 11:46:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:46:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:46:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EIYh0SyZlN/Render', 11:46:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:46:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:46:27 INFO - 'HOME': '/Users/cltbld', 11:46:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:46:27 INFO - 'LOGNAME': 'cltbld', 11:46:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:46:27 INFO - 'MOZ_NO_REMOTE': '1', 11:46:27 INFO - 'NO_EM_RESTART': '1', 11:46:27 INFO - 'PAGER': '/bin/cat', 11:46:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:46:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:46:27 INFO - 'PWD': '/builds/slave/test', 11:46:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:46:27 INFO - 'SHELL': '/bin/bash', 11:46:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5x0lyg9xIx/Listeners', 11:46:27 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 11:46:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:46:27 INFO - 'USER': 'cltbld', 11:46:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:46:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:46:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:46:27 INFO - 'XPC_FLAGS': '0x0', 11:46:27 INFO - 'XPC_SERVICE_NAME': '0', 11:46:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:46:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:46:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:46:27 INFO - Cleaning up... 11:46:27 INFO - Return code: 0 11:46:27 INFO - Running post-action listener: _resource_record_post_action 11:46:27 INFO - Running post-action listener: _start_resource_monitoring 11:46:27 INFO - Starting resource monitoring. 11:46:27 INFO - [mozharness: 2017-01-20 19:46:27.594467Z] Finished create-virtualenv step (success) 11:46:27 INFO - [mozharness: 2017-01-20 19:46:27.595505Z] Running install step. 11:46:27 INFO - Running pre-action listener: _resource_record_pre_action 11:46:27 INFO - Running main action method: install 11:46:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:46:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:46:27 INFO - Reading from file tmpfile_stdout 11:46:27 INFO - Detecting whether we're running mozinstall >=1.0... 11:46:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:46:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:46:27 INFO - Reading from file tmpfile_stdout 11:46:27 INFO - Output received: 11:46:27 INFO - Usage: mozinstall [options] installer 11:46:27 INFO - Options: 11:46:27 INFO - -h, --help show this help message and exit 11:46:27 INFO - -d DEST, --destination=DEST 11:46:27 INFO - Directory to install application into. [default: 11:46:27 INFO - "/builds/slave/test"] 11:46:27 INFO - --app=APP Application being installed. [default: firefox] 11:46:27 INFO - mkdir: /builds/slave/test/build/application 11:46:27 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'] 11:46:27 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 11:46:59 INFO - Reading from file tmpfile_stdout 11:46:59 INFO - Output received: 11:46:59 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 11:46:59 INFO - Running post-action listener: _resource_record_post_action 11:46:59 INFO - [mozharness: 2017-01-20 19:46:59.450884Z] Finished install step (success) 11:46:59 INFO - [mozharness: 2017-01-20 19:46:59.451023Z] Running run-tests step. 11:46:59 INFO - Running pre-action listener: _resource_record_pre_action 11:46:59 INFO - Running main action method: run_tests 11:46:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:46:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:46:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:46:59 INFO - 2017-01-20 11:46:59.463 screenresolution[1628:7503] starting screenresolution argv=screenresolution get 11:46:59 INFO - 2017-01-20 11:46:59.465 screenresolution[1628:7503] Display 0: 1600x1200x32@60 11:46:59 INFO - 2017-01-20 11:46:59.470 screenresolution[1629:7505] starting screenresolution argv=screenresolution list 11:46:59 INFO - Available Modes on Display 0 11:46:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:46:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:46:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:46:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:46:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:46:59 INFO - Intel Iris: 11:46:59 INFO - Chipset Model: Intel Iris 11:46:59 INFO - Type: GPU 11:46:59 INFO - Bus: Built-In 11:46:59 INFO - VRAM (Dynamic, Max): 1536 MB 11:46:59 INFO - Vendor: Intel (0x8086) 11:46:59 INFO - Device ID: 0x0a2e 11:46:59 INFO - Revision ID: 0x0009 11:46:59 INFO - Displays: 11:46:59 INFO - Display: 11:46:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:46:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:46:59 INFO - Display Serial Number: 1600x1200 60 11:46:59 INFO - Main Display: Yes 11:46:59 INFO - Mirror: Off 11:46:59 INFO - Online: Yes 11:46:59 INFO - Rotation: Supported 11:46:59 INFO - Return code: 0 11:46:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:46:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:46:59 INFO - Python 2.7.3 11:46:59 INFO - Return code: 0 11:46:59 INFO - grabbing minidump binary from tooltool 11:46:59 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')]} 11:46:59 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 0x10ec8bd30>, '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 0x10eaf1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ec83b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:46:59 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 11:46:59 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 11:46:59 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 11:46:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:46:59 INFO - Return code: 0 11:46:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:46:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:46:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:46:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:46:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:46:59 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/NBz51HwdRoqPR2RstrCxpA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0273', '--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 11:46:59 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/NBz51HwdRoqPR2RstrCxpA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0273 --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 11:46:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EIYh0SyZlN/Render', 11:46:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:46:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:46:59 INFO - 'HOME': '/Users/cltbld', 11:46:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:46:59 INFO - 'LOGNAME': 'cltbld', 11:46:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:46:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:46:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:46:59 INFO - 'MOZ_NO_REMOTE': '1', 11:46:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:46:59 INFO - 'NO_EM_RESTART': '1', 11:46:59 INFO - 'PAGER': '/bin/cat', 11:46:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:46:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:46:59 INFO - 'PWD': '/builds/slave/test', 11:46:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:46:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:46:59 INFO - 'SHELL': '/bin/bash', 11:46:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5x0lyg9xIx/Listeners', 11:46:59 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 11:46:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:46:59 INFO - 'USER': 'cltbld', 11:46:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:46:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:46:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:46:59 INFO - 'XPC_FLAGS': '0x0', 11:46:59 INFO - 'XPC_SERVICE_NAME': '0', 11:46:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:46:59 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/NBz51HwdRoqPR2RstrCxpA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0273', '--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 11:47:00 INFO - mozversion application_buildid: 20170120104124 11:47:00 INFO - mozversion application_changeset: 086c474cfefbe660922ce3d588dea7213559b24a 11:47:00 INFO - mozversion application_display_name: FirefoxDeveloperEdition 11:47:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:47:00 INFO - mozversion application_name: Firefox 11:47:00 INFO - mozversion application_remotingname: firefox-dev 11:47:00 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:47:00 INFO - mozversion application_vendor: Mozilla 11:47:00 INFO - mozversion application_version: 52.0a2 11:47:00 INFO - mozversion platform_buildid: 20170120104124 11:47:00 INFO - mozversion platform_changeset: 086c474cfefbe660922ce3d588dea7213559b24a 11:47:00 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:47:00 INFO - mozversion platform_version: 52.0a2 11:47:00 INFO - using testdate: 1484941620 11:47:00 INFO - actual date: 1484941620 11:47:00 INFO - starting webserver on 'localhost:49242' 11:47:00 INFO - SUITE-START | Running 2 tests 11:47:00 INFO - TEST-START | dromaeo_css 11:47:00 INFO - operating with platform_type : mac_ 11:47:00 INFO - Initialising browser for dromaeo_css test... 11:47:00 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/tmpWHLa0a/profile http://localhost:49242/getInfo.html) 11:47:02 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 11:47:02 INFO - PROCESS | 1639 | colorDepth:24 11:47:02 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 11:47:02 INFO - PROCESS | 1639 | __metrics 11:47:02 INFO - PROCESS | 1639 | 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" 11:47:02 INFO - PROCESS | 1639 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:47:03 INFO - TEST-INFO | 1639: exit 0 11:47:03 INFO - Browser initialized. 11:47:03 INFO - Running cycle 1/1 for dromaeo_css test... 11:47:03 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/tmpWHLa0a/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:47:16 INFO - PROCESS | 1641 | RSS: Main: 222552064 11:47:16 INFO - PROCESS | 1641 | 11:50:19 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 11:50:21 INFO - PROCESS | 1641 | RSS: Main: 344379392 11:50:21 INFO - PROCESS | 1641 | 11:53:24 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 11:53:24 INFO - PROCESS | 1641 | RSS: Main: 346415104 11:53:24 INFO - PROCESS | 1641 | 11:56:26 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 11:56:27 INFO - PROCESS | 1641 | RSS: Main: 347885568 11:56:27 INFO - PROCESS | 1641 | 11:59:29 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 11:59:30 INFO - PROCESS | 1641 | RSS: Main: 327290880 11:59:30 INFO - PROCESS | 1641 | 12:02:33 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 12:02:34 INFO - PROCESS | 1641 | RSS: Main: 308154368 12:02:34 INFO - PROCESS | 1641 | 12:05:37 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 12:05:38 INFO - PROCESS | 1641 | RSS: Main: 356560896 12:05:38 INFO - PROCESS | 1641 | 12:05:38 INFO - PROCESS | 1641 | __start_tp_report 12:05:38 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 12:05:38 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 12:05:38 INFO - PROCESS | 1641 | |i|pagename|runs| 12:05:38 INFO - PROCESS | 1641 | |0;dojo.html;2664;3329.8335083245834;3343.3146205027965;3346.3809195298854;3383.390989621869;5639.971800140998;5663.065594176966;5678.176664383662;5687.886242275157;5688.459596338346;5630.831075067745;5695.857603559903;5710.343310519292;5722.456366645172;5729.885402291951;5156.484351564851;5179.197224430201;5180.274761533388;5228.111221092405;5252.579793616521;4683.882902927437;4684.812607495696;5144.742762861861;5152.201408781645;5155.407128180251;5217.765200565984;5276.973615131919;5285.075111855429;5292.179712144623;5306.93861227754;5254.290670759437;5268.81559145428;5269.104252277084;5301.5228629423345;5305.840824775263;5027.773750181251;5143.794248230066;5197.974010129925;5210;5230.97384513075;5582.91625625616;5592.496675299242;5620.522255608278;5640.351359593658;5675.659460432387;6096.69516524172;6123.112148738408;6142.385761423867;6189.071639254103;6190.504759619264;5464.207689884985;6155.953487907022;6274.686265686697;6275.466585340251;6277.8744425111245;5480.835574932718;5617.185508101303;5620.859478512988;5655.971720141455;5757.884842303172;6107.378548143415;6186.938130618726;6228.252609686867;6228.4705800007505;6253.468455181361;6112.419320164578;6112.633242005494;6121.387861213843;6122.510199184055;6131.96934015327;5363.249145119687;6110.953680641517;6146.231721034871;6242.406971337716;6261.186045814106;6047.6069055512135;6106.72519736613;6107.4197951194565;6108.908366374508;6111.755529778848;5157.974210129;6087.4521293083835;6116.235470566179;6135.9079613805825;6143.201383820075;4899;4932.482089380635;4954.430240522314;4958.925616115761;4967.602591792648;4356;4360.887973566783;4371.082072764755;4382.583654552876;4426.712263702849;5508.944910550923;5553.888922221614;5696.401877802854;5699.74351154199;5731.455511726379;6139.662318572432;6171.105189747551;6267.341929097471;6271.6550589718;6285.8742825143245;5707.229524014288;5740;5744.454276843693;5758.2514273144225;5764.048931926185;5371.892562148735;5382.946170538323;5385.461453854661;5391.595630327741;5394.136938089887;6134.130457564626;6142.713648198372;6197.318294987457;6203.44169024799;6223.097650840693;6134.392883024786;6136.263648362046;6143.96928015357;6154.046122850965;6154.907676384947;5181.248718935809;6178.721957511832;6234.1583886174785;6238.033104868752;6245.781397651061;5986.132010858489;5998.310194327708;6053.273607166992;6058.545609079425;6064.120702498201;6138.541671868701;6249.312575616587;6251.156093927443;6271.498280137487;6273.686315684289;6082.90875636857;6083.6045657032155;6109.877802444015;6112.847178820565;6135.969320153371;5915.112733090071;5964.612300200473;6067.906572232573;6097.237845269341;6114.816555503342;5864.179014937827;5864.501517371071;5885.117232415001;5953.315368732651;5996.310424301087;6058.363871793398;6061.878762424815;6074.483668888194;6089.847753806191;6099.939000610115;5688.829335119953;5893.941060589339;5906.645601263938;5915.852103697442;5929.822105336847 12:05:38 INFO - PROCESS | 1641 | |1;ext.html;432.2241576370415;464.85357112509575;466.9416322959629;482.2789929056062;536.7826030457666;30001.39997200055;31634.52548211776;38165.42751858725;39047;40008;41400.79299603505;41702.79148604253;41769.7911510442;41799.58200417995;41837;9342.439453632804;9530.332876698634;9533;9535.094166054214;9543.904560954372;8484.490930544187;8715.477071375706;9332.766680832965;11154.996050355467;11453.713657158596;11359.204855660108;11423.828642570368;11449.942750286278;11450.370229637367;11452.599159029436;11364.545418183263;11443.370614616193;11449.484773185226;11452.198346115816;11477.885221147764;8472.491650500928;8751.693690720858;11005.889941100566;11296.887031129665;11339.659810205707;39009.41485877714;39446.40830387546;39453.40819887704;39537.011574710574;39581.80209098965;3347.598288205431;3580.15866271426;3682.581390025196;3780.0310769353396;3952.5256969163884;28121;28226.29434264139;29007.564886526718;29499.410011799642;30580.235494112825;3175.841207939594;3189.059227527885;3194.872205111793;3243.6918492743152;3269.54226408303;500868.99999999633;501324;504605;505118;507249;33361.83319083389;34207;34350.48474272888;58242;58686.4131358681;34868.82565587156;34975.30049398998;34983.475247871305;35036.299274014375;35066.64933350685;14189.78715319271;14313.928430357782;14332;14346.713065738626;14379.424823007172;11093.445327733582;12102.515899363927;12188.085893558018;12207.267563946192;12238.89849913512;7311.086114235721;7433.85132297351;7435.628218589049;7438.851222975511;7446.851062978819;2936.7797415193945;3189.3621275744945;3221.7905836120576;3259.4784834426378;3261.2662151015834;944.17856464876;944.5560586524323;945.7588314979773;974.288769198489;992.831218692824;28224.85887570549;28955.276118097216;29217.94481664585;29314.68083936228;29573.260668483028;1004.8040632076821;1045.231754660324;1087.4019289390803;1094.8795632480421;1131.0725205331714;14721.631959201106;15112.622184445258;15193.696126077417;15464.38142474311;15529.301181446635;678446;681269;682019;682051;683804;48175.759121204166;49190.75404622954;54404;55480.72259638757;55971.72014139903;1341.4433010300615;1358.843732970721;1387.3687472199922;1388.6806034612096;1397.4340392141198;29147;29586.852065739535;29808.85095574508;30402.239944001576;30465.23836904095;48593.7851553714;53933.730331348095;55203.44796551983;55253.17120243119;58005.70997144987;629407.8529607323;664240;690934.5453272702;872548;872622;39352.40971385374;39593;40007;40285.992850177805;40818.79590602028;44204.56874871913;44211.77894110509;44329.77835110804;44698.3295250578;44987;35952.82023589865;36082.8195859019;36882.44676329804;38129;38180.427293590066;36776.44835327419;36853.85233461311;36893;37251;37475.875723728335;36196;36822.8158859204;36991.445128323634;37014.814925925195;37032.07419814526;30804.075877723702;32895.50656740103;33099;33217.335653287286;33277;24521.632175517738;24674.50650987006;24874.626880597167;24912.62631060572;25265.747342527076 12:05:38 INFO - PROCESS | 1641 | |2;jquery.html;352.95235143255655;370.3666729891885;371.9293334266487;382.8813067948936;400.2854903996367;14651.926740366296;14987.400503979841;15779.368825247006;20031.098600562975;20157.59684806303;19071.618567628673;19405.902970485164;19468;20106.798932010675;20299.898500507476;5759.712014399273;5997.85005374867;6007.429294217042;6009.1587177795145;6013.819585412422;4837.4195096588455;6505.837354066162;6609.537332386735;6627.801165965029;6641.070250164981;5904.970475147618;6481.027845823117;6487.675616219194;6508.349165083478;6580.835479113037;6529.5429319947625;6564.901526477082;6572.408483236484;6574.638394888279;6577.539572229945;5430.83707488776;5575.832725018256;5634.802781902655;5634.943650563483;5635.239242702265;24274.757252427426;24845.254642360756;25091.49817003668;25270.873645631655;25597.104101356363;2070.9171633134656;2200.702905107806;2327.813774898004;2452.865092419916;3005.1735772662473;14967.850321496864;14986.850131498764;15057.849421505754;16026.198690065567;16043.35826566936;1855.174447370907;1888.3296429767508;1903.5021854472595;1918.7409699690502;1927.4025052233712;113335.4333228345;117937;119669;120150.39924800408;120325;9830.852537211947;9882.802343953224;11053.839346868624;11525.942370288263;11571.132165087653;14662.120272783453;14696.338664760111;14699.191544464944;14704.852951470562;14709.264536773118;8187.4678145920325;8472.110428405053;8512.40413171072;8599.226069653761;8618.870716939251;8369.49783013009;8378.371622128365;8415.873761893576;8518.48889066658;8538;3821.8662346817728;4459.509453960061;4574.428196475441;4609.492955774862;4678.730815554453;1883.5667796406904;1922.5482011727233;1924.3457224543722;1925.9325923592887;1932.1305412564402;855.3157474020883;882.8101958079044;937.0114529171789;946.4841661294611;952.3000594562991;20306.79693203049;20790.896045519676;21159.68260476094;21173.470663233544;21183.8940805295;911.977200569991;948.2745699539857;963.6868017894212;963.802420503795;1000.8090372456899;10292.794144117226;11068.612598559012;11071.280366776135;11122.443877806078;11137.331760094421;577133;592942;601463;606497;607147;128708;129488;129497;131041;136359.31820340836;944.7874228298577;952.8999455057119;980.5048450532436;982.1602529837029;984.675057231112;18121.90939045296;18310.725339120192;18484.63030739351;18634.813651863307;18660.720089198945;53300.73349633382;53573;53882.73058634682;54582.72708636431;55312.72343638256;77105.84341234998;77881.22118778739;78467.60766196133;79102.6044869795;79676.60161699154;7982.880256796268;8036.718714844952;8124.796880078045;8202.38482113832;8273.958630206811;11909.344986025852;12079.879201208227;12522.311272880079;12988.350582471028;13230.007749419025;11168.832467512831;11196;11212;11533.250338727956;11567.479463424335;10795.784084318428;10826.079783218514;11195.832062519232;11385.373804440835;11459.025982791556;11319.377434241196;11443.313401195956;11464.598739044093;11703.180777345504;11772.70568235801;8000.199980001953;8130.105688374156;8132.9186708132165;8407.495550267004;8560.272376848037;8379.162083791573;10979.166250524557;11177;11594.594189203337;11608.825867611824 12:05:38 INFO - PROCESS | 1641 | |3;mootools.html;1277.9872201277985;1461.2985766831923;1499.490173341064;1650.9091999940006;1842.797292297847;7318.34134927857;7692.423068269881;7874.133845277015;8261.793455163624;8293.668253269861;8096.67613295469;8194.30348420385;8197.590120493982;8201.179882011797;8206.933098697162;2296.219285442949;2334.1947028275304;2334.9533009339802;2336.532693461306;2339.2397470821966;2298.5058212484314;2350.083467447697;2353.388119089032;2357.6581395697613;2358.8230882683783;2170.4899348653053;2181;2203.1187524989973;2203.4050806282294;2208.0726095040122;2284.942876428086;2290.1183044527893;2312.9306120816404;2315.4327189838596;2321.6401457774073;1977.7033444983224;2316.4440534271653;2339.695839540866;2341.94145146371;2342.8008619267384;8146.714864979697;8211.01467823859;8221.013478382632;8223.506589604583;8227.42408031438;4955.281484184773;5151.407588127338;5164.096283150471;5170;5184.974075129638;3281.753868459875;3446.207372304359;3452.7583069185166;3524.594671612746;3528.770629909048;973.6008236623021;1035.6646504013574;1039.272509243525;1066.2269854355607;1075.887031861659;10522.894771052344;10610.257281990263;10644;10652.254342196049;10704.518296676668;4243.639290660267;4389.626881715026;4461.464624245046;4466.821327146943;4484;8399.538025408661;8500.957495212484;8561.44350617208;8566.18621230982;8580.184882436159;5057.418396884332;5059.645824792303;5065.11360511909;5075.0357432087785;5082.796688132434;4041.8585349512628;4230.344296634026;4386.254336762758;4403.4495688039;4669.533046695371;3635.7273204509765;3660.066682995819;3725.590185079639;3727.8881633551036;3741.064733816546;997.5909876950416;1130.8190689489643;1131.4003578103618;1131.886811318878;1139.2993309115;743.9702411903571;833.3832963607008;860.4579115157411;866.3848667446058;900.6847603338779;5388.137897936312;5470.425605311465;5475.425080366584;5577.609567330248;5601.355844077902;436.2670713201788;451.11806418451846;461.45087346058085;940.4122423485321;962.3263715399248;3118.251619611277;3647.9817600912356;3656.891642104243;3759.9248015039548;3763.284975854579;9630.566624501775;9901.356411833207;9983.650572230083;10002.84967228778;10010.399376037316;9959.452230127268;9965.750856228507;9976.750581235527;9985.900140998643;9987.450690211963;7569.621518924032;7646.464747467624;7657.425693073042;7851;7859.410544209093;5381.596380271417;5389.649672771257;5394.838154855361;5418.187271909245;5497.8075767347955;8980.685676001309;9274.350795444254;9276.350655454053;9285.860712089188;9303.255739540957;8096.271335579826;8419.200175983271;8747.037825839025;9323.347365684336;9391.906080939376;6647.268800432045;6942.791716248521;7070.858582828418;7157;7208.927910720826;7749.263819937322;7848.64681089341;7910.129885712452;7922.683092676229;7923.20767923203;6802.319768023356;6916.930830691628;6960.303969603202;6979.127609048869;7113.146422429135;6857.451403887777;6901.723931042702;6925.722971081101;6989.7553585624255;7030.437564994891;6593.703283352356;6633.270340262471;6647.60114393138;6690.732370705117;6695.698693558704;4613.723176609415;4691.812327506861;4797.520247975174;4822.855314340575;4923.310736496965;4659.953400465952;4779.545943135397;4791.976040119777;4854.126257273725;4933.9013219736125 12:05:38 INFO - PROCESS | 1641 | |4;prototype.html;298.226103262035;301.7751774927679;307.2840282142608;312.7060563070713;313.39045556392824;4793.873439741658;4994.875128121799;5173.637845350827;5227.895442091156;5296.496832800878;4888.826681596418;5050.7222102784335;5050.949490505094;5092.479222784717;5246.081935661263;3774.1508160663907;3854;3870.9225815483674;3874.1984976985696;3954.6638535724496;3342.0115031556184;3368;3956.287868183726;3975.3241948868617;3982;3157.7159753856076;3306.9173270668184;3385;3536.964630353702;3904.2581909437117;3274.5415641810164;3301.669833016679;3918.45141680165;3945.4870866787132;3965.2466031453932;3391;3410.931781364359;3412.3791199180437;3416.8462419191196;3429.53701250333;5024.748762561857;5033.924491132636;5051.494850514956;5053.393592768891;5096.133657278235;3415.334009868077;3684.4841722158653;4035.9910022494378;4263.59495847894;4415.4259946206785;4689.929651055237;5306.787728490855;5336.226247194135;5499.862503437406;5510.311211098613;3270.4767237241927;3760.3795373763296;3760.623937606245;3777.3578491656485;3799.6200379961783;4580.404547408849;4839.41926968766;5557.472040156179;5716.828495145152;5762.740676669518;3784.3188226119564;3812.341631390354;3813.3326667833016;3813.3517302058162;3835.7890316032326;3728.12389088564;3861.691064714816;3879.4568760373572;3895.824687888995;3902.590228026073;3824.3116239077235;4263.658907287409;4398.758068306274;4497.460304763384;4511.526289739596;4732.0960207828675;4900.803967841246;5086.338775959101;5490.835274941758;5494.955958367897;1136.9772604547863;1233.747081848219;1241.4537603454633;1245.9626211213679;1262.9621111366673;794.2653045932605;813.5037627047496;826.3884725303352;831.6257684042084;862.3403096631082;4539.051025607677;4552.886177845514;4601.654875884322;4866.345442549479;4878.82924097662;4535.523770004168;5254.500822421864;5299.311089558333;5313.468653134656;5355.678659280456;4464.267876051396;4617.183518053985;4752.120857641278;4784.818095966143;4824.903501929942;2311.768823117675;2574.0990653271206;2670.813043086965;2802.1418153483332;2803.1730639461407;4633.289525627546;4782.426108866958;4807.807687692453;5327.840164795062;5567;4904.814446835332;5320.866978325573;5353;5471.316085489314;5858.606045465901;5921.526277897758;5923.7926672566255;5929.762809487658;5953.583249172516;5993;5498.972505137449;5691.573132015198;5711.314642242791;5717.942820571908;5728.312602487728;4816.494268101799;5138.073222541547;5353.411124776194;5658.6233781810915;5691.544676425793;5396.028714831211;5909.763609455574;5919.099641175707;5927.940720592739;5955.344912059583;3313.50297455374;3533.2756784859557;3536.876209332661;3806.904827379227;3814.847406103836;4686.484486706528;4828.855134345975;5362.954223926297;5645.604807663424;5652.039153343859;4441.844535441244;5437.525925703716;5492.890142197054;5495.395506494362;5599.796043850592;5140.121531942124;5430.266913966564;5485.9725701371235;5500.899820035929;5520;5159.922601160911;5353;5452.973599652231;5467.58993075513;5470.398256191896;4971.776270067783;5172.904973485744;5426.593005524681;5443.727813609383;5570.2201691762375;4593.402857628487;4658.883527911694;5161.251618515219;5209.739513024258;5439.537639300704 12:05:38 INFO - PROCESS | 1641 | |5;yui.html;687.3264201082935;693.9618320992345;721.2679130682357;768.2509553185641;772.551919886466;14203.147811131326;14767.852321476768;14768.704625907476;14788.260586970662;14796.926015369922;11640.243384180023;13251.403686834092;13259.138156019852;13337.666558336046;13379.197248165117;659.8482349059719;679.6839469646613;815.4291995603072;839.3033781960988;845.6194712379454;845.8688903220011;849.235687880909;851.4891065360796;851.595492141231;852.0074113657581;900.8873890763655;902.1159263921361;902.251131560803;902.3052249767675;903.7605034665819;690.8583740333245;715.7995761186902;716.9533980291317;716.9677364518609;719.0580339754938;644.0950464597248;663.2869665109978;666.0575285970347;667.0527850452321;669.6517810738437;14010.879129669602;14149.646258843508;14153.646158846008;14169.433222671081;14197.929010354883;13093.738125237538;13133.212007279593;13155;13307.733845323137;13363;11667.708307292301;11912.940435297767;12018.819717704242;12027.819582706266;12290.324032178227;4219.050713589449;4280.507741609693;4319.568043195655;4330.588594083557;4438.778061096932;87478.12521874828;88868.11131888663;89875.65186522207;90283.86017995053;91800.54099729459;10482.737931551772;10532.894671053346;10536.947315263527;10539.26225164231;10623.256372054037;20269.49326266855;20492.897535512227;20522.38432847017;20542.38372848817;20542.794572054387;7284.526505777108;7568.091828980558;7632.236776322435;7756.379489640815;7780.260875216845;4097.160082183145;4148.149629325981;4193.874183774492;4346.739195648272;4370.038591509863;3176.4758814795764;3278.655741147193;3281.032095531824;3287.474004159323;3291.588551431071;610.9816705498843;620.7920346683911;626.6835248199721;638.8530637953297;639.7281155508983;515.9045576568346;522.5323335614604;595.2499850188793;602.135934933372;606.6663335165641;8447;8635.050144484101;8700.695475658322;8705.651773928972;8828.117188281121;525.1597444089426;552.2847911954029;619.755196697302;624.2602516018442;627.3632263252802;4015.297322968469;4266.935995960063;4320.330348795942;4329.869239141764;4333.544977777288;81375.18624813795;91599;122339;124233;124647;106439.4678026605;109248.4537577307;110021;110253;111779;64562.031569526494;82098.17901821024;82139;84537.57731211305;86852;8140.59297035134;8155.714549990722;8209.835803283782;8341.374396920377;8523.872141917753;49636.25545616891;55695.44304556902;56358;56434.58913527195;57726.13410798757;50474.74762626163;56825.715871420376;57008.714956426615;71247.28752712406;79560.60219699096;12194.87805121973;12391.566295179626;12456.937715311366;12475.875241247471;12533.498660053496;12774.36128193568;13092.279924604238;13373.197608143542;13540.796888046883;13630.182189068688;11701.414929253333;12648.6837829055;13058.738825223632;13519.459221631418;13901.721965560835;12059.57791477294;13306.207730796676;13457.394417251051;13475.19148851072;13576.117552359065;12218.816717749063;13265.403056862271;13585.388657510239;13590.184588924696;13634.93182534081;10230;10488.370697758159;11415.429228538374;11497.655070347902;11721.824172637247;11058.447077646246;11312.60405885823;11355.146721000367;11386.487608057492;11534 12:05:38 INFO - PROCESS | 1641 | __end_tp_report 12:05:38 INFO - PROCESS | 1641 | __start_cc_report 12:05:38 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,1302 12:05:38 INFO - PROCESS | 1641 | __end_cc_report 12:05:38 INFO - PROCESS | 1641 | __startTimestamp1484942738040__endTimestamp 12:05:38 INFO - PROCESS | 1641 | 12:05:38 INFO - PROCESS | 1641 | ------- Summary: start ------- 12:05:38 INFO - PROCESS | 1641 | Number of tests: 6 12:05:38 INFO - PROCESS | 1641 | 12:05:38 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:5673.93 Median:5885.12 stddev:642.61 (10.9%) stddev-sans-first:599.64 12:05:38 INFO - PROCESS | 1641 | Values: 2664.0 3329.8 3343.3 3346.4 3383.4 5640.0 5663.1 5678.2 5687.9 5688.5 5630.8 5695.9 5710.3 5722.5 5729.9 5156.5 5179.2 5180.3 5228.1 5252.6 4683.9 4684.8 5144.7 5152.2 5155.4 5217.8 5277.0 5285.1 5292.2 5306.9 5254.3 5268.8 5269.1 5301.5 5305.8 5027.8 5143.8 5198.0 5210.0 5231.0 5582.9 5592.5 5620.5 5640.4 5675.7 6096.7 6123.1 6142.4 6189.1 6190.5 5464.2 6156.0 6274.7 6275.5 6277.9 5480.8 5617.2 5620.9 5656.0 5757.9 6107.4 6186.9 6228.3 6228.5 6253.5 6112.4 6112.6 6121.4 6122.5 6132.0 5363.2 6111.0 6146.2 6242.4 6261.2 6047.6 6106.7 6107.4 6108.9 6111.8 5158.0 6087.5 6116.2 6135.9 6143.2 4899.0 4932.5 4954.4 4958.9 4967.6 4356.0 4360.9 4371.1 4382.6 4426.7 5508.9 5553.9 5696.4 5699.7 5731.5 6139.7 6171.1 6267.3 6271.7 6285.9 5707.2 5740.0 5744.5 5758.3 5764.0 5371.9 5382.9 5385.5 5391.6 5394.1 6134.1 6142.7 6197.3 6203.4 6223.1 6134.4 6136.3 6144.0 6154.0 6154.9 5181.2 6178.7 6234.2 6238.0 6245.8 5986.1 5998.3 6053.3 6058.5 6064.1 6138.5 6249.3 6251.2 6271.5 6273.7 6082.9 6083.6 6109.9 6112.8 6136.0 5915.1 5964.6 6067.9 6097.2 6114.8 5864.2 5864.5 5885.1 5953.3 5996.3 6058.4 6061.9 6074.5 6089.8 6099.9 5688.8 5893.9 5906.6 5915.9 5929.8 12:05:38 INFO - PROCESS | 1641 | 12:05:38 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:74719.95 Median:20229.51 stddev:176213.00 (871.1%) stddev-sans-first:176619.05 12:05:38 INFO - PROCESS | 1641 | Values: 432.2 464.9 466.9 482.3 536.8 30001.4 31634.5 38165.4 39047.0 40008.0 41400.8 41702.8 41769.8 41799.6 41837.0 9342.4 9530.3 9533.0 9535.1 9543.9 8484.5 8715.5 9332.8 11155.0 11453.7 11359.2 11423.8 11449.9 11450.4 11452.6 11364.5 11443.4 11449.5 11452.2 11477.9 8472.5 8751.7 11005.9 11296.9 11339.7 39009.4 39446.4 39453.4 39537.0 39581.8 3347.6 3580.2 3682.6 3780.0 3952.5 28121.0 28226.3 29007.6 29499.4 30580.2 3175.8 3189.1 3194.9 3243.7 3269.5 500869.0 501324.0 504605.0 505118.0 507249.0 33361.8 34207.0 34350.5 58242.0 58686.4 34868.8 34975.3 34983.5 35036.3 35066.6 14189.8 14313.9 14332.0 14346.7 14379.4 11093.4 12102.5 12188.1 12207.3 12238.9 7311.1 7433.9 7435.6 7438.9 7446.9 2936.8 3189.4 3221.8 3259.5 3261.3 944.2 944.6 945.8 974.3 992.8 28224.9 28955.3 29217.9 29314.7 29573.3 1004.8 1045.2 1087.4 1094.9 1131.1 14721.6 15112.6 15193.7 15464.4 15529.3 678446.0 681269.0 682019.0 682051.0 683804.0 48175.8 49190.8 54404.0 55480.7 55971.7 1341.4 1358.8 1387.4 1388.7 1397.4 29147.0 29586.9 29808.9 30402.2 30465.2 48593.8 53933.7 55203.4 55253.2 58005.7 629407.9 664240.0 690934.5 872548.0 872622.0 39352.4 39593.0 40007.0 40286.0 40818.8 44204.6 44211.8 44329.8 44698.3 44987.0 35952.8 36082.8 36882.4 38129.0 38180.4 36776.4 36853.9 36893.0 37251.0 37475.9 36196.0 36822.8 36991.4 37014.8 37032.1 30804.1 32895.5 33099.0 33217.3 33277.0 24521.6 24674.5 24874.6 24912.6 25265.7 12:05:38 INFO - PROCESS | 1641 | 12:05:38 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:35197.78 Median:25433.99 stddev:99745.26 (392.2%) stddev-sans-first:99990.76 12:05:38 INFO - PROCESS | 1641 | Values: 353.0 370.4 371.9 382.9 400.3 14651.9 14987.4 15779.4 20031.1 20157.6 19071.6 19405.9 19468.0 20106.8 20299.9 5759.7 5997.9 6007.4 6009.2 6013.8 4837.4 6505.8 6609.5 6627.8 6641.1 5905.0 6481.0 6487.7 6508.3 6580.8 6529.5 6564.9 6572.4 6574.6 6577.5 5430.8 5575.8 5634.8 5634.9 5635.2 24274.8 24845.3 25091.5 25270.9 25597.1 2070.9 2200.7 2327.8 2452.9 3005.2 14967.9 14986.9 15057.8 16026.2 16043.4 1855.2 1888.3 1903.5 1918.7 1927.4 113335.4 117937.0 119669.0 120150.4 120325.0 9830.9 9882.8 11053.8 11525.9 11571.1 14662.1 14696.3 14699.2 14704.9 14709.3 8187.5 8472.1 8512.4 8599.2 8618.9 8369.5 8378.4 8415.9 8518.5 8538.0 3821.9 4459.5 4574.4 4609.5 4678.7 1883.6 1922.5 1924.3 1925.9 1932.1 855.3 882.8 937.0 946.5 952.3 20306.8 20790.9 21159.7 21173.5 21183.9 912.0 948.3 963.7 963.8 1000.8 10292.8 11068.6 11071.3 11122.4 11137.3 577133.0 592942.0 601463.0 606497.0 607147.0 128708.0 129488.0 129497.0 131041.0 136359.3 944.8 952.9 980.5 982.2 984.7 18121.9 18310.7 18484.6 18634.8 18660.7 53300.7 53573.0 53882.7 54582.7 55312.7 77105.8 77881.2 78467.6 79102.6 79676.6 7982.9 8036.7 8124.8 8202.4 8274.0 11909.3 12079.9 12522.3 12988.4 13230.0 11168.8 11196.0 11212.0 11533.3 11567.5 10795.8 10826.1 11195.8 11385.4 11459.0 11319.4 11443.3 11464.6 11703.2 11772.7 8000.2 8130.1 8132.9 8407.5 8560.3 8379.2 10979.2 11177.0 11594.6 11608.8 12:05:38 INFO - PROCESS | 1641 | 12:05:38 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:5312.62 Median:5167.05 stddev:2915.58 (56.4%) stddev-sans-first:2907.99 12:05:38 INFO - PROCESS | 1641 | Values: 1278.0 1461.3 1499.5 1650.9 1842.8 7318.3 7692.4 7874.1 8261.8 8293.7 8096.7 8194.3 8197.6 8201.2 8206.9 2296.2 2334.2 2335.0 2336.5 2339.2 2298.5 2350.1 2353.4 2357.7 2358.8 2170.5 2181.0 2203.1 2203.4 2208.1 2284.9 2290.1 2312.9 2315.4 2321.6 1977.7 2316.4 2339.7 2341.9 2342.8 8146.7 8211.0 8221.0 8223.5 8227.4 4955.3 5151.4 5164.1 5170.0 5185.0 3281.8 3446.2 3452.8 3524.6 3528.8 973.6 1035.7 1039.3 1066.2 1075.9 10522.9 10610.3 10644.0 10652.3 10704.5 4243.6 4389.6 4461.5 4466.8 4484.0 8399.5 8501.0 8561.4 8566.2 8580.2 5057.4 5059.6 5065.1 5075.0 5082.8 4041.9 4230.3 4386.3 4403.4 4669.5 3635.7 3660.1 3725.6 3727.9 3741.1 997.6 1130.8 1131.4 1131.9 1139.3 744.0 833.4 860.5 866.4 900.7 5388.1 5470.4 5475.4 5577.6 5601.4 436.3 451.1 461.5 940.4 962.3 3118.3 3648.0 3656.9 3759.9 3763.3 9630.6 9901.4 9983.7 10002.8 10010.4 9959.5 9965.8 9976.8 9985.9 9987.5 7569.6 7646.5 7657.4 7851.0 7859.4 5381.6 5389.6 5394.8 5418.2 5497.8 8980.7 9274.4 9276.4 9285.9 9303.3 8096.3 8419.2 8747.0 9323.3 9391.9 6647.3 6942.8 7070.9 7157.0 7208.9 7749.3 7848.6 7910.1 7922.7 7923.2 6802.3 6916.9 6960.3 6979.1 7113.1 6857.5 6901.7 6925.7 6989.8 7030.4 6593.7 6633.3 6647.6 6690.7 6695.7 4613.7 4691.8 4797.5 4822.9 4923.3 4660.0 4779.5 4792.0 4854.1 4933.9 12:05:38 INFO - PROCESS | 1641 | 12:05:38 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4350.12 Median:4826.88 stddev:1368.23 (28.3%) stddev-sans-first:1337.84 12:05:38 INFO - PROCESS | 1641 | Values: 298.2 301.8 307.3 312.7 313.4 4793.9 4994.9 5173.6 5227.9 5296.5 4888.8 5050.7 5050.9 5092.5 5246.1 3774.2 3854.0 3870.9 3874.2 3954.7 3342.0 3368.0 3956.3 3975.3 3982.0 3157.7 3306.9 3385.0 3537.0 3904.3 3274.5 3301.7 3918.5 3945.5 3965.2 3391.0 3410.9 3412.4 3416.8 3429.5 5024.7 5033.9 5051.5 5053.4 5096.1 3415.3 3684.5 4036.0 4263.6 4415.4 4689.9 5306.8 5336.2 5499.9 5510.3 3270.5 3760.4 3760.6 3777.4 3799.6 4580.4 4839.4 5557.5 5716.8 5762.7 3784.3 3812.3 3813.3 3813.4 3835.8 3728.1 3861.7 3879.5 3895.8 3902.6 3824.3 4263.7 4398.8 4497.5 4511.5 4732.1 4900.8 5086.3 5490.8 5495.0 1137.0 1233.7 1241.5 1246.0 1263.0 794.3 813.5 826.4 831.6 862.3 4539.1 4552.9 4601.7 4866.3 4878.8 4535.5 5254.5 5299.3 5313.5 5355.7 4464.3 4617.2 4752.1 4784.8 4824.9 2311.8 2574.1 2670.8 2802.1 2803.2 4633.3 4782.4 4807.8 5327.8 5567.0 4904.8 5320.9 5353.0 5471.3 5858.6 5921.5 5923.8 5929.8 5953.6 5993.0 5499.0 5691.6 5711.3 5717.9 5728.3 4816.5 5138.1 5353.4 5658.6 5691.5 5396.0 5909.8 5919.1 5927.9 5955.3 3313.5 3533.3 3536.9 3806.9 3814.8 4686.5 4828.9 5363.0 5645.6 5652.0 4441.8 5437.5 5492.9 5495.4 5599.8 5140.1 5430.3 5486.0 5500.9 5520.0 5159.9 5353.0 5453.0 5467.6 5470.4 4971.8 5172.9 5426.6 5443.7 5570.2 4593.4 4658.9 5161.3 5209.7 5439.5 12:05:38 INFO - PROCESS | 1641 | 12:05:38 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:20521.48 Median:4319.95 stddev:30583.68 (708.0%) stddev-sans-first:30633.22 12:05:38 INFO - PROCESS | 1641 | Values: 687.3 694.0 721.3 768.3 772.6 14203.1 14767.9 14768.7 14788.3 14796.9 11640.2 13251.4 13259.1 13337.7 13379.2 659.8 679.7 815.4 839.3 845.6 845.9 849.2 851.5 851.6 852.0 900.9 902.1 902.3 902.3 903.8 690.9 715.8 717.0 717.0 719.1 644.1 663.3 666.1 667.1 669.7 14010.9 14149.6 14153.6 14169.4 14197.9 13093.7 13133.2 13155.0 13307.7 13363.0 11667.7 11912.9 12018.8 12027.8 12290.3 4219.1 4280.5 4319.6 4330.6 4438.8 87478.1 88868.1 89875.7 90283.9 91800.5 10482.7 10532.9 10536.9 10539.3 10623.3 20269.5 20492.9 20522.4 20542.4 20542.8 7284.5 7568.1 7632.2 7756.4 7780.3 4097.2 4148.1 4193.9 4346.7 4370.0 3176.5 3278.7 3281.0 3287.5 3291.6 611.0 620.8 626.7 638.9 639.7 515.9 522.5 595.2 602.1 606.7 8447.0 8635.1 8700.7 8705.7 8828.1 525.2 552.3 619.8 624.3 627.4 4015.3 4266.9 4320.3 4329.9 4333.5 81375.2 91599.0 122339.0 124233.0 124647.0 106439.5 109248.5 110021.0 110253.0 111779.0 64562.0 82098.2 82139.0 84537.6 86852.0 8140.6 8155.7 8209.8 8341.4 8523.9 49636.3 55695.4 56358.0 56434.6 57726.1 50474.7 56825.7 57008.7 71247.3 79560.6 12194.9 12391.6 12456.9 12475.9 12533.5 12774.4 13092.3 13373.2 13540.8 13630.2 11701.4 12648.7 13058.7 13519.5 13901.7 12059.6 13306.2 13457.4 13475.2 13576.1 12218.8 13265.4 13585.4 13590.2 13634.9 10230.0 10488.4 11415.4 11497.7 11721.8 11058.4 11312.6 11355.1 11386.5 11534.0 12:05:38 INFO - PROCESS | 1641 | -------- Summary: end -------- 12:05:38 INFO - PROCESS | 1641 | 12:05:38 INFO - TEST-INFO | 1641: exit 0 12:05:38 INFO - TEST-OK | dromaeo_css | took 1118622ms 12:05:38 INFO - TEST-START | kraken 12:05:38 INFO - operating with platform_type : mac_ 12:05:38 INFO - Initialising browser for kraken test... 12:05:38 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/tmpp71FT_/profile http://localhost:49242/getInfo.html) 12:05:41 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 12:05:41 INFO - PROCESS | 1651 | colorDepth:24 12:05:41 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 12:05:41 INFO - PROCESS | 1651 | __metrics 12:05:41 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" 12:05:41 INFO - PROCESS | 1651 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:05:42 INFO - TEST-INFO | 1651: exit 0 12:05:42 INFO - Browser initialized. 12:05:42 INFO - Running cycle 1/1 for kraken test... 12:05:42 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/tmpp71FT_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:05:54 INFO - PROCESS | 1652 | RSS: Main: 222519296 12:05:54 INFO - PROCESS | 1652 | 12:05:57 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 12:05:58 INFO - PROCESS | 1652 | RSS: Main: 269955072 12:05:58 INFO - PROCESS | 1652 | 12:06:00 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 12:06:01 INFO - PROCESS | 1652 | RSS: Main: 284196864 12:06:01 INFO - PROCESS | 1652 | 12:06:04 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 12:06:05 INFO - PROCESS | 1652 | RSS: Main: 381124608 12:06:05 INFO - PROCESS | 1652 | 12:06:07 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 12:06:07 INFO - PROCESS | 1652 | RSS: Main: 283951104 12:06:07 INFO - PROCESS | 1652 | 12:06:09 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:06:10 INFO - PROCESS | 1652 | RSS: Main: 285065216 12:06:10 INFO - PROCESS | 1652 | 12:06:13 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 12:06:13 INFO - PROCESS | 1652 | RSS: Main: 327745536 12:06:13 INFO - PROCESS | 1652 | 12:06:16 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 12:06:17 INFO - PROCESS | 1652 | RSS: Main: 327417856 12:06:17 INFO - PROCESS | 1652 | 12:06:20 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 12:06:20 INFO - PROCESS | 1652 | RSS: Main: 322605056 12:06:20 INFO - PROCESS | 1652 | 12:06:22 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:06:22 INFO - PROCESS | 1652 | RSS: Main: 298008576 12:06:22 INFO - PROCESS | 1652 | 12:06:24 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:06:25 INFO - PROCESS | 1652 | RSS: Main: 300720128 12:06:25 INFO - PROCESS | 1652 | 12:06:27 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:06:28 INFO - PROCESS | 1652 | RSS: Main: 303239168 12:06:28 INFO - PROCESS | 1652 | 12:06:31 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:06:31 INFO - PROCESS | 1652 | RSS: Main: 322961408 12:06:31 INFO - PROCESS | 1652 | 12:06:34 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:06:35 INFO - PROCESS | 1652 | RSS: Main: 316694528 12:06:35 INFO - PROCESS | 1652 | 12:06:37 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 12:06:37 INFO - PROCESS | 1652 | RSS: Main: 361046016 12:06:37 INFO - PROCESS | 1652 | 12:06:37 INFO - PROCESS | 1652 | __start_tp_report 12:06:37 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 12:06:37 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 12:06:37 INFO - PROCESS | 1652 | |i|pagename|runs| 12:06:37 INFO - PROCESS | 1652 | |0;ai-astar;105;115;115;114;114;115;115;115;115;115 12:06:37 INFO - PROCESS | 1652 | |1;audio-beat-detection;145;112;119;195;112;110;110;106;141;112 12:06:37 INFO - PROCESS | 1652 | |2;audio-dft;187;183;184;183;183;185;181;183;184;184 12:06:37 INFO - PROCESS | 1652 | |3;audio-fft;82;59;58;89;60;59;61;59;82;58 12:06:37 INFO - PROCESS | 1652 | |4;audio-oscillator;89;67;94;69;67;71;107;55;68;112 12:06:37 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;88;86;89;125;89;85;89;85;88;84 12:06:37 INFO - PROCESS | 1652 | |6;imaging-darkroom;133;147;93;93;93;93;93;93;98;94 12:06:37 INFO - PROCESS | 1652 | |7;imaging-desaturate;83;82;79;80;84;83;86;84;81;83 12:06:37 INFO - PROCESS | 1652 | |8;json-parse-financial;58;56;56;56;58;59;59;58;58;58 12:06:37 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;64;75;80;73;72;69;70;71;70;71 12:06:37 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;84;85;84;84;81;85;84;81;85;84 12:06:37 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;143;141;142;139;141;145;159;147;197;143 12:06:37 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;168;166;164;215;218;168;167;167;169;166 12:06:37 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;67;68;70;68;68;69;68;68;67;67 12:06:37 INFO - PROCESS | 1652 | __end_tp_report 12:06:37 INFO - PROCESS | 1652 | __start_cc_report 12:06:37 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1935 12:06:37 INFO - PROCESS | 1652 | __end_cc_report 12:06:37 INFO - PROCESS | 1652 | __startTimestamp1484942797414__endTimestamp 12:06:37 INFO - PROCESS | 1652 | 12:06:37 INFO - PROCESS | 1652 | ------- Summary: start ------- 12:06:37 INFO - PROCESS | 1652 | Number of tests: 14 12:06:37 INFO - PROCESS | 1652 | 12:06:37 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:113.80 Median:115.00 stddev:3.12 (2.7%) stddev-sans-first:0.44 12:06:37 INFO - PROCESS | 1652 | Values: 105.0 115.0 115.0 114.0 114.0 115.0 115.0 115.0 115.0 115.0 12:06:37 INFO - PROCESS | 1652 | 12:06:37 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:126.20 Median:115.50 stddev:27.68 (24.0%) stddev-sans-first:28.51 12:06:37 INFO - PROCESS | 1652 | Values: 145.0 112.0 119.0 195.0 112.0 110.0 110.0 106.0 141.0 112.0 12:06:37 INFO - PROCESS | 1652 | 12:06:37 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:183.70 Median:184.00 stddev:1.57 (0.9%) stddev-sans-first:1.12 12:06:37 INFO - PROCESS | 1652 | Values: 187.0 183.0 184.0 183.0 183.0 185.0 181.0 183.0 184.0 184.0 12:06:37 INFO - PROCESS | 1652 | 12:06:37 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:66.70 Median:60.50 stddev:12.35 (20.4%) stddev-sans-first:11.79 12:06:37 INFO - PROCESS | 1652 | Values: 82.0 59.0 58.0 89.0 60.0 59.0 61.0 59.0 82.0 58.0 12:06:37 INFO - PROCESS | 1652 | 12:06:37 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:79.90 Median:68.50 stddev:19.26 (28.1%) stddev-sans-first:20.15 12:06:37 INFO - PROCESS | 1652 | Values: 89.0 67.0 94.0 69.0 67.0 71.0 107.0 55.0 68.0 112.0 12:06:37 INFO - PROCESS | 1652 | 12:06:37 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:90.80 Median:88.00 stddev:12.16 (13.8%) stddev-sans-first:12.86 12:06:37 INFO - PROCESS | 1652 | Values: 88.0 86.0 89.0 125.0 89.0 85.0 89.0 85.0 88.0 84.0 12:06:37 INFO - PROCESS | 1652 | 12:06:37 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:103.00 Median:93.00 stddev:19.84 (21.3%) stddev-sans-first:17.83 12:06:37 INFO - PROCESS | 1652 | Values: 133.0 147.0 93.0 93.0 93.0 93.0 93.0 93.0 98.0 94.0 12:06:37 INFO - PROCESS | 1652 | 12:06:37 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:82.50 Median:83.00 stddev:2.07 (2.5%) stddev-sans-first:2.19 12:06:37 INFO - PROCESS | 1652 | Values: 83.0 82.0 79.0 80.0 84.0 83.0 86.0 84.0 81.0 83.0 12:06:37 INFO - PROCESS | 1652 | 12:06:37 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:57.60 Median:58.00 stddev:1.17 (2.0%) stddev-sans-first:1.24 12:06:37 INFO - PROCESS | 1652 | Values: 58.0 56.0 56.0 56.0 58.0 59.0 59.0 58.0 58.0 58.0 12:06:37 INFO - PROCESS | 1652 | 12:06:37 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:71.50 stddev:4.14 (5.8%) stddev-sans-first:3.39 12:06:37 INFO - PROCESS | 1652 | Values: 64.0 75.0 80.0 73.0 72.0 69.0 70.0 71.0 70.0 71.0 12:06:37 INFO - PROCESS | 1652 | 12:06:37 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:84.00 stddev:1.49 (1.8%) stddev-sans-first:1.58 12:06:37 INFO - PROCESS | 1652 | Values: 84.0 85.0 84.0 84.0 81.0 85.0 84.0 81.0 85.0 84.0 12:06:37 INFO - PROCESS | 1652 | 12:06:37 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:149.70 Median:144.00 stddev:17.54 (12.2%) stddev-sans-first:18.43 12:06:37 INFO - PROCESS | 1652 | Values: 143.0 141.0 142.0 139.0 141.0 145.0 159.0 147.0 197.0 143.0 12:06:37 INFO - PROCESS | 1652 | 12:06:37 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.80 Median:168.00 stddev:20.98 (12.5%) stddev-sans-first:22.01 12:06:37 INFO - PROCESS | 1652 | Values: 168.0 166.0 164.0 215.0 218.0 168.0 167.0 167.0 169.0 166.0 12:06:37 INFO - PROCESS | 1652 | 12:06:37 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.00 stddev:0.94 (1.4%) stddev-sans-first:0.93 12:06:37 INFO - PROCESS | 1652 | Values: 67.0 68.0 70.0 68.0 68.0 69.0 68.0 68.0 67.0 67.0 12:06:37 INFO - PROCESS | 1652 | -------- Summary: end -------- 12:06:37 INFO - PROCESS | 1652 | 12:06:38 INFO - TEST-INFO | 1652: exit 0 12:06:38 INFO - TEST-OK | kraken | took 59843ms 12:06:38 INFO - SUITE-END | took 1178s 12:06:38 INFO - Completed test suite (00:19:38) 12:06:38 INFO - javascript benchmark 12:06:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2664.0, 3329.8335083245834, 3343.3146205027965, 3346.3809195298854, 3383.390989621869, 5639.971800140998, 5663.065594176966, 5678.176664383662, 5687.886242275157, 5688.459596338346, 5630.831075067745, 5695.857603559903, 5710.343310519292, 5722.456366645172, 5729.885402291951, 5156.484351564851, 5179.197224430201, 5180.274761533388, 5228.111221092405, 5252.579793616521, 4683.882902927437, 4684.812607495696, 5144.742762861861, 5152.201408781645, 5155.407128180251, 5217.765200565984, 5276.973615131919, 5285.075111855429, 5292.179712144623, 5306.93861227754, 5254.290670759437, 5268.81559145428, 5269.104252277084, 5301.5228629423345, 5305.840824775263, 5027.773750181251, 5143.794248230066, 5197.974010129925, 5210.0, 5230.97384513075, 5582.91625625616, 5592.496675299242, 5620.522255608278, 5640.351359593658, 5675.659460432387, 6096.69516524172, 6123.112148738408, 6142.385761423867, 6189.071639254103, 6190.504759619264, 5464.207689884985, 6155.953487907022, 6274.686265686697, 6275.466585340251, 6277.8744425111245, 5480.835574932718, 5617.185508101303, 5620.859478512988, 5655.971720141455, 5757.884842303172, 6107.378548143415, 6186.938130618726, 6228.252609686867, 6228.4705800007505, 6253.468455181361, 6112.419320164578, 6112.633242005494, 6121.387861213843, 6122.510199184055, 6131.96934015327, 5363.249145119687, 6110.953680641517, 6146.231721034871, 6242.406971337716, 6261.186045814106, 6047.6069055512135, 6106.72519736613, 6107.4197951194565, 6108.908366374508, 6111.755529778848, 5157.974210129, 6087.4521293083835, 6116.235470566179, 6135.9079613805825, 6143.201383820075, 4899.0, 4932.482089380635, 4954.430240522314, 4958.925616115761, 4967.602591792648, 4356.0, 4360.887973566783, 4371.082072764755, 4382.583654552876, 4426.712263702849, 5508.944910550923, 5553.888922221614, 5696.401877802854, 5699.74351154199, 5731.455511726379, 6139.662318572432, 6171.105189747551, 6267.341929097471, 6271.6550589718, 6285.8742825143245, 5707.229524014288, 5740.0, 5744.454276843693, 5758.2514273144225, 5764.048931926185, 5371.892562148735, 5382.946170538323, 5385.461453854661, 5391.595630327741, 5394.136938089887, 6134.130457564626, 6142.713648198372, 6197.318294987457, 6203.44169024799, 6223.097650840693, 6134.392883024786, 6136.263648362046, 6143.96928015357, 6154.046122850965, 6154.907676384947, 5181.248718935809, 6178.721957511832, 6234.1583886174785, 6238.033104868752, 6245.781397651061, 5986.132010858489, 5998.310194327708, 6053.273607166992, 6058.545609079425, 6064.120702498201, 6138.541671868701, 6249.312575616587, 6251.156093927443, 6271.498280137487, 6273.686315684289, 6082.90875636857, 6083.6045657032155, 6109.877802444015, 6112.847178820565, 6135.969320153371, 5915.112733090071, 5964.612300200473, 6067.906572232573, 6097.237845269341, 6114.816555503342, 5864.179014937827, 5864.501517371071, 5885.117232415001, 5953.315368732651, 5996.310424301087, 6058.363871793398, 6061.878762424815, 6074.483668888194, 6089.847753806191, 6099.939000610115, 5688.829335119953, 5893.941060589339, 5906.645601263938, 5915.852103697442, 5929.822105336847], "value": 5632.036932491156, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [432.2241576370415, 464.85357112509575, 466.9416322959629, 482.2789929056062, 536.7826030457666, 30001.39997200055, 31634.52548211776, 38165.42751858725, 39047.0, 40008.0, 41400.79299603505, 41702.79148604253, 41769.7911510442, 41799.58200417995, 41837.0, 9342.439453632804, 9530.332876698634, 9533.0, 9535.094166054214, 9543.904560954372, 8484.490930544187, 8715.477071375706, 9332.766680832965, 11154.996050355467, 11453.713657158596, 11359.204855660108, 11423.828642570368, 11449.942750286278, 11450.370229637367, 11452.599159029436, 11364.545418183263, 11443.370614616193, 11449.484773185226, 11452.198346115816, 11477.885221147764, 8472.491650500928, 8751.693690720858, 11005.889941100566, 11296.887031129665, 11339.659810205707, 39009.41485877714, 39446.40830387546, 39453.40819887704, 39537.011574710574, 39581.80209098965, 3347.598288205431, 3580.15866271426, 3682.581390025196, 3780.0310769353396, 3952.5256969163884, 28121.0, 28226.29434264139, 29007.564886526718, 29499.410011799642, 30580.235494112825, 3175.841207939594, 3189.059227527885, 3194.872205111793, 3243.6918492743152, 3269.54226408303, 500868.99999999633, 501324.0, 504605.0, 505118.0, 507249.0, 33361.83319083389, 34207.0, 34350.48474272888, 58242.0, 58686.4131358681, 34868.82565587156, 34975.30049398998, 34983.475247871305, 35036.299274014375, 35066.64933350685, 14189.78715319271, 14313.928430357782, 14332.0, 14346.713065738626, 14379.424823007172, 11093.445327733582, 12102.515899363927, 12188.085893558018, 12207.267563946192, 12238.89849913512, 7311.086114235721, 7433.85132297351, 7435.628218589049, 7438.851222975511, 7446.851062978819, 2936.7797415193945, 3189.3621275744945, 3221.7905836120576, 3259.4784834426378, 3261.2662151015834, 944.17856464876, 944.5560586524323, 945.7588314979773, 974.288769198489, 992.831218692824, 28224.85887570549, 28955.276118097216, 29217.94481664585, 29314.68083936228, 29573.260668483028, 1004.8040632076821, 1045.231754660324, 1087.4019289390803, 1094.8795632480421, 1131.0725205331714, 14721.631959201106, 15112.622184445258, 15193.696126077417, 15464.38142474311, 15529.301181446635, 678446.0, 681269.0, 682019.0, 682051.0, 683804.0, 48175.759121204166, 49190.75404622954, 54404.0, 55480.72259638757, 55971.72014139903, 1341.4433010300615, 1358.843732970721, 1387.3687472199922, 1388.6806034612096, 1397.4340392141198, 29147.0, 29586.852065739535, 29808.85095574508, 30402.239944001576, 30465.23836904095, 48593.7851553714, 53933.730331348095, 55203.44796551983, 55253.17120243119, 58005.70997144987, 629407.8529607323, 664240.0, 690934.5453272702, 872548.0, 872622.0, 39352.40971385374, 39593.0, 40007.0, 40285.992850177805, 40818.79590602028, 44204.56874871913, 44211.77894110509, 44329.77835110804, 44698.3295250578, 44987.0, 35952.82023589865, 36082.8195859019, 36882.44676329804, 38129.0, 38180.427293590066, 36776.44835327419, 36853.85233461311, 36893.0, 37251.0, 37475.875723728335, 36196.0, 36822.8158859204, 36991.445128323634, 37014.814925925195, 37032.07419814526, 30804.075877723702, 32895.50656740103, 33099.0, 33217.335653287286, 33277.0, 24521.632175517738, 24674.50650987006, 24874.626880597167, 24912.62631060572, 25265.747342527076], "value": 18797.79125299454, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.95235143255655, 370.3666729891885, 371.9293334266487, 382.8813067948936, 400.2854903996367, 14651.926740366296, 14987.400503979841, 15779.368825247006, 20031.098600562975, 20157.59684806303, 19071.618567628673, 19405.902970485164, 19468.0, 20106.798932010675, 20299.898500507476, 5759.712014399273, 5997.85005374867, 6007.429294217042, 6009.1587177795145, 6013.819585412422, 4837.4195096588455, 6505.837354066162, 6609.537332386735, 6627.801165965029, 6641.070250164981, 5904.970475147618, 6481.027845823117, 6487.675616219194, 6508.349165083478, 6580.835479113037, 6529.5429319947625, 6564.901526477082, 6572.408483236484, 6574.638394888279, 6577.539572229945, 5430.83707488776, 5575.832725018256, 5634.802781902655, 5634.943650563483, 5635.239242702265, 24274.757252427426, 24845.254642360756, 25091.49817003668, 25270.873645631655, 25597.104101356363, 2070.9171633134656, 2200.702905107806, 2327.813774898004, 2452.865092419916, 3005.1735772662473, 14967.850321496864, 14986.850131498764, 15057.849421505754, 16026.198690065567, 16043.35826566936, 1855.174447370907, 1888.3296429767508, 1903.5021854472595, 1918.7409699690502, 1927.4025052233712, 113335.4333228345, 117937.0, 119669.0, 120150.39924800408, 120325.0, 9830.852537211947, 9882.802343953224, 11053.839346868624, 11525.942370288263, 11571.132165087653, 14662.120272783453, 14696.338664760111, 14699.191544464944, 14704.852951470562, 14709.264536773118, 8187.4678145920325, 8472.110428405053, 8512.40413171072, 8599.226069653761, 8618.870716939251, 8369.49783013009, 8378.371622128365, 8415.873761893576, 8518.48889066658, 8538.0, 3821.8662346817728, 4459.509453960061, 4574.428196475441, 4609.492955774862, 4678.730815554453, 1883.5667796406904, 1922.5482011727233, 1924.3457224543722, 1925.9325923592887, 1932.1305412564402, 855.3157474020883, 882.8101958079044, 937.0114529171789, 946.4841661294611, 952.3000594562991, 20306.79693203049, 20790.896045519676, 21159.68260476094, 21173.470663233544, 21183.8940805295, 911.977200569991, 948.2745699539857, 963.6868017894212, 963.802420503795, 1000.8090372456899, 10292.794144117226, 11068.612598559012, 11071.280366776135, 11122.443877806078, 11137.331760094421, 577133.0, 592942.0, 601463.0, 606497.0, 607147.0, 128708.0, 129488.0, 129497.0, 131041.0, 136359.31820340836, 944.7874228298577, 952.8999455057119, 980.5048450532436, 982.1602529837029, 984.675057231112, 18121.90939045296, 18310.725339120192, 18484.63030739351, 18634.813651863307, 18660.720089198945, 53300.73349633382, 53573.0, 53882.73058634682, 54582.72708636431, 55312.72343638256, 77105.84341234998, 77881.22118778739, 78467.60766196133, 79102.6044869795, 79676.60161699154, 7982.880256796268, 8036.718714844952, 8124.796880078045, 8202.38482113832, 8273.958630206811, 11909.344986025852, 12079.879201208227, 12522.311272880079, 12988.350582471028, 13230.007749419025, 11168.832467512831, 11196.0, 11212.0, 11533.250338727956, 11567.479463424335, 10795.784084318428, 10826.079783218514, 11195.832062519232, 11385.373804440835, 11459.025982791556, 11319.377434241196, 11443.313401195956, 11464.598739044093, 11703.180777345504, 11772.70568235801, 8000.199980001953, 8130.105688374156, 8132.9186708132165, 8407.495550267004, 8560.272376848037, 8379.162083791573, 10979.166250524557, 11177.0, 11594.594189203337, 11608.825867611824], "value": 9711.959662541487, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1277.9872201277985, 1461.2985766831923, 1499.490173341064, 1650.9091999940006, 1842.797292297847, 7318.34134927857, 7692.423068269881, 7874.133845277015, 8261.793455163624, 8293.668253269861, 8096.67613295469, 8194.30348420385, 8197.590120493982, 8201.179882011797, 8206.933098697162, 2296.219285442949, 2334.1947028275304, 2334.9533009339802, 2336.532693461306, 2339.2397470821966, 2298.5058212484314, 2350.083467447697, 2353.388119089032, 2357.6581395697613, 2358.8230882683783, 2170.4899348653053, 2181.0, 2203.1187524989973, 2203.4050806282294, 2208.0726095040122, 2284.942876428086, 2290.1183044527893, 2312.9306120816404, 2315.4327189838596, 2321.6401457774073, 1977.7033444983224, 2316.4440534271653, 2339.695839540866, 2341.94145146371, 2342.8008619267384, 8146.714864979697, 8211.01467823859, 8221.013478382632, 8223.506589604583, 8227.42408031438, 4955.281484184773, 5151.407588127338, 5164.096283150471, 5170.0, 5184.974075129638, 3281.753868459875, 3446.207372304359, 3452.7583069185166, 3524.594671612746, 3528.770629909048, 973.6008236623021, 1035.6646504013574, 1039.272509243525, 1066.2269854355607, 1075.887031861659, 10522.894771052344, 10610.257281990263, 10644.0, 10652.254342196049, 10704.518296676668, 4243.639290660267, 4389.626881715026, 4461.464624245046, 4466.821327146943, 4484.0, 8399.538025408661, 8500.957495212484, 8561.44350617208, 8566.18621230982, 8580.184882436159, 5057.418396884332, 5059.645824792303, 5065.11360511909, 5075.0357432087785, 5082.796688132434, 4041.8585349512628, 4230.344296634026, 4386.254336762758, 4403.4495688039, 4669.533046695371, 3635.7273204509765, 3660.066682995819, 3725.590185079639, 3727.8881633551036, 3741.064733816546, 997.5909876950416, 1130.8190689489643, 1131.4003578103618, 1131.886811318878, 1139.2993309115, 743.9702411903571, 833.3832963607008, 860.4579115157411, 866.3848667446058, 900.6847603338779, 5388.137897936312, 5470.425605311465, 5475.425080366584, 5577.609567330248, 5601.355844077902, 436.2670713201788, 451.11806418451846, 461.45087346058085, 940.4122423485321, 962.3263715399248, 3118.251619611277, 3647.9817600912356, 3656.891642104243, 3759.9248015039548, 3763.284975854579, 9630.566624501775, 9901.356411833207, 9983.650572230083, 10002.84967228778, 10010.399376037316, 9959.452230127268, 9965.750856228507, 9976.750581235527, 9985.900140998643, 9987.450690211963, 7569.621518924032, 7646.464747467624, 7657.425693073042, 7851.0, 7859.410544209093, 5381.596380271417, 5389.649672771257, 5394.838154855361, 5418.187271909245, 5497.8075767347955, 8980.685676001309, 9274.350795444254, 9276.350655454053, 9285.860712089188, 9303.255739540957, 8096.271335579826, 8419.200175983271, 8747.037825839025, 9323.347365684336, 9391.906080939376, 6647.268800432045, 6942.791716248521, 7070.858582828418, 7157.0, 7208.927910720826, 7749.263819937322, 7848.64681089341, 7910.129885712452, 7922.683092676229, 7923.20767923203, 6802.319768023356, 6916.930830691628, 6960.303969603202, 6979.127609048869, 7113.146422429135, 6857.451403887777, 6901.723931042702, 6925.722971081101, 6989.7553585624255, 7030.437564994891, 6593.703283352356, 6633.270340262471, 6647.60114393138, 6690.732370705117, 6695.698693558704, 4613.723176609415, 4691.812327506861, 4797.520247975174, 4822.855314340575, 4923.310736496965, 4659.953400465952, 4779.545943135397, 4791.976040119777, 4854.126257273725, 4933.9013219736125], "value": 4270.255111121649, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.226103262035, 301.7751774927679, 307.2840282142608, 312.7060563070713, 313.39045556392824, 4793.873439741658, 4994.875128121799, 5173.637845350827, 5227.895442091156, 5296.496832800878, 4888.826681596418, 5050.7222102784335, 5050.949490505094, 5092.479222784717, 5246.081935661263, 3774.1508160663907, 3854.0, 3870.9225815483674, 3874.1984976985696, 3954.6638535724496, 3342.0115031556184, 3368.0, 3956.287868183726, 3975.3241948868617, 3982.0, 3157.7159753856076, 3306.9173270668184, 3385.0, 3536.964630353702, 3904.2581909437117, 3274.5415641810164, 3301.669833016679, 3918.45141680165, 3945.4870866787132, 3965.2466031453932, 3391.0, 3410.931781364359, 3412.3791199180437, 3416.8462419191196, 3429.53701250333, 5024.748762561857, 5033.924491132636, 5051.494850514956, 5053.393592768891, 5096.133657278235, 3415.334009868077, 3684.4841722158653, 4035.9910022494378, 4263.59495847894, 4415.4259946206785, 4689.929651055237, 5306.787728490855, 5336.226247194135, 5499.862503437406, 5510.311211098613, 3270.4767237241927, 3760.3795373763296, 3760.623937606245, 3777.3578491656485, 3799.6200379961783, 4580.404547408849, 4839.41926968766, 5557.472040156179, 5716.828495145152, 5762.740676669518, 3784.3188226119564, 3812.341631390354, 3813.3326667833016, 3813.3517302058162, 3835.7890316032326, 3728.12389088564, 3861.691064714816, 3879.4568760373572, 3895.824687888995, 3902.590228026073, 3824.3116239077235, 4263.658907287409, 4398.758068306274, 4497.460304763384, 4511.526289739596, 4732.0960207828675, 4900.803967841246, 5086.338775959101, 5490.835274941758, 5494.955958367897, 1136.9772604547863, 1233.747081848219, 1241.4537603454633, 1245.9626211213679, 1262.9621111366673, 794.2653045932605, 813.5037627047496, 826.3884725303352, 831.6257684042084, 862.3403096631082, 4539.051025607677, 4552.886177845514, 4601.654875884322, 4866.345442549479, 4878.82924097662, 4535.523770004168, 5254.500822421864, 5299.311089558333, 5313.468653134656, 5355.678659280456, 4464.267876051396, 4617.183518053985, 4752.120857641278, 4784.818095966143, 4824.903501929942, 2311.768823117675, 2574.0990653271206, 2670.813043086965, 2802.1418153483332, 2803.1730639461407, 4633.289525627546, 4782.426108866958, 4807.807687692453, 5327.840164795062, 5567.0, 4904.814446835332, 5320.866978325573, 5353.0, 5471.316085489314, 5858.606045465901, 5921.526277897758, 5923.7926672566255, 5929.762809487658, 5953.583249172516, 5993.0, 5498.972505137449, 5691.573132015198, 5711.314642242791, 5717.942820571908, 5728.312602487728, 4816.494268101799, 5138.073222541547, 5353.411124776194, 5658.6233781810915, 5691.544676425793, 5396.028714831211, 5909.763609455574, 5919.099641175707, 5927.940720592739, 5955.344912059583, 3313.50297455374, 3533.2756784859557, 3536.876209332661, 3806.904827379227, 3814.847406103836, 4686.484486706528, 4828.855134345975, 5362.954223926297, 5645.604807663424, 5652.039153343859, 4441.844535441244, 5437.525925703716, 5492.890142197054, 5495.395506494362, 5599.796043850592, 5140.121531942124, 5430.266913966564, 5485.9725701371235, 5500.899820035929, 5520.0, 5159.922601160911, 5353.0, 5452.973599652231, 5467.58993075513, 5470.398256191896, 4971.776270067783, 5172.904973485744, 5426.593005524681, 5443.727813609383, 5570.2201691762375, 4593.402857628487, 4658.883527911694, 5161.251618515219, 5209.739513024258, 5439.537639300704], "value": 3915.2559879632827, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [687.3264201082935, 693.9618320992345, 721.2679130682357, 768.2509553185641, 772.551919886466, 14203.147811131326, 14767.852321476768, 14768.704625907476, 14788.260586970662, 14796.926015369922, 11640.243384180023, 13251.403686834092, 13259.138156019852, 13337.666558336046, 13379.197248165117, 659.8482349059719, 679.6839469646613, 815.4291995603072, 839.3033781960988, 845.6194712379454, 845.8688903220011, 849.235687880909, 851.4891065360796, 851.595492141231, 852.0074113657581, 900.8873890763655, 902.1159263921361, 902.251131560803, 902.3052249767675, 903.7605034665819, 690.8583740333245, 715.7995761186902, 716.9533980291317, 716.9677364518609, 719.0580339754938, 644.0950464597248, 663.2869665109978, 666.0575285970347, 667.0527850452321, 669.6517810738437, 14010.879129669602, 14149.646258843508, 14153.646158846008, 14169.433222671081, 14197.929010354883, 13093.738125237538, 13133.212007279593, 13155.0, 13307.733845323137, 13363.0, 11667.708307292301, 11912.940435297767, 12018.819717704242, 12027.819582706266, 12290.324032178227, 4219.050713589449, 4280.507741609693, 4319.568043195655, 4330.588594083557, 4438.778061096932, 87478.12521874828, 88868.11131888663, 89875.65186522207, 90283.86017995053, 91800.54099729459, 10482.737931551772, 10532.894671053346, 10536.947315263527, 10539.26225164231, 10623.256372054037, 20269.49326266855, 20492.897535512227, 20522.38432847017, 20542.38372848817, 20542.794572054387, 7284.526505777108, 7568.091828980558, 7632.236776322435, 7756.379489640815, 7780.260875216845, 4097.160082183145, 4148.149629325981, 4193.874183774492, 4346.739195648272, 4370.038591509863, 3176.4758814795764, 3278.655741147193, 3281.032095531824, 3287.474004159323, 3291.588551431071, 610.9816705498843, 620.7920346683911, 626.6835248199721, 638.8530637953297, 639.7281155508983, 515.9045576568346, 522.5323335614604, 595.2499850188793, 602.135934933372, 606.6663335165641, 8447.0, 8635.050144484101, 8700.695475658322, 8705.651773928972, 8828.117188281121, 525.1597444089426, 552.2847911954029, 619.755196697302, 624.2602516018442, 627.3632263252802, 4015.297322968469, 4266.935995960063, 4320.330348795942, 4329.869239141764, 4333.544977777288, 81375.18624813795, 91599.0, 122339.0, 124233.0, 124647.0, 106439.4678026605, 109248.4537577307, 110021.0, 110253.0, 111779.0, 64562.031569526494, 82098.17901821024, 82139.0, 84537.57731211305, 86852.0, 8140.59297035134, 8155.714549990722, 8209.835803283782, 8341.374396920377, 8523.872141917753, 49636.25545616891, 55695.44304556902, 56358.0, 56434.58913527195, 57726.13410798757, 50474.74762626163, 56825.715871420376, 57008.714956426615, 71247.28752712406, 79560.60219699096, 12194.87805121973, 12391.566295179626, 12456.937715311366, 12475.875241247471, 12533.498660053496, 12774.36128193568, 13092.279924604238, 13373.197608143542, 13540.796888046883, 13630.182189068688, 11701.414929253333, 12648.6837829055, 13058.738825223632, 13519.459221631418, 13901.721965560835, 12059.57791477294, 13306.207730796676, 13457.394417251051, 13475.19148851072, 13576.117552359065, 12218.816717749063, 13265.403056862271, 13585.388657510239, 13590.184588924696, 13634.93182534081, 10230.0, 10488.370697758159, 11415.429228538374, 11497.655070347902, 11721.824172637247, 11058.447077646246, 11312.60405885823, 11355.146721000367, 11386.487608057492, 11534.0], "value": 7207.9995184217905, "unit": "score"}], "name": "dromaeo_css", "value": 7060.889995476778, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 115.0, 115.0, 114.0, 114.0, 115.0, 115.0, 115.0, 115.0, 115.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 112.0, 119.0, 195.0, 112.0, 110.0, 110.0, 106.0, 141.0, 112.0], "value": 126.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 183.0, 184.0, 183.0, 183.0, 185.0, 181.0, 183.0, 184.0, 184.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 58.0, 89.0, 60.0, 59.0, 61.0, 59.0, 82.0, 58.0], "value": 66.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 67.0, 94.0, 69.0, 67.0, 71.0, 107.0, 55.0, 68.0, 112.0], "value": 79.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 125.0, 89.0, 85.0, 89.0, 85.0, 88.0, 84.0], "value": 90.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 147.0, 93.0, 93.0, 93.0, 93.0, 93.0, 93.0, 98.0, 94.0], "value": 103.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 79.0, 80.0, 84.0, 83.0, 86.0, 84.0, 81.0, 83.0], "value": 82.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 56.0, 56.0, 58.0, 59.0, 59.0, 58.0, 58.0, 58.0], "value": 57.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 75.0, 80.0, 73.0, 72.0, 69.0, 70.0, 71.0, 70.0, 71.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 84.0, 84.0, 81.0, 85.0, 84.0, 81.0, 85.0, 84.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 141.0, 142.0, 139.0, 141.0, 145.0, 159.0, 147.0, 197.0, 143.0], "value": 149.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 166.0, 164.0, 215.0, 218.0, 168.0, 167.0, 167.0, 169.0, 166.0], "value": 176.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 70.0, 68.0, 68.0, 69.0, 68.0, 68.0, 67.0, 67.0], "value": 68.0, "unit": "score"}], "name": "kraken", "value": 1453.8999999999999, "alertThreshold": 2.0}]} 12:06:38 INFO - Return code: 0 12:06:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:06:38 INFO - # TBPL SUCCESS # 12:06:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:06:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:06:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:06:38 INFO - 2017-01-20 12:06:38.729 screenresolution[1656:10111] starting screenresolution argv=screenresolution get 12:06:38 INFO - 2017-01-20 12:06:38.732 screenresolution[1656:10111] Display 0: 1600x1200x32@60 12:06:38 INFO - 2017-01-20 12:06:38.738 screenresolution[1657:10114] starting screenresolution argv=screenresolution list 12:06:38 INFO - Available Modes on Display 0 12:06:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:06:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:06:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:06:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:06:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:06:38 INFO - Intel Iris: 12:06:38 INFO - Chipset Model: Intel Iris 12:06:38 INFO - Type: GPU 12:06:38 INFO - Bus: Built-In 12:06:38 INFO - VRAM (Dynamic, Max): 1536 MB 12:06:38 INFO - Vendor: Intel (0x8086) 12:06:38 INFO - Device ID: 0x0a2e 12:06:38 INFO - Revision ID: 0x0009 12:06:38 INFO - Displays: 12:06:38 INFO - Display: 12:06:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:06:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:06:38 INFO - Display Serial Number: 1600x1200 60 12:06:38 INFO - Main Display: Yes 12:06:38 INFO - Mirror: Off 12:06:38 INFO - Online: Yes 12:06:38 INFO - Rotation: Supported 12:06:38 INFO - Return code: 0 12:06:38 INFO - Running post-action listener: _resource_record_post_action 12:06:38 INFO - [mozharness: 2017-01-20 20:06:38.994317Z] Finished run-tests step (success) 12:06:38 INFO - Running post-run listener: _resource_record_post_run 12:06:39 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 37835776; Write bytes: 653629952; Read time: 234; Write time: 1919 12:06:39 INFO - TinderboxPrint: CPU usage
53.7% 12:06:39 INFO - TinderboxPrint: I/O read bytes / time
37,835,776 / 234 12:06:39 INFO - TinderboxPrint: I/O write bytes / time
653,629,952 / 1,919 12:06:39 INFO - TinderboxPrint: CPU idle
2,241.5 (46.3%) 12:06:39 INFO - TinderboxPrint: CPU system
969.7 (20.0%) 12:06:39 INFO - TinderboxPrint: CPU user
1,630.5 (33.7%) 12:06:39 INFO - TinderboxPrint: Swap in / out
386,871,296 / 0 12:06:39 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 336962048; Write bytes: 333411840; Read time: 26787; Write time: 706 12:06:39 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 36910080; Write bytes: 302494720; Read time: 223; Write time: 1159 12:06:39 INFO - Running post-run listener: _upload_blobber_files 12:06:39 INFO - Blob upload gear active. 12:06:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:06:39 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 12:06:39 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'] 12:06:39 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 12:06:39 INFO - (blobuploader) - INFO - Open directory for files ... 12:06:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:06:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:06:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:06:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:06:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:06:40 INFO - (blobuploader) - INFO - Done attempting. 12:06:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:06:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:06:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:06:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:06:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:06:41 INFO - (blobuploader) - INFO - Done attempting. 12:06:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:06:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:06:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:06:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:06:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:06:43 INFO - (blobuploader) - INFO - Done attempting. 12:06:43 INFO - (blobuploader) - INFO - Iteration through files over. 12:06:43 INFO - Return code: 0 12:06:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:06:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:06:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b24c9ca35ee354abbf35c5d7227ec76dd60370c225c9d3122d2387ed4baf6c6e44c90bf06b4ed049cb47a2f2f375af431fa7debb21ec9595302d92f465a531bb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a4929366f99ed3451eed6eda924fa5ccfc5d8893c7502f62bd2709ed9ca35ad738261ad90bb528accc45fa72fef3c4d44dade3ced5b586a05c1182b73dcf773", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/01019a75ce8f35f2b8ffbc03c715b518715bf3d5cb7bb32c64ae47ff1ff23cc6a531848b8f518f2ccfd0c14d71fcd864be56ea188f6a14f83903ede49bde42b7"} 12:06:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:06:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:06:43 INFO - Contents: 12:06:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b24c9ca35ee354abbf35c5d7227ec76dd60370c225c9d3122d2387ed4baf6c6e44c90bf06b4ed049cb47a2f2f375af431fa7debb21ec9595302d92f465a531bb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a4929366f99ed3451eed6eda924fa5ccfc5d8893c7502f62bd2709ed9ca35ad738261ad90bb528accc45fa72fef3c4d44dade3ced5b586a05c1182b73dcf773", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/01019a75ce8f35f2b8ffbc03c715b518715bf3d5cb7bb32c64ae47ff1ff23cc6a531848b8f518f2ccfd0c14d71fcd864be56ea188f6a14f83903ede49bde42b7"} 12:06:43 INFO - Running post-run listener: copy_logs_to_upload_dir 12:06:43 INFO - Copying logs to upload dir... 12:06:43 INFO - mkdir: /builds/slave/test/build/upload/logs 12:06:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.569718 ========= master_lag: 3.94 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-01-20 12:06:47.775470) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 4 secs) (at 2017-01-20 12:06:47.778997) ========= 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.EIYh0SyZlN/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.5x0lyg9xIx/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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/b24c9ca35ee354abbf35c5d7227ec76dd60370c225c9d3122d2387ed4baf6c6e44c90bf06b4ed049cb47a2f2f375af431fa7debb21ec9595302d92f465a531bb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a4929366f99ed3451eed6eda924fa5ccfc5d8893c7502f62bd2709ed9ca35ad738261ad90bb528accc45fa72fef3c4d44dade3ced5b586a05c1182b73dcf773", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/01019a75ce8f35f2b8ffbc03c715b518715bf3d5cb7bb32c64ae47ff1ff23cc6a531848b8f518f2ccfd0c14d71fcd864be56ea188f6a14f83903ede49bde42b7"} build_url:https://queue.taskcluster.net/v1/task/NBz51HwdRoqPR2RstrCxpA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011135 build_url: 'https://queue.taskcluster.net/v1/task/NBz51HwdRoqPR2RstrCxpA/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/b24c9ca35ee354abbf35c5d7227ec76dd60370c225c9d3122d2387ed4baf6c6e44c90bf06b4ed049cb47a2f2f375af431fa7debb21ec9595302d92f465a531bb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8a4929366f99ed3451eed6eda924fa5ccfc5d8893c7502f62bd2709ed9ca35ad738261ad90bb528accc45fa72fef3c4d44dade3ced5b586a05c1182b73dcf773", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/01019a75ce8f35f2b8ffbc03c715b518715bf3d5cb7bb32c64ae47ff1ff23cc6a531848b8f518f2ccfd0c14d71fcd864be56ea188f6a14f83903ede49bde42b7"}' ========= master_lag: 4.51 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 4 secs) (at 2017-01-20 12:06:52.299523) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 12:06:52.299874) ========= 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.EIYh0SyZlN/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.5x0lyg9xIx/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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.005048 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 12:06:52.331475) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-20 12:06:52.331899) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-20 12:06:52.332553) ========= ========= Total master_lag: 8.56 =========