builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0177 starttime: 1482284775.56 results: success (0) buildid: 20161220153544 builduid: 81937d11d65f4e5fac6fb79cd2217e4b revision: 81a1f25491a65bdf41584a261a3d44607af9ac95 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-20 17:46:15.556157) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-20 17:46:15.556610) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-20 17:46:15.556887) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-20 17:46:15.583339) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 17:46:15.583746) ========= 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.LKbNiRgKpx/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.yKqm16OKDZ/Listeners TMPDIR=/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-20 17:46:15-- 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% 159M=0s 2016-12-20 17:46:15 (159 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.201705 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 17:46:15.809576) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 17:46:15.810035) ========= 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.LKbNiRgKpx/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.yKqm16OKDZ/Listeners TMPDIR=/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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.026800 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 17:46:15.859170) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-20 17:46:15.859525) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 81a1f25491a65bdf41584a261a3d44607af9ac95 --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 81a1f25491a65bdf41584a261a3d44607af9ac95 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LKbNiRgKpx/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.yKqm16OKDZ/Listeners TMPDIR=/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-20 17:46:15,949 truncating revision to first 12 chars 2016-12-20 17:46:15,950 Setting DEBUG logging. 2016-12-20 17:46:15,950 attempt 1/10 2016-12-20 17:46:15,950 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/81a1f25491a6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-20 17:46:17,740 unpacking tar archive at: mozilla-aurora-81a1f25491a6/testing/mozharness/ program finished with exit code 0 elapsedTime=2.066624 ========= master_lag: 0.62 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-20 17:46:18.544979) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-20 17:46:18.545349) ========= script_repo_revision: 81a1f25491a65bdf41584a261a3d44607af9ac95 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-20 17:46:18.545735) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-20 17:46:18.546018) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-20 17:46:18.567710) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-20 17:46:18.568257) ========= /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.LKbNiRgKpx/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.yKqm16OKDZ/Listeners TMPDIR=/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 17:46:18 INFO - MultiFileLogger online at 20161220 17:46:18 in /builds/slave/test 17:46:18 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 17:46:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:46:18 INFO - {'append_to_log': False, 17:46:18 INFO - 'base_work_dir': '/builds/slave/test', 17:46:18 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 17:46:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:46:18 INFO - 'branch': 'Mozilla-Aurora', 17:46:18 INFO - 'buildbot_json_path': 'buildprops.json', 17:46:18 INFO - 'config_files': ('talos/mac_config.py',), 17:46:18 INFO - 'default_actions': ('clobber', 17:46:18 INFO - 'read-buildbot-config', 17:46:18 INFO - 'download-and-extract', 17:46:18 INFO - 'populate-webroot', 17:46:18 INFO - 'create-virtualenv', 17:46:18 INFO - 'install', 17:46:18 INFO - 'run-tests'), 17:46:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:46:18 INFO - 'download_minidump_stackwalk': True, 17:46:18 INFO - 'download_symbols': 'ondemand', 17:46:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:46:18 INFO - 'tooltool.py': '/tools/tooltool.py', 17:46:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:46:18 INFO - '/tools/misc-python/virtualenv.py')}, 17:46:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:46:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:46:18 INFO - 'installer_path': 'installer.exe', 17:46:18 INFO - 'log_level': 'info', 17:46:18 INFO - 'log_name': 'talos', 17:46:18 INFO - 'log_to_console': True, 17:46:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:46:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:46:18 INFO - 'opt_config_files': (), 17:46:18 INFO - 'pip_index': False, 17:46:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:46:18 INFO - 'cmd': ('bash', 17:46:18 INFO - '-c', 17:46:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:46:18 INFO - 'enabled': True, 17:46:18 INFO - 'halt_on_failure': False, 17:46:18 INFO - 'name': 'check_screen_resolution'},), 17:46:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:46:18 INFO - 'cmd': ('bash', 17:46:18 INFO - '-c', 17:46:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:46:18 INFO - 'enabled': True, 17:46:18 INFO - 'halt_on_failure': False, 17:46:18 INFO - 'name': 'check_screen_resolution'},), 17:46:18 INFO - 'run_cmd_checks_enabled': True, 17:46:18 INFO - 'sps_profile': False, 17:46:18 INFO - 'sps_profile_interval': 0, 17:46:18 INFO - 'suite': 'dromaeojs', 17:46:18 INFO - 'system_bits': '32', 17:46:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:46:18 INFO - 'title': 't-yosemite-r7-0177', 17:46:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:46:18 INFO - 'use_talos_json': True, 17:46:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:46:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:46:18 INFO - 'work_dir': 'build'} 17:46:18 INFO - [mozharness: 2016-12-21 01:46:18.773692Z] Running clobber step. 17:46:18 INFO - Running pre-action listener: _resource_record_pre_action 17:46:18 INFO - Running main action method: clobber 17:46:18 INFO - rmtree: /builds/slave/test/build 17:46:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:46:21 INFO - Running post-action listener: _resource_record_post_action 17:46:21 INFO - [mozharness: 2016-12-21 01:46:21.230646Z] Finished clobber step (success) 17:46:21 INFO - [mozharness: 2016-12-21 01:46:21.230817Z] Running read-buildbot-config step. 17:46:21 INFO - Running pre-action listener: _resource_record_pre_action 17:46:21 INFO - Running main action method: read_buildbot_config 17:46:21 INFO - Using buildbot properties: 17:46:21 INFO - { 17:46:21 INFO - "project": "", 17:46:21 INFO - "product": "firefox", 17:46:21 INFO - "script_repo_revision": "production", 17:46:21 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 17:46:21 INFO - "repository": "", 17:46:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 17:46:21 INFO - "buildid": "20161220153544", 17:46:21 INFO - "pgo_build": "False", 17:46:21 INFO - "basedir": "/builds/slave/test", 17:46:21 INFO - "buildnumber": 27, 17:46:21 INFO - "slavename": "t-yosemite-r7-0177", 17:46:21 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 17:46:21 INFO - "platform": "yosemite_r7", 17:46:21 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 17:46:21 INFO - "branch": "mozilla-aurora", 17:46:21 INFO - "repo_path": "releases/mozilla-aurora", 17:46:21 INFO - "revision": "81a1f25491a65bdf41584a261a3d44607af9ac95", 17:46:21 INFO - "stage_platform": "macosx64", 17:46:21 INFO - "builduid": "81937d11d65f4e5fac6fb79cd2217e4b", 17:46:21 INFO - "slavebuilddir": "test" 17:46:21 INFO - } 17:46:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/KsD4CFTWQ2aX0Fx_m1mQbQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 17:46:21 INFO - Running post-action listener: _resource_record_post_action 17:46:21 INFO - [mozharness: 2016-12-21 01:46:21.234111Z] Finished read-buildbot-config step (success) 17:46:21 INFO - [mozharness: 2016-12-21 01:46:21.234220Z] Running download-and-extract step. 17:46:21 INFO - Running pre-action listener: _resource_record_pre_action 17:46:21 INFO - Running main action method: download_and_extract 17:46:21 INFO - mkdir: /builds/slave/test/build/tests 17:46:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:46:21 INFO - trying https://queue.taskcluster.net/v1/task/KsD4CFTWQ2aX0Fx_m1mQbQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 17:46:21 INFO - Downloading https://queue.taskcluster.net/v1/task/KsD4CFTWQ2aX0Fx_m1mQbQ/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 17:46:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KsD4CFTWQ2aX0Fx_m1mQbQ/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 17:46:22 INFO - Downloaded 1199 bytes. 17:46:22 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 17:46:22 INFO - Using the following test package requirements: 17:46:22 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 17:46:22 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:46:22 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 17:46:22 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:46:22 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 17:46:22 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:46:22 INFO - u'jsshell-mac.zip'], 17:46:22 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:46:22 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 17:46:22 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 17:46:22 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:46:22 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 17:46:22 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:46:22 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 17:46:22 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:46:22 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 17:46:22 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:46:22 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 17:46:22 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 17:46:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KsD4CFTWQ2aX0Fx_m1mQbQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 17:46:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KsD4CFTWQ2aX0Fx_m1mQbQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 17:46:22 INFO - Fetch https://queue.taskcluster.net/v1/task/KsD4CFTWQ2aX0Fx_m1mQbQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 17:46:22 INFO - Http code: 200 17:46:22 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 17:46:22 INFO - x-amz-cf-id: EbgXz8e2MGuO8yOs19fMcu7DDSdhQ_I0aCANf9GaKO-nqMBiixYEhA== 17:46:22 INFO - x-amz-version-id: cI8qbeQdsuXcQX8zBrDgJereXw7bANQ7 17:46:23 INFO - Expected file size: 16267558 17:46:23 INFO - Obtained file size: 16267558 17:46:29 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 17:46:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KsD4CFTWQ2aX0Fx_m1mQbQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 17:46:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KsD4CFTWQ2aX0Fx_m1mQbQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 17:46:29 INFO - Fetch https://queue.taskcluster.net/v1/task/KsD4CFTWQ2aX0Fx_m1mQbQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 17:46:30 INFO - Http code: 200 17:46:30 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront) 17:46:30 INFO - x-amz-cf-id: KFY19nlWMArHXAyQvebTydOaw7ZCS9LF739MlzYA2HrRYYH8IuSq9Q== 17:46:30 INFO - x-amz-version-id: cI8qbeQdsuXcQX8zBrDgJereXw7bANQ7 17:46:30 INFO - Expected file size: 16267558 17:46:30 INFO - Obtained file size: 16267558 17:46:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KsD4CFTWQ2aX0Fx_m1mQbQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 17:46:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KsD4CFTWQ2aX0Fx_m1mQbQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 17:46:37 INFO - Fetch https://queue.taskcluster.net/v1/task/KsD4CFTWQ2aX0Fx_m1mQbQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 17:46:37 INFO - Http code: 200 17:46:37 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 17:46:37 INFO - x-amz-cf-id: 9GpE0Xo_bn7vHlctkptXUQguVL-h6vF5dsaO_DixPEvxReTryGviKg== 17:46:37 INFO - x-amz-version-id: KZJN0WSSLQXQFSupc6FH.xG5V3FimD09 17:46:38 INFO - Expected file size: 14409038 17:46:38 INFO - Obtained file size: 14409038 17:46:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:46:39 INFO - trying https://queue.taskcluster.net/v1/task/KsD4CFTWQ2aX0Fx_m1mQbQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 17:46:39 INFO - Downloading https://queue.taskcluster.net/v1/task/KsD4CFTWQ2aX0Fx_m1mQbQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 17:46:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KsD4CFTWQ2aX0Fx_m1mQbQ/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 17:46:41 INFO - Downloaded 109796826 bytes. 17:46:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KsD4CFTWQ2aX0Fx_m1mQbQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 17:46:41 INFO - mkdir: /builds/slave/test/properties 17:46:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:46:41 INFO - Writing to file /builds/slave/test/properties/build_url 17:46:41 INFO - Contents: 17:46:41 INFO - build_url:https://queue.taskcluster.net/v1/task/KsD4CFTWQ2aX0Fx_m1mQbQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 17:46:42 INFO - Running post-action listener: _resource_record_post_action 17:46:42 INFO - Running post-action listener: set_extra_try_arguments 17:46:42 INFO - [mozharness: 2016-12-21 01:46:42.619653Z] Finished download-and-extract step (success) 17:46:42 INFO - [mozharness: 2016-12-21 01:46:42.619783Z] Running populate-webroot step. 17:46:42 INFO - Running pre-action listener: _resource_record_pre_action 17:46:42 INFO - Running main action method: populate_webroot 17:46:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:46:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:46:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:46:42 INFO - '--tppagecycles', 17:46:42 INFO - '7'], 17:46:42 INFO - 'tests': ['tsvgm']}}, 17:46:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:46:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:46:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:46:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:46:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:46:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:46:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:46:42 INFO - 'talos_options': ['--disable-e10s'], 17:46:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:46:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:46:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:46:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:46:42 INFO - 'talos_options': ['--disable-e10s'], 17:46:42 INFO - 'tests': ['damp', 'tps']}, 17:46:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:46:42 INFO - 'tests': ['damp', 'tps']}, 17:46:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:46:42 INFO - 'tests': ['dromaeo_dom']}, 17:46:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:46:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:46:42 INFO - 'tests': ['basic_compositor_video']}, 17:46:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:46:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:46:42 INFO - 'tests': ['a11yr', 17:46:42 INFO - 'ts_paint', 17:46:42 INFO - 'tpaint', 17:46:42 INFO - 'sessionrestore', 17:46:42 INFO - 'sessionrestore_no_auto_restore', 17:46:42 INFO - 'tabpaint']}, 17:46:42 INFO - 'other-e10s': {'tests': ['a11yr', 17:46:42 INFO - 'ts_paint', 17:46:42 INFO - 'tpaint', 17:46:42 INFO - 'sessionrestore', 17:46:42 INFO - 'sessionrestore_no_auto_restore', 17:46:42 INFO - 'tabpaint']}, 17:46:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:46:42 INFO - 'ts_paint', 17:46:42 INFO - 'tpaint', 17:46:42 INFO - 'sessionrestore', 17:46:42 INFO - 'sessionrestore_no_auto_restore', 17:46:42 INFO - 'tabpaint']}, 17:46:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:46:42 INFO - 'ts_paint', 17:46:42 INFO - 'tpaint', 17:46:42 INFO - 'sessionrestore', 17:46:42 INFO - 'sessionrestore_no_auto_restore', 17:46:42 INFO - 'tabpaint']}, 17:46:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:46:42 INFO - 'tests': ['a11yr', 17:46:42 INFO - 'ts_paint', 17:46:42 INFO - 'tpaint', 17:46:42 INFO - 'sessionrestore', 17:46:42 INFO - 'sessionrestore_no_auto_restore', 17:46:42 INFO - 'tabpaint']}, 17:46:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:46:42 INFO - 'tests': ['a11yr', 17:46:42 INFO - 'ts_paint', 17:46:42 INFO - 'tpaint', 17:46:42 INFO - 'sessionrestore', 17:46:42 INFO - 'sessionrestore_no_auto_restore', 17:46:42 INFO - 'tabpaint']}, 17:46:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:46:42 INFO - 'tests': ['tsvgx', 17:46:42 INFO - 'tsvgr_opacity', 17:46:42 INFO - 'tart', 17:46:42 INFO - 'tscrollx', 17:46:42 INFO - 'cart']}, 17:46:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:46:42 INFO - 'tsvgr_opacity', 17:46:42 INFO - 'tart', 17:46:42 INFO - 'tscrollx', 17:46:42 INFO - 'cart']}, 17:46:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:46:42 INFO - 'talos_options': ['--disable-e10s'], 17:46:42 INFO - 'tests': ['tp5o']}, 17:46:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:46:42 INFO - 'tests': ['tp5o']}, 17:46:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:46:42 INFO - 'talos_options': ['--disable-e10s', 17:46:42 INFO - '--xperf_path', 17:46:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:46:42 INFO - 'tests': ['tp5n']}, 17:46:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:46:42 INFO - 'talos_options': ['--xperf_path', 17:46:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:46:42 INFO - 'tests': ['tp5n']}}, 17:46:42 INFO - 'talos.zip': {'path': '', 17:46:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:46:42 INFO - Running post-action listener: _resource_record_post_action 17:46:42 INFO - [mozharness: 2016-12-21 01:46:42.638682Z] Finished populate-webroot step (success) 17:46:42 INFO - [mozharness: 2016-12-21 01:46:42.638792Z] Running create-virtualenv step. 17:46:42 INFO - Running pre-action listener: _resource_record_pre_action 17:46:42 INFO - Running main action method: create_virtualenv 17:46:42 INFO - Creating virtualenv /builds/slave/test/build/venv 17:46:42 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 17:46:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:46:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:46:42 INFO - Using real prefix '/tools/python27' 17:46:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:46:43 INFO - Installing distribute.............................................................................................................................................................................................done. 17:46:46 INFO - Installing pip.................done. 17:46:46 INFO - Return code: 0 17:46:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:46:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:46:46 INFO - Reading from file tmpfile_stdout 17:46:46 INFO - Output received: 17:46:46 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) 17:46:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:46:46 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')]} 17:46:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:46:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:46:46 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')]} 17:46:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:46:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:46:46 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 0x10495b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048d1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf04c8630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10476fd30>, '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 0x1045d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104767b20>, '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.LKbNiRgKpx/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.yKqm16OKDZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 17:46:46 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 17:46:46 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 17:46:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LKbNiRgKpx/Render', 17:46:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:46:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:46:46 INFO - 'HOME': '/Users/cltbld', 17:46:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:46:46 INFO - 'LOGNAME': 'cltbld', 17:46:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:46:46 INFO - 'MOZ_NO_REMOTE': '1', 17:46:46 INFO - 'NO_EM_RESTART': '1', 17:46:46 INFO - 'PAGER': '/bin/cat', 17:46:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:46:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:46:46 INFO - 'PWD': '/builds/slave/test', 17:46:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:46:46 INFO - 'SHELL': '/bin/bash', 17:46:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yKqm16OKDZ/Listeners', 17:46:46 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 17:46:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:46:46 INFO - 'USER': 'cltbld', 17:46:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:46:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:46:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:46:46 INFO - 'XPC_FLAGS': '0x0', 17:46:46 INFO - 'XPC_SERVICE_NAME': '0', 17:46:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:46:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:46:46 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 17:46:46 INFO - Cleaning up... 17:46:46 INFO - Return code: 0 17:46:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:46:46 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')]} 17:46:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:46:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:46:46 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')]} 17:46:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:46:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:46:46 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 0x10495b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048d1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf04c8630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10476fd30>, '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 0x1045d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104767b20>, '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.LKbNiRgKpx/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.yKqm16OKDZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 17:46:46 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 17:46:46 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 17:46:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LKbNiRgKpx/Render', 17:46:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:46:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:46:46 INFO - 'HOME': '/Users/cltbld', 17:46:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:46:46 INFO - 'LOGNAME': 'cltbld', 17:46:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:46:46 INFO - 'MOZ_NO_REMOTE': '1', 17:46:46 INFO - 'NO_EM_RESTART': '1', 17:46:46 INFO - 'PAGER': '/bin/cat', 17:46:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:46:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:46:46 INFO - 'PWD': '/builds/slave/test', 17:46:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:46:46 INFO - 'SHELL': '/bin/bash', 17:46:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yKqm16OKDZ/Listeners', 17:46:46 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 17:46:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:46:46 INFO - 'USER': 'cltbld', 17:46:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:46:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:46:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:46:46 INFO - 'XPC_FLAGS': '0x0', 17:46:46 INFO - 'XPC_SERVICE_NAME': '0', 17:46:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:46:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:46:47 INFO - Downloading/unpacking psutil>=3.1.1 17:46:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:46:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:46:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:46:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:46:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:46:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:46:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:46:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:46:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:46:50 INFO - Installing collected packages: psutil 17:46:50 INFO - Running setup.py install for psutil 17:46:50 INFO - building 'psutil._psutil_osx' extension 17:46:50 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 17:46:51 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 17:46:51 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 17:46:51 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 17:46:51 INFO - building 'psutil._psutil_posix' extension 17:46:51 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 17:46:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:46:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:46:51 INFO - ^ 17:46:51 INFO - 1 warning generated. 17:46:51 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 17:46:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:46:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:46:51 INFO - Successfully installed psutil 17:46:51 INFO - Cleaning up... 17:46:51 INFO - Return code: 0 17:46:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:46:51 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')]} 17:46:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:46:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:46:51 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')]} 17:46:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:46:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:46:51 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 0x10495b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048d1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf04c8630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10476fd30>, '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 0x1045d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104767b20>, '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.LKbNiRgKpx/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.yKqm16OKDZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 17:46:51 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 17:46:51 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 17:46:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LKbNiRgKpx/Render', 17:46:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:46:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:46:51 INFO - 'HOME': '/Users/cltbld', 17:46:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:46:51 INFO - 'LOGNAME': 'cltbld', 17:46:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:46:51 INFO - 'MOZ_NO_REMOTE': '1', 17:46:51 INFO - 'NO_EM_RESTART': '1', 17:46:51 INFO - 'PAGER': '/bin/cat', 17:46:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:46:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:46:51 INFO - 'PWD': '/builds/slave/test', 17:46:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:46:51 INFO - 'SHELL': '/bin/bash', 17:46:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yKqm16OKDZ/Listeners', 17:46:51 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 17:46:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:46:51 INFO - 'USER': 'cltbld', 17:46:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:46:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:46:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:46:51 INFO - 'XPC_FLAGS': '0x0', 17:46:51 INFO - 'XPC_SERVICE_NAME': '0', 17:46:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:46:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:46:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:46:52 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 17:46:52 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 17:46:52 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 17:46:52 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 17:46:52 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 17:46:52 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 17:46:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:46:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:46:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:46:54 INFO - Installing collected packages: mozsystemmonitor 17:46:54 INFO - Running setup.py install for mozsystemmonitor 17:46:54 INFO - Successfully installed mozsystemmonitor 17:46:54 INFO - Cleaning up... 17:46:55 INFO - Return code: 0 17:46:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:46:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:46:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:46:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:46:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:46:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:46:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:46:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10495b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048d1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf04c8630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10476fd30>, '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 0x1045d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104767b20>, '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.LKbNiRgKpx/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.yKqm16OKDZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 17:46:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:46:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:46:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LKbNiRgKpx/Render', 17:46:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:46:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:46:55 INFO - 'HOME': '/Users/cltbld', 17:46:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:46:55 INFO - 'LOGNAME': 'cltbld', 17:46:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:46:55 INFO - 'MOZ_NO_REMOTE': '1', 17:46:55 INFO - 'NO_EM_RESTART': '1', 17:46:55 INFO - 'PAGER': '/bin/cat', 17:46:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:46:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:46:55 INFO - 'PWD': '/builds/slave/test', 17:46:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:46:55 INFO - 'SHELL': '/bin/bash', 17:46:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yKqm16OKDZ/Listeners', 17:46:55 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 17:46:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:46:55 INFO - 'USER': 'cltbld', 17:46:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:46:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:46:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:46:55 INFO - 'XPC_FLAGS': '0x0', 17:46:55 INFO - 'XPC_SERVICE_NAME': '0', 17:46:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:46:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:46:55 INFO - Downloading/unpacking jsonschema==2.5.1 17:46:55 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 17:46:55 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 17:46:55 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 17:46:55 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 17:46:55 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 17:46:55 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 17:46:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:46:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:46:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:46:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:46:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:46:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:46:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:46:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:46:58 INFO - Downloading functools32-3.2.3-2.tar.gz 17:46:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:46:58 INFO - warning: no files found matching '*.txt' 17:46:58 INFO - no previously-included directories found matching 'build' 17:46:58 INFO - no previously-included directories found matching 'dist' 17:46:58 INFO - no previously-included directories found matching '.git*' 17:46:58 INFO - Installing collected packages: jsonschema, functools32 17:46:58 INFO - Running setup.py install for functools32 17:46:58 INFO - warning: no files found matching '*.txt' 17:46:58 INFO - no previously-included directories found matching 'build' 17:46:58 INFO - no previously-included directories found matching 'dist' 17:46:58 INFO - no previously-included directories found matching '.git*' 17:46:58 INFO - Successfully installed jsonschema functools32 17:46:58 INFO - Cleaning up... 17:46:58 INFO - Return code: 0 17:46:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:46:58 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')]} 17:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:46:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:46:58 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')]} 17:46:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:46:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:46:58 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 0x10495b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048d1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf04c8630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10476fd30>, '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 0x1045d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104767b20>, '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.LKbNiRgKpx/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.yKqm16OKDZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 17:46:58 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 17:46:58 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 17:46:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LKbNiRgKpx/Render', 17:46:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:46:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:46:58 INFO - 'HOME': '/Users/cltbld', 17:46:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:46:58 INFO - 'LOGNAME': 'cltbld', 17:46:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:46:58 INFO - 'MOZ_NO_REMOTE': '1', 17:46:58 INFO - 'NO_EM_RESTART': '1', 17:46:58 INFO - 'PAGER': '/bin/cat', 17:46:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:46:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:46:58 INFO - 'PWD': '/builds/slave/test', 17:46:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:46:58 INFO - 'SHELL': '/bin/bash', 17:46:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yKqm16OKDZ/Listeners', 17:46:58 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 17:46:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:46:58 INFO - 'USER': 'cltbld', 17:46:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:46:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:46:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:46:58 INFO - 'XPC_FLAGS': '0x0', 17:46:58 INFO - 'XPC_SERVICE_NAME': '0', 17:46:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:46:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:46:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:46:59 INFO - Cleaning up... 17:46:59 INFO - Return code: 0 17:46:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17: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')]} 17:46:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:46:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17: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')]} 17:46:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:46:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:46:59 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 0x10495b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048d1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf04c8630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10476fd30>, '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 0x1045d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104767b20>, '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.LKbNiRgKpx/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.yKqm16OKDZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 17:46:59 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 17:46:59 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 17:46:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LKbNiRgKpx/Render', 17:46:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:46:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:46:59 INFO - 'HOME': '/Users/cltbld', 17:46:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:46:59 INFO - 'LOGNAME': 'cltbld', 17:46:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:46:59 INFO - 'MOZ_NO_REMOTE': '1', 17:46:59 INFO - 'NO_EM_RESTART': '1', 17:46:59 INFO - 'PAGER': '/bin/cat', 17:46:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:46:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:46:59 INFO - 'PWD': '/builds/slave/test', 17:46:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:46:59 INFO - 'SHELL': '/bin/bash', 17:46:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yKqm16OKDZ/Listeners', 17:46:59 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 17:46:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:46:59 INFO - 'USER': 'cltbld', 17:46:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:46:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:46:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:46:59 INFO - 'XPC_FLAGS': '0x0', 17:46:59 INFO - 'XPC_SERVICE_NAME': '0', 17:46:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:46:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:46:59 INFO - Downloading/unpacking blobuploader==1.2.4 17:46:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:46:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:46:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:46:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:46:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:46:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:47:02 INFO - Downloading blobuploader-1.2.4.tar.gz 17:47:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:47:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:47:02 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 17:47:02 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 17:47:02 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 17:47:02 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 17:47:02 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 17:47:02 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 17:47:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:47:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:47:02 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 17:47:02 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 17:47:02 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 17:47:02 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 17:47:02 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 17:47:02 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 17:47:03 INFO - Downloading docopt-0.6.1.tar.gz 17:47:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:47:03 INFO - Installing collected packages: blobuploader, requests, docopt 17:47:03 INFO - Running setup.py install for blobuploader 17:47:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:47:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:47:03 INFO - Running setup.py install for requests 17:47:04 INFO - Running setup.py install for docopt 17:47:04 INFO - Successfully installed blobuploader requests docopt 17:47:04 INFO - Cleaning up... 17:47:04 INFO - Return code: 0 17:47:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:47:04 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')]} 17:47:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:47:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:47:04 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')]} 17:47:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:47:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:47:04 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 0x10495b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048d1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf04c8630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10476fd30>, '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 0x1045d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104767b20>, '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.LKbNiRgKpx/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.yKqm16OKDZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 17:47:04 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 17:47:04 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 17:47:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LKbNiRgKpx/Render', 17:47:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:47:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:47:04 INFO - 'HOME': '/Users/cltbld', 17:47:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:47:04 INFO - 'LOGNAME': 'cltbld', 17:47:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:47:04 INFO - 'MOZ_NO_REMOTE': '1', 17:47:04 INFO - 'NO_EM_RESTART': '1', 17:47:04 INFO - 'PAGER': '/bin/cat', 17:47:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:47:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:47:04 INFO - 'PWD': '/builds/slave/test', 17:47:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:47:04 INFO - 'SHELL': '/bin/bash', 17:47:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yKqm16OKDZ/Listeners', 17:47:04 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 17:47:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:47:04 INFO - 'USER': 'cltbld', 17:47:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:47:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:47:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:47:04 INFO - 'XPC_FLAGS': '0x0', 17:47:04 INFO - 'XPC_SERVICE_NAME': '0', 17:47:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:47:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:47:04 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-vNkcur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:47:04 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-cmxGPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:47:04 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-2FIpbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:47:04 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-2EcXF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:47:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:47:04 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-22pP55-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:47:05 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-nA34ar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:47:05 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-vNMDW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:47:05 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-BKn3jW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:47:05 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-RNlGGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:47:05 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-Nv7DIg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:47:05 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-iibE5D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:47:05 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-WDApD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:47:05 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-eyLTcb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:47:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:47:05 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-8XdLqA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:47:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:47:06 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-3Dky3W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:47:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:47:06 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-_FnT4_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:47:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:47:06 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-OX48dN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:47:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:47:06 INFO - Running setup.py install for manifestparser 17:47:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:47:06 INFO - Running setup.py install for mozcrash 17:47:06 INFO - Running setup.py install for mozdebug 17:47:06 INFO - Running setup.py install for mozdevice 17:47:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:47:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:47:06 INFO - Running setup.py install for mozfile 17:47:07 INFO - Running setup.py install for mozhttpd 17:47:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:47:07 INFO - Running setup.py install for mozinfo 17:47:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:47:07 INFO - Running setup.py install for mozInstall 17:47:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:47:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:47:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:47:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:47:07 INFO - Running setup.py install for mozleak 17:47:07 INFO - Running setup.py install for mozlog 17:47:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:47:07 INFO - Running setup.py install for moznetwork 17:47:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:47:07 INFO - Running setup.py install for mozprocess 17:47:08 INFO - Running setup.py install for mozprofile 17:47:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:47:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:47:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:47:08 INFO - Running setup.py install for mozrunner 17:47:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:47:08 INFO - Running setup.py install for mozscreenshot 17:47:08 INFO - Running setup.py install for moztest 17:47:08 INFO - Running setup.py install for mozversion 17:47:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:47:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:47:08 INFO - Cleaning up... 17:47:08 INFO - Return code: 0 17:47:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:47:08 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')]} 17:47:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:47:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:47:08 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')]} 17:47:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:47:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:47:08 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 0x10495b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048d1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf04c8630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10476fd30>, '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 0x1045d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104767b20>, '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.LKbNiRgKpx/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.yKqm16OKDZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 17:47:08 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 17:47:08 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 17:47:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LKbNiRgKpx/Render', 17:47:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:47:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:47:08 INFO - 'HOME': '/Users/cltbld', 17:47:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:47:08 INFO - 'LOGNAME': 'cltbld', 17:47:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:47:08 INFO - 'MOZ_NO_REMOTE': '1', 17:47:08 INFO - 'NO_EM_RESTART': '1', 17:47:08 INFO - 'PAGER': '/bin/cat', 17:47:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:47:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:47:08 INFO - 'PWD': '/builds/slave/test', 17:47:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:47:08 INFO - 'SHELL': '/bin/bash', 17:47:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yKqm16OKDZ/Listeners', 17:47:08 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 17:47:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:47:08 INFO - 'USER': 'cltbld', 17:47:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:47:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:47:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:47:08 INFO - 'XPC_FLAGS': '0x0', 17:47:08 INFO - 'XPC_SERVICE_NAME': '0', 17:47:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:47:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:47:09 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-jRmZeO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:47:09 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)) 17:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:47:09 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-2bkPYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:47:09 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)) 17:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:47:09 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-d2e0ll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:47:09 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)) 17:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:47:09 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-JZMubZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:47:09 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)) 17:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:47:09 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-8Ya9vG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:47:09 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)) 17:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:47:09 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-TX6EHL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:47:09 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)) 17:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:47:09 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-k4oVEF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:47:09 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)) 17:47:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:47:09 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-iITI1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:47:09 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)) 17:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:47:10 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-JER2E5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:47:10 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)) 17:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:47:10 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-LeXDXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:47:10 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)) 17:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:47:10 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-r1AfJi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:47:10 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)) 17:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:47:10 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-YyUpPS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:47:10 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)) 17:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:47:10 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-qZCJlu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:47:10 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)) 17:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:47:10 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-dVhVbQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:47:10 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)) 17:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:47:10 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-3tMSCk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:47:10 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)) 17:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:47:10 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-NR1XAO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:47:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 17:47:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:47:10 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-bTv4eZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:47:10 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)) 17:47:10 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)) 17:47:10 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)) 17:47:10 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)) 17:47:10 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)) 17:47:10 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)) 17:47:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:47: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 17:47: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 17:47: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 17:47: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 17:47: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 17:47: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 17:47:13 INFO - Downloading blessings-1.6.tar.gz 17:47:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:47:13 INFO - Installing collected packages: blessings 17:47:13 INFO - Running setup.py install for blessings 17:47:13 INFO - Successfully installed blessings 17:47:13 INFO - Cleaning up... 17:47:13 INFO - Return code: 0 17:47:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:47:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:47:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:47:14 INFO - Reading from file tmpfile_stdout 17:47:14 INFO - Current package versions: 17:47:14 INFO - blessings == 1.6 17:47:14 INFO - blobuploader == 1.2.4 17:47:14 INFO - docopt == 0.6.1 17:47:14 INFO - functools32 == 3.2.3-2 17:47:14 INFO - jsonschema == 2.5.1 17:47:14 INFO - manifestparser == 1.1 17:47:14 INFO - mozInstall == 1.12 17:47:14 INFO - mozcrash == 1.0 17:47:14 INFO - mozdebug == 0.1 17:47:14 INFO - mozdevice == 0.48 17:47:14 INFO - mozfile == 1.2 17:47:14 INFO - mozhttpd == 0.7 17:47:14 INFO - mozinfo == 0.9 17:47:14 INFO - mozleak == 0.1 17:47:14 INFO - mozlog == 3.4 17:47:14 INFO - moznetwork == 0.27 17:47:14 INFO - mozprocess == 0.23 17:47:14 INFO - mozprofile == 0.28 17:47:14 INFO - mozrunner == 6.13 17:47:14 INFO - mozscreenshot == 0.1 17:47:14 INFO - mozsystemmonitor == 0.3 17:47:14 INFO - moztest == 0.7 17:47:14 INFO - mozversion == 1.4 17:47:14 INFO - psutil == 3.1.1 17:47:14 INFO - requests == 1.2.3 17:47:14 INFO - wsgiref == 0.1.2 17:47:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:47:14 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')]} 17:47:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:47:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:47:14 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')]} 17:47:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:47:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:47:14 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 0x10495b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048d1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf04c8630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10476fd30>, '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 0x1045d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104767b20>, '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.LKbNiRgKpx/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.yKqm16OKDZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 17:47:14 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 17:47:14 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 17:47:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LKbNiRgKpx/Render', 17:47:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:47:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:47:14 INFO - 'HOME': '/Users/cltbld', 17:47:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:47:14 INFO - 'LOGNAME': 'cltbld', 17:47:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:47:14 INFO - 'MOZ_NO_REMOTE': '1', 17:47:14 INFO - 'NO_EM_RESTART': '1', 17:47:14 INFO - 'PAGER': '/bin/cat', 17:47:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:47:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:47:14 INFO - 'PWD': '/builds/slave/test', 17:47:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:47:14 INFO - 'SHELL': '/bin/bash', 17:47:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yKqm16OKDZ/Listeners', 17:47:14 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 17:47:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:47:14 INFO - 'USER': 'cltbld', 17:47:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:47:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:47:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:47:14 INFO - 'XPC_FLAGS': '0x0', 17:47:14 INFO - 'XPC_SERVICE_NAME': '0', 17:47:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:47:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:47:15 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)) 17:47:15 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)) 17:47:15 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)) 17:47:15 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)) 17:47:15 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)) 17:47:15 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)) 17:47:15 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)) 17:47:15 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)) 17:47:15 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)) 17:47:15 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)) 17:47:15 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)) 17:47:15 INFO - Cleaning up... 17:47:15 INFO - Return code: 0 17:47:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:47: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')]} 17:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:47:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:47: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')]} 17:47:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:47:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:47:15 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 0x10495b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1048d1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fabf04c8630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10476fd30>, '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 0x1045d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104767b20>, '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.LKbNiRgKpx/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.yKqm16OKDZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 17:47:15 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 17:47:15 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 17:47:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LKbNiRgKpx/Render', 17:47:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:47:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:47:15 INFO - 'HOME': '/Users/cltbld', 17:47:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:47:15 INFO - 'LOGNAME': 'cltbld', 17:47:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:47:15 INFO - 'MOZ_NO_REMOTE': '1', 17:47:15 INFO - 'NO_EM_RESTART': '1', 17:47:15 INFO - 'PAGER': '/bin/cat', 17:47:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:47:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:47:15 INFO - 'PWD': '/builds/slave/test', 17:47:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:47:15 INFO - 'SHELL': '/bin/bash', 17:47:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yKqm16OKDZ/Listeners', 17:47:15 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 17:47:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:47:15 INFO - 'USER': 'cltbld', 17:47:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:47:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:47:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:47:15 INFO - 'XPC_FLAGS': '0x0', 17:47:15 INFO - 'XPC_SERVICE_NAME': '0', 17:47:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:47:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:47:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:47:15 INFO - Cleaning up... 17:47:15 INFO - Return code: 0 17:47:15 INFO - Running post-action listener: _resource_record_post_action 17:47:15 INFO - Running post-action listener: _start_resource_monitoring 17:47:15 INFO - Starting resource monitoring. 17:47:15 INFO - [mozharness: 2016-12-21 01:47:15.886245Z] Finished create-virtualenv step (success) 17:47:15 INFO - [mozharness: 2016-12-21 01:47:15.887279Z] Running install step. 17:47:15 INFO - Running pre-action listener: _resource_record_pre_action 17:47:15 INFO - Running main action method: install 17:47:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:47:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:47:16 INFO - Reading from file tmpfile_stdout 17:47:16 INFO - Detecting whether we're running mozinstall >=1.0... 17:47:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:47:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:47:16 INFO - Reading from file tmpfile_stdout 17:47:16 INFO - Output received: 17:47:16 INFO - Usage: mozinstall [options] installer 17:47:16 INFO - Options: 17:47:16 INFO - -h, --help show this help message and exit 17:47:16 INFO - -d DEST, --destination=DEST 17:47:16 INFO - Directory to install application into. [default: 17:47:16 INFO - "/builds/slave/test"] 17:47:16 INFO - --app=APP Application being installed. [default: firefox] 17:47:16 INFO - mkdir: /builds/slave/test/build/application 17:47:16 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'] 17:47:16 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 17:47:47 INFO - Reading from file tmpfile_stdout 17:47:47 INFO - Output received: 17:47:47 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 17:47:47 INFO - Running post-action listener: _resource_record_post_action 17:47:47 INFO - [mozharness: 2016-12-21 01:47:47.239634Z] Finished install step (success) 17:47:47 INFO - [mozharness: 2016-12-21 01:47:47.239785Z] Running run-tests step. 17:47:47 INFO - Running pre-action listener: _resource_record_pre_action 17:47:47 INFO - Running main action method: run_tests 17:47:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:47:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:47:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:47:47 INFO - 2016-12-20 17:47:47.254 screenresolution[1625:7285] starting screenresolution argv=screenresolution get 17:47:47 INFO - 2016-12-20 17:47:47.256 screenresolution[1625:7285] Display 0: 1600x1200x32@60 17:47:47 INFO - 2016-12-20 17:47:47.262 screenresolution[1626:7287] starting screenresolution argv=screenresolution list 17:47:47 INFO - Available Modes on Display 0 17:47:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:47:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:47:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:47:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:47:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:47:47 INFO - Intel Iris: 17:47:47 INFO - Chipset Model: Intel Iris 17:47:47 INFO - Type: GPU 17:47:47 INFO - Bus: Built-In 17:47:47 INFO - VRAM (Dynamic, Max): 1536 MB 17:47:47 INFO - Vendor: Intel (0x8086) 17:47:47 INFO - Device ID: 0x0a2e 17:47:47 INFO - Revision ID: 0x0009 17:47:47 INFO - Displays: 17:47:47 INFO - Display: 17:47:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:47:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:47:47 INFO - Display Serial Number: 1600x1200 60 17:47:47 INFO - Main Display: Yes 17:47:47 INFO - Mirror: Off 17:47:47 INFO - Online: Yes 17:47:47 INFO - Rotation: Supported 17:47:47 INFO - Return code: 0 17:47:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:47:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:47:47 INFO - Python 2.7.3 17:47:47 INFO - Return code: 0 17:47:47 INFO - grabbing minidump binary from tooltool 17:47:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:47:47 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 0x10476fd30>, '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 0x1045d5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104767b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:47:47 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 17:47:47 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 17:47:47 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 17:47:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:47:47 INFO - Return code: 0 17:47:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:47:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:47:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:47:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:47:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:47:47 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/KsD4CFTWQ2aX0Fx_m1mQbQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0177', '--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 17:47:47 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/KsD4CFTWQ2aX0Fx_m1mQbQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0177 --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 17:47:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LKbNiRgKpx/Render', 17:47:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:47:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:47:47 INFO - 'HOME': '/Users/cltbld', 17:47:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:47:47 INFO - 'LOGNAME': 'cltbld', 17:47:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:47:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:47:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:47:47 INFO - 'MOZ_NO_REMOTE': '1', 17:47:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:47:47 INFO - 'NO_EM_RESTART': '1', 17:47:47 INFO - 'PAGER': '/bin/cat', 17:47:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:47:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:47:47 INFO - 'PWD': '/builds/slave/test', 17:47:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:47:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:47:47 INFO - 'SHELL': '/bin/bash', 17:47:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yKqm16OKDZ/Listeners', 17:47:47 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 17:47:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:47:47 INFO - 'USER': 'cltbld', 17:47:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:47:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:47:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:47:47 INFO - 'XPC_FLAGS': '0x0', 17:47:47 INFO - 'XPC_SERVICE_NAME': '0', 17:47:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:47:47 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/KsD4CFTWQ2aX0Fx_m1mQbQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0177', '--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 17:47:47 INFO - mozversion application_buildid: 20161220153544 17:47:47 INFO - mozversion application_changeset: 81a1f25491a65bdf41584a261a3d44607af9ac95 17:47:47 INFO - mozversion application_display_name: FirefoxDeveloperEdition 17:47:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:47:47 INFO - mozversion application_name: Firefox 17:47:47 INFO - mozversion application_remotingname: firefox-dev 17:47:47 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:47:47 INFO - mozversion application_vendor: Mozilla 17:47:47 INFO - mozversion application_version: 52.0a2 17:47:47 INFO - mozversion platform_buildid: 20161220153544 17:47:47 INFO - mozversion platform_changeset: 81a1f25491a65bdf41584a261a3d44607af9ac95 17:47:47 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:47:47 INFO - mozversion platform_version: 52.0a2 17:47:47 INFO - using testdate: 1482284867 17:47:47 INFO - actual date: 1482284867 17:47:47 INFO - starting webserver on 'localhost:49241' 17:47:47 INFO - SUITE-START | Running 2 tests 17:47:47 INFO - TEST-START | dromaeo_css 17:47:47 INFO - operating with platform_type : mac_ 17:47:47 INFO - Initialising browser for dromaeo_css test... 17:47:47 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/tmpgGRpUh/profile http://localhost:49241/getInfo.html) 17:47:50 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 17:47:50 INFO - PROCESS | 1636 | colorDepth:24 17:47:50 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 17:47:50 INFO - PROCESS | 1636 | __metrics 17:47:50 INFO - PROCESS | 1636 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:47:50 INFO - PROCESS | 1636 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:47:51 INFO - TEST-INFO | 1636: exit 0 17:47:51 INFO - Browser initialized. 17:47:51 INFO - Running cycle 1/1 for dromaeo_css test... 17:47:51 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/tmpgGRpUh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:48:03 INFO - PROCESS | 1638 | RSS: Main: 222658560 17:48:03 INFO - PROCESS | 1638 | 17:48:55 INFO - PROCESS | 1638 | 1482284935695 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 17:51:08 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 17:51:10 INFO - PROCESS | 1638 | RSS: Main: 414769152 17:51:10 INFO - PROCESS | 1638 | 17:54:13 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 17:54:14 INFO - PROCESS | 1638 | RSS: Main: 345788416 17:54:14 INFO - PROCESS | 1638 | 17:57:16 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 17:57:17 INFO - PROCESS | 1638 | RSS: Main: 321757184 17:57:17 INFO - PROCESS | 1638 | 18:00:19 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 18:00:20 INFO - PROCESS | 1638 | RSS: Main: 320454656 18:00:20 INFO - PROCESS | 1638 | 18:03:23 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 18:03:24 INFO - PROCESS | 1638 | RSS: Main: 306622464 18:03:24 INFO - PROCESS | 1638 | 18:06:27 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 18:06:27 INFO - PROCESS | 1638 | RSS: Main: 391122944 18:06:27 INFO - PROCESS | 1638 | 18:06:27 INFO - PROCESS | 1638 | __start_tp_report 18:06:27 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 18:06:27 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 18:06:27 INFO - PROCESS | 1638 | |i|pagename|runs| 18:06:27 INFO - PROCESS | 1638 | |0;dojo.html;2999.640043194817;3273.247153154773;3328.9667103328966;3353.6311005789366;3408.977306807957;5676.574256930731;5752.108423194401;5756.913646295298;5772.567057470691;5807.854803629912;5676.971615141929;5745.138229265613;5746.885062298751;5752.424757524245;5770.076787713967;5213.530782229599;5239.580833533318;5240.580753539727;5252.107141785906;5277.340332458442;4996.225585034307;5013.248012798073;5016.548510634042;5312;5323.494268044529;5069.898602027957;5089.185730283156;5089.923651145235;5091.719955402451;5161.30322406476;5228;5257.658252213613;5268.578513718893;5284.709340986245;5296.576273898079;5225.608079394061;5262.315898933152;5271.024860400838;5291.576673866081;5300.973495132538;5561.860953476155;5642.125470552071;5765.682887441189;5782.855428614276;5858.648481091105;6106.710922515801;6140.60946423305;6145.754169833202;6171.969140154315;6194.473469755077;5273.920891186635;6264.780732674334;6273.435390814803;6304.621722696607;6318.368163183645;5644.689542075143;5768;5769.07694768835;5790.073588225863;5795.913061304083;6234.906476402857;6275.905861411991;6312;6319.020551814476;6327.082573026885;6163.537734669918;6167.105769663373;6177.444030037318;6178.505719542426;6184.319724830265;6274;6282.246130464282;6312.495000399957;6320.62076275425;6321.683915804283;6058.0610005449225;6082.239720034996;6118.082287656798;6136.8772624547255;6157.138000679908;5215.921761173586;5233.738313084331;6246.312905580383;6247.156633854372;6261.5616906816085;4837.516248375135;4847.957689096861;4900.240462728283;4920.507949205051;4941.135301322254;4316.525182229952;4399.494058183286;4408.713433626804;4419.513853476115;4420.270655341833;5679.716014199274;5698.715064246771;5701.144828275792;5705.657660540381;5707.486326230659;6197.101420294032;6200.658963756947;6215.378462153839;6271.529635277372;6274.874502509924;5722.7424765885635;5764.193012978187;5765.1928729977835;5773.797917072882;5788.160716696056;5424.159255315432;5425.674459532362;5438.592105592096;5439.809606663827;5448.673079615157;6212.534059945432;6281.560290779785;6283.151774510383;6283.2460104787715;6331.398517140957;6255.280642726145;6302.023186406114;6332.366763323631;6340.556161068866;6344.936550634435;4877.609791216623;6230.7819226326865;6241.344658810759;6314.778982735582;6412.487001039813;6081.787137450168;6129.295131059986;6187.588980815032;6233.376662333731;6245.875082498415;6155.076738489262;6187.257529096537;6240.937590624218;6290.748370065329;6301.842453938688;5966.671833049224;6116.8776624468155;6129.264488261437;6137.018077107641;6150.200473938538;5898.970505147447;6100;6102.9257733398435;6123.526657168115;6159.969200153971;5836;5892.35184129754;5895.086261629454;5912.054071348563;5916.940830591811;6025.276966764016;6026.337102918763;6028.005379112397;6035;6035.336113027652;5826.329972053268;5863.325717542367;5873.9412605873395;5879.088741245114;5879.5002424794375 18:06:27 INFO - PROCESS | 1638 | |1;ext.html;448.65901914544946;491.34160225298115;518.0648928683725;520.700597156635;557.6431084106173;27598.586021209674;31546.369072618563;31674.68325316749;38999.41500877489;39963;40998.59001409985;41308.17383652325;41395.58604413955;41422.58577414225;41483.585164148346;9295.349325547231;9446.480443575601;9457.952710236439;9470.10034046768;9470.621175152986;9305.34862559621;9415.62337506499;9417.434953902753;9419.62321507139;9441.90558094417;9366.438013719164;9423.293253006052;9424.01047889972;9435.056494350545;9449.385789923634;9314.115159059878;9361.344705870591;9384.20234280087;9392.530373481299;9396.812063758754;9335.813283734355;9403.717888463358;9404.62381504739;9407.576659050359;9416.90583094167;38915.61084389148;39224.607753922384;39256.214875702324;39262.41106383378;39319.41020884689;2791.623130877326;3269.6076470823414;3292.9012129635908;3315.9388995521444;3366.192113892648;28389.076587832955;28869.56695649567;29071.854640726662;29453.410931781244;29555.522223888718;3146.0876345859674;3198.9040328790174;3213.6304325002457;3214.7428205743486;3217.469117595594;612647;615586.9220653913;617786;623012;631748;56300.71849640726;56718.432815671316;57499;57671.13493297604;58051.70974145102;34557.13607159791;63491;63870.36129638737;63911.68044159749;64042;12302.138850280573;15030.774538381933;15086.471973481095;15087.170205638795;15143.318550665246;11031.944840275748;11270.605528806616;11376.317420954769;11444;11493.252938558968;18071.54821129456;18149.909250453664;18734.437966860743;18793.15430805617;18866.622667546573;6427.485801135898;7553.924460755432;7653.961730191313;7696.807579810549;7772.961135194401;844.6621351459465;1014.5586669798661;1018.2312354172552;1018.4672068536953;1052.8789189243182;27221.591676124874;28919;29335.297513586585;29392;29746.85126574353;994.7463396833906;998.5905778630727;1073.7798751255973;1075.2580719303655;1190.3155685480883;14420.06269592494;14656.487022954145;15130.39478420873;15202;15381.846181538265;676894;680681;681765;682466;683598;56080.71959640176;56846;56978.43021569731;57029;57159;1220.1032241302635;1260.5084017233103;1349.1230700045048;1358.5448874627111;1388.1809732258016;28136.859315703292;29096.418071638025;29217.41565168727;29863.95476158324;29977;49166.75416622894;49740;49744.25383619321;49883.002339953724;49969.250461243835;426766;428034;429573;429711;430012;26531.806068726568;26631.733682662925;26697.14058579531;26785.464290713688;26799.598006030315;45893.31160032669;45922.540774593166;47213.763931180125;47507.76246118747;49122;37291;37935.62064379431;38015.239695206496;39104.02239944024;39379.621713241046;37220.81389593035;37581.624183758904;38041.37683614614;38773.80613096916;39022.804885976526;31851.044468665976;32522;32619.34761304808;36223.81888090648;36461.45307820438;27521.449571008867;28605;30551.2362190947;33216.50175247325;34123.829380852934;24771.87614061918;25127.623085654097;25161.87419062893;25625.10312139066;25660.486790264462 18:06:27 INFO - PROCESS | 1638 | |2;jquery.html;363.96906262967644;366.45764268882056;376.5142965574409;384.69609008882986;392.71331927692785;19025;19910.800891991075;20989.790102098974;21056.684149737746;21198;18832.058397080127;18986.335478258265;19273;19386.93371864547;19770.604587908234;3857.7492462989817;5687.17535957286;5946.197263369455;5968.253968253968;6520.9673951630175;6487.18910136233;6541.018847172938;6582;6589.571677840949;6602.339766023408;6237.656928868933;6452.677366131698;6484.124643173182;6495.837604059889;6517.641529715864;5619.381867994558;6451.258105317878;6490;6494.610323380589;6502.317256688027;5701.743421546041;5821.09772985188;5822.184894114785;5829.7376618052285;5830.15462757902;12998.740025199442;13112.344382780822;13156.934215328862;13315.467381304736;13330.800037999437;2116.6613341865227;2152.6017686728005;2229.799318061382;2472.765087316702;2786.5959435881887;9625.90374096257;10355.844662330072;10442.164626829835;14473.710525789425;14711.190884501348;1824.2885274742862;1868.7570615819993;1923.3268356075403;1925.3068895197719;1927.9878064016364;79051.20948790554;91489;99257.50371248098;116910;121175.3941230297;10756.892431075745;11055.351733927784;11363.375014374124;11515.884841151481;12003.339816310012;14377;14480.34838432252;14496;14501.927490362692;14571.781423278448;7998.080220774569;8278.71024514139;8280.668773249003;8365.623546940402;8490.745277641681;7705.499142555716;8080.313173380328;8087.272145506932;8203.05664848538;8230.325144328117;4481.081378317437;4524.977375113169;4527.411436513298;4673.088747694235;4676.929846052312;1862.580919293148;1907.437305994735;1907.9141438635295;1916.338863092231;1939.8933058681907;783.1268136028287;853.8447807374165;855.2986551028097;869.9304055675531;884.9690260840969;20104.798952010584;21175.68236476454;21239.044243009103;21276.468088297916;21435.67846482304;881.713443130985;897.8159477307137;910.6038873090226;912.2473958983865;939.145377706284;10397.896021039693;10691.893081069245;10897.509612067317;10930.398828064373;10944.343339399662;547577;547804;575216;594470;596504;134185.32907335402;136971;137953;139330;140105.299473504;938.5448057692014;947.1664934857281;968.4528241543505;970.4807927758616;971.1405406215409;18287.45137645873;18573;18867.90566047161;18869.71695424597;18877.622447551246;56057.15914261371;57753;57784;58612.41387586069;58793.412065880504;70232;73257;74394;74394.62802685951;81861.59069204616;5781.988152073286;6573.3098024706715;6962.23415424313;7161;7553.357964573072;10718.517666704862;12594.3073130975;12610.810837837624;13037.804432933324;13081.672958176121;10677.558529598715;10852.40311782828;10923.399213043358;10931.83602245983;11237.831432528683;10795.082417994558;10916.454177291262;10935.507902144263;11134.498947547218;11440.141989351;10569.63006294776;10669.03978641926;10828.404437755999;11585.710357241136;11751.588694395656;7401.48189626721;7547.132079810892;7586.886196707164;7596.81007974805;7760.922390776246;9261.629534818532;10515.737106572396;11107.757020908079;11160.609378672032;11288.951982964718 18:06:27 INFO - PROCESS | 1638 | |3;mootools.html;1216.3431746856697;1452.7530319845628;1477.0768269831356;1624.6263359427332;1823.124900047977;7438.256174382559;7505.324520793141;7838.333741631961;8043.155468675785;8291.336693064555;7851.646675899584;8246.91753082469;8260.339172866172;8261.793455163624;8262.380321475883;1991.2632326039386;2301.1715782318392;2332.3119679694446;2333.218371845434;2338.1582630253097;2278.031836469502;2323.7095363079616;2337.415646088478;2347.3427440316736;2348.3894187511287;2284.3832165315357;2298.138198175684;2326.581215381231;2328.8253380996407;2330.0912644068826;2138.2302371146375;2166.945826354338;2189.0696454006984;2196.2752291743686;2200.207925146946;2092.5605622819226;2311.734150572689;2326.162581470669;2339.298210536849;2341.040173528845;8011.078725946532;8357.707480238163;8366.372522060808;8390.454620449713;8395.454295470774;5016.347874776256;5235.136202526579;5242;5254.710990895499;5258.92111618326;3385.6106547747077;3551.94672079919;3572.732045096602;3622.257437225389;3622.3298689742483;1055.7782865598156;1078.3314345106085;1081.1675010242147;1089.5206109311878;1102.2339474065527;10879.891201087967;10951;10991.230613857033;11006.669799906016;11022.944885275603;3998;4258.403823464655;4405.691601587922;4485.102979404067;4492.124035813051;8271.669133234724;8617.913820861837;8635;8639.870401943976;8643.222110010129;5046.470120637354;5053.2420136979745;5055.570276526537;5056.393232812013;5067.4932506749765;4028.335324671455;4242.0455397535925;4390.407295015196;4402.119576084732;4627.95372046282;3661.5423072115987;3661.908452288661;3748.6626203641804;3762.529683789526;3766.8116594170183;1150.7698460307972;1152.5389844062443;1162.9883701163048;1166.9788934682153;1171.3908767441053;794.4200733464603;826.5412695953684;864.8183881384854;874.0385575866496;884.9336299777542;5207.29701490294;5344.866378340573;5385.3268341457315;5433.239346491494;5439.619226654097;418.907840275139;420.4114240063935;423.73940026883247;433.20939285802905;455.92933095370273;3614.4578313252696;3622.3298689742483;3628.3650361186687;3639.7816131032223;3675.687566556819;9720.659776907774;10256.487175641338;10261.384316941007;10264.589416423409;10301.175905927508;7727.65225564851;9927.950360248296;10203.693889183336;10232.53953572091;10465.581376744996;7801.1808760079375;7905.130435652074;7925.524468531907;7928.1675424080795;7935.166807485131;5236.816711415082;5287.259783630372;5384.946150538444;5472.398036216093;5504.174373843955;9093.22707569864;9297.535123243675;9409.858852117086;9428.481433520948;9546.85679714791;8855.203031727175;8999;9408.623655053721;9597.136257736558;9716;6696.296888826603;6775.966120169368;6784.948333008391;6787.898181527183;6803.421709154777;7814.960925195338;7902.288794008567;7986.321162701235;7992.160823113722;8003.679852806055;7050.083489146378;7172.995780590826;7191.137063552407;7192.568445893263;7328.5969271690565;6993.160820701549;7035.366816986496;7085.256048114874;7114.57312561248;7140.6072666003865;6597.142371491867;6683.598984060972;6778.288279730755;6786.626735529593;6790.694418751069;4716.858494245178;4742.288656701522;4762.857114286577;4858.3441235432765;4881.463039065629;4753.405824271966;4778.235482322812;4811.302361157683;4921.064997650435;5044.848654540369 18:06:27 INFO - PROCESS | 1638 | |4;prototype.html;272.2038038736695;280.69756330625887;298.72517284098626;305.8883507519755;312.982785946773;4907.386576677915;4976.4774698656665;4983.202687569989;4985.651004429691;4988.825391111312;4892.339534162887;4921.75391230438;4925.556699897009;4935.703857768536;5074.873128171788;3593.2633810068874;3657.4330978698345;3972.1857019311046;3978.4032395140816;3986.7807270600106;3336;3752.4746535485056;3865.4538184726052;4028.415879697442;4042.4138499917494;3337.5815278506657;3756.924861502768;3921.254961557295;3978.7413818101877;4050.23045621331;3348.392771469684;3704.907377315575;3934.449177115206;3948;3957.3272543667504;2843.971560284391;2940;3010.473167195754;3308.8180150091735;3436.329915666446;4210.642095421893;5064.493550644943;5072.188449848007;5080.720560369178;5105.617078719074;3295.5715756951445;3936.350502167139;4427.557244275579;4454.416438465682;4478.753668548228;4629.04691979582;4700.4594471635855;4832.251001094836;5408.4050754416985;5415.918761218585;3353.480210567366;3735.6637902588895;3735.6824669902817;3751;3788.3520668509013;4279.641374140658;5427.45725427458;5444.482774136451;5465.781368745285;5754.4533269339345;3607.404778211566;3789.2800367930004;3794.2221844521805;3794.4766730124156;3820.4842346283453;3244.902652920416;3860.8841734748003;3865.342891708416;3873.4383514390256;3892.338302488584;4430.5126436092005;4441.911161776747;4487.634274294279;4491.820327186876;4520.480144783326;4597.908041839144;5236.429071278601;5446.2647542582035;5520.889582208333;5541.251930989345;1080.173667144635;1128.6896103571582;1183.8579370475597;1253.1666441816271;1258.2953546014262;717.9497435179592;728.6502478810095;799.0930294116104;852;853.0871966995255;3960.782156981336;4276.700630955803;4816.662833601615;4827.42070951481;4843.294879417249;4276.722013069203;4871.369460491295;5142.945696132284;5262.500062494057;5287.735613219324;4135.379693046007;4363.454568178978;4521.603550118442;4772.856814295507;4793.49668284832;2579.9355016124746;2642.828216165943;2775.9167224983285;2809.4942910275936;2810.9297267568068;4719.206701453426;4978.85486338137;4985.626078044161;5411;5436.755346009439;4441.555844415597;4881.1689220252165;5385.407605163429;5436.537894278951;5443.482869127427;4441.267190913528;5280.9438112377675;5366;5560.304961879765;5628.718564071862;4526.728396296233;5198.428172901054;5683.2895888014;5688.265079150899;5746.396628353963;4206.369044643328;4354.390385346117;4441.37820705108;5228.516454684147;5646.943530564642;5425.294711687456;5665.07387488256;5780.364159942487;5815.5929084963645;5821.621594596338;3559.4126969050853;3791.4123310886857;3797.867074652374;3953.584873588232;3960.801959902051;4407.735535867858;5329.360476742816;5394.002109609734;5605.915911261252;5627.577931655061;4938.16051271292;5045.672031317952;5434.64674796137;5467.781288748405;5517.5585953124455;5029.0773196906475;5465.672059676433;5469.753861076181;5511.944880551143;5515.979543784413;5357.892842143057;5367.060764366299;5377.784888604412;5383.811566595307;5427.430119837518;4399.6920215584605;4531.43357080365;5281.339832520935;5506.439505629741;5518.33779946409;4480.887977800581;5164.535191832753;5329.57363410934;5372.57626728925;5376.650517716336 18:06:27 INFO - PROCESS | 1638 | |5;yui.html;686.4817063117346;736.9889451658224;737.4800765460353;747.2938073520526;751.654239050037;14164.220967846764;14836.703265934675;14853.777193342095;14858.925705371485;14900.329485173166;13030.674233144176;13072.019598530125;13075.673108172301;13186;13189.868101318983;641.3073880209386;655.0305547789275;658.8649326887977;763.3969164360149;790.0598288037231;788.3181048393127;795.308081968688;801.2708435323857;804.8873157757889;804.9597520123999;843.9324854011664;844.252836239929;845.6279237135672;847.7711018025129;850.4302117581235;661.4972620808172;837.0374069819695;838.5052818836915;838.5849004742661;838.6938767349874;511.9913769873344;544.3957207499673;627.6987046217841;631.6462780842743;631.6620607974758;12523.686907827287;12557.686057848627;12557.811632825515;12558.497660093677;12564.937175314066;12868.806967895489;13093.803592946113;13232.933835330858;13269.535566255232;13340.666483337896;11712.355820429873;11937.403129843473;12126.454309556091;12162.148649594532;12341.259524428473;4361.978190109029;4371.3553300253525;4380.693351465398;4395.135057285751;4537.614302784267;79338;83232.75150872742;85949.14050859473;86293.56853215756;88186.11813881843;9741.269404794668;9763.414195148314;9983.650572229937;10069.093781559695;10377.066064054272;19049.523761905784;19085.809141908405;19271.036448177536;19308.03459827031;19376.612467750565;7278.636068196569;7279.180204948762;7304.634768261566;7471.402287816962;7482.663280152297;3588.4258518637416;3987.6211759882763;4020.2160578686876;4085.816138273785;4190.601892820177;3243.2378391078078;3345.514900339437;3349.413852575838;3358.8152651604396;3526.9771766188032;643.6202640442161;643.8229486891142;671.144291028942;675.5575098310615;681.5297444763098;605.9939400605938;643.5173619785161;651.667649498759;652.6214795418646;669.7777645671753;8600.268977137013;8924.910750892408;9029.63881444735;9043.728688139367;9080.636774528944;547.6669388405343;613.2212090644856;638.5019684645983;644.3846126948753;653.2781276689285;4054.553999060101;4188.643965262926;4361.302191649321;4464.866054018385;4503.234450143484;112830;117177.4141129306;117249;119501;122711;66972.33027669661;67098;91293;91338.62992055125;93555.53222233846;79894;82078.7660546136;86276;86501.56749216339;86881.78709277464;8387.287080598218;8476.957615211884;8544.530050847263;8604.655813767378;8639;52610.473895260555;53096.73451632717;53256.467435325154;55171.03460688557;55229;76516.46967060516;79565.20434795578;81492.18507814845;81937;84973.57513212394;10189.643362482277;10373.740656483647;10728.89271107279;10791.244612877023;11149.888501114885;11686.24039437434;11855.940720296343;12068.982744322368;12173.817392738938;12248.081393895316;10746.032857042903;11136.55453781872;11945.730311241185;12105.81841272364;12121.151519393557;11335.659930202108;12174.634760957186;12479.812802807783;12678.619641410773;12679.556215532411;11876.406179690808;11952.282863028246;12162.574309899112;12512.436940337524;12576.74846503083;10425.687229383131;10561.366318020944;10989.61536346224;11028.944855275673;11128.332300062022;10455.66025558295;10528.157747380037;10699.839502407625;10878.020978111692;11112.388818615054 18:06:27 INFO - PROCESS | 1638 | __end_tp_report 18:06:27 INFO - PROCESS | 1638 | __start_cc_report 18:06:27 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,2460 18:06:27 INFO - PROCESS | 1638 | __end_cc_report 18:06:27 INFO - PROCESS | 1638 | __startTimestamp1482285987759__endTimestamp 18:06:27 INFO - PROCESS | 1638 | 18:06:27 INFO - PROCESS | 1638 | ------- Summary: start ------- 18:06:27 INFO - PROCESS | 1638 | Number of tests: 6 18:06:27 INFO - PROCESS | 1638 | 18:06:27 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5715.40 Median:5873.94 stddev:653.29 (11.1%) stddev-sans-first:619.59 18:06:27 INFO - PROCESS | 1638 | Values: 2999.6 3273.2 3329.0 3353.6 3409.0 5676.6 5752.1 5756.9 5772.6 5807.9 5677.0 5745.1 5746.9 5752.4 5770.1 5213.5 5239.6 5240.6 5252.1 5277.3 4996.2 5013.2 5016.5 5312.0 5323.5 5069.9 5089.2 5089.9 5091.7 5161.3 5228.0 5257.7 5268.6 5284.7 5296.6 5225.6 5262.3 5271.0 5291.6 5301.0 5561.9 5642.1 5765.7 5782.9 5858.6 6106.7 6140.6 6145.8 6172.0 6194.5 5273.9 6264.8 6273.4 6304.6 6318.4 5644.7 5768.0 5769.1 5790.1 5795.9 6234.9 6275.9 6312.0 6319.0 6327.1 6163.5 6167.1 6177.4 6178.5 6184.3 6274.0 6282.2 6312.5 6320.6 6321.7 6058.1 6082.2 6118.1 6136.9 6157.1 5215.9 5233.7 6246.3 6247.2 6261.6 4837.5 4848.0 4900.2 4920.5 4941.1 4316.5 4399.5 4408.7 4419.5 4420.3 5679.7 5698.7 5701.1 5705.7 5707.5 6197.1 6200.7 6215.4 6271.5 6274.9 5722.7 5764.2 5765.2 5773.8 5788.2 5424.2 5425.7 5438.6 5439.8 5448.7 6212.5 6281.6 6283.2 6283.2 6331.4 6255.3 6302.0 6332.4 6340.6 6344.9 4877.6 6230.8 6241.3 6314.8 6412.5 6081.8 6129.3 6187.6 6233.4 6245.9 6155.1 6187.3 6240.9 6290.7 6301.8 5966.7 6116.9 6129.3 6137.0 6150.2 5899.0 6100.0 6102.9 6123.5 6160.0 5836.0 5892.4 5895.1 5912.1 5916.9 6025.3 6026.3 6028.0 6035.0 6035.3 5826.3 5863.3 5873.9 5879.1 5879.5 18:06:27 INFO - PROCESS | 1638 | 18:06:27 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:69940.96 Median:39063.41 stddev:157296.68 (402.7%) stddev-sans-first:157651.41 18:06:27 INFO - PROCESS | 1638 | Values: 448.7 491.3 518.1 520.7 557.6 27598.6 31546.4 31674.7 38999.4 39963.0 40998.6 41308.2 41395.6 41422.6 41483.6 9295.3 9446.5 9458.0 9470.1 9470.6 9305.3 9415.6 9417.4 9419.6 9441.9 9366.4 9423.3 9424.0 9435.1 9449.4 9314.1 9361.3 9384.2 9392.5 9396.8 9335.8 9403.7 9404.6 9407.6 9416.9 38915.6 39224.6 39256.2 39262.4 39319.4 2791.6 3269.6 3292.9 3315.9 3366.2 28389.1 28869.6 29071.9 29453.4 29555.5 3146.1 3198.9 3213.6 3214.7 3217.5 612647.0 615586.9 617786.0 623012.0 631748.0 56300.7 56718.4 57499.0 57671.1 58051.7 34557.1 63491.0 63870.4 63911.7 64042.0 12302.1 15030.8 15086.5 15087.2 15143.3 11031.9 11270.6 11376.3 11444.0 11493.3 18071.5 18149.9 18734.4 18793.2 18866.6 6427.5 7553.9 7654.0 7696.8 7773.0 844.7 1014.6 1018.2 1018.5 1052.9 27221.6 28919.0 29335.3 29392.0 29746.9 994.7 998.6 1073.8 1075.3 1190.3 14420.1 14656.5 15130.4 15202.0 15381.8 676894.0 680681.0 681765.0 682466.0 683598.0 56080.7 56846.0 56978.4 57029.0 57159.0 1220.1 1260.5 1349.1 1358.5 1388.2 28136.9 29096.4 29217.4 29864.0 29977.0 49166.8 49740.0 49744.3 49883.0 49969.3 426766.0 428034.0 429573.0 429711.0 430012.0 26531.8 26631.7 26697.1 26785.5 26799.6 45893.3 45922.5 47213.8 47507.8 49122.0 37291.0 37935.6 38015.2 39104.0 39379.6 37220.8 37581.6 38041.4 38773.8 39022.8 31851.0 32522.0 32619.3 36223.8 36461.5 27521.4 28605.0 30551.2 33216.5 34123.8 24771.9 25127.6 25161.9 25625.1 25660.5 18:06:27 INFO - PROCESS | 1638 | 18:06:27 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:33798.34 Median:365.21 stddev:95838.34 (26241.7%) stddev-sans-first:96074.31 18:06:27 INFO - PROCESS | 1638 | Values: 364.0 366.5 376.5 384.7 392.7 19025.0 19910.8 20989.8 21056.7 21198.0 18832.1 18986.3 19273.0 19386.9 19770.6 3857.7 5687.2 5946.2 5968.3 6521.0 6487.2 6541.0 6582.0 6589.6 6602.3 6237.7 6452.7 6484.1 6495.8 6517.6 5619.4 6451.3 6490.0 6494.6 6502.3 5701.7 5821.1 5822.2 5829.7 5830.2 12998.7 13112.3 13156.9 13315.5 13330.8 2116.7 2152.6 2229.8 2472.8 2786.6 9625.9 10355.8 10442.2 14473.7 14711.2 1824.3 1868.8 1923.3 1925.3 1928.0 79051.2 91489.0 99257.5 116910.0 121175.4 10756.9 11055.4 11363.4 11515.9 12003.3 14377.0 14480.3 14496.0 14501.9 14571.8 7998.1 8278.7 8280.7 8365.6 8490.7 7705.5 8080.3 8087.3 8203.1 8230.3 4481.1 4525.0 4527.4 4673.1 4676.9 1862.6 1907.4 1907.9 1916.3 1939.9 783.1 853.8 855.3 869.9 885.0 20104.8 21175.7 21239.0 21276.5 21435.7 881.7 897.8 910.6 912.2 939.1 10397.9 10691.9 10897.5 10930.4 10944.3 547577.0 547804.0 575216.0 594470.0 596504.0 134185.3 136971.0 137953.0 139330.0 140105.3 938.5 947.2 968.5 970.5 971.1 18287.5 18573.0 18867.9 18869.7 18877.6 56057.2 57753.0 57784.0 58612.4 58793.4 70232.0 73257.0 74394.0 74394.6 81861.6 5782.0 6573.3 6962.2 7161.0 7553.4 10718.5 12594.3 12610.8 13037.8 13081.7 10677.6 10852.4 10923.4 10931.8 11237.8 10795.1 10916.5 10935.5 11134.5 11440.1 10569.6 10669.0 10828.4 11585.7 11751.6 7401.5 7547.1 7586.9 7596.8 7760.9 9261.6 10515.7 11107.8 11160.6 11289.0 18:06:27 INFO - PROCESS | 1638 | 18:06:27 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:5350.00 Median:4968.71 stddev:2978.40 (59.9%) stddev-sans-first:2970.55 18:06:27 INFO - PROCESS | 1638 | Values: 1216.3 1452.8 1477.1 1624.6 1823.1 7438.3 7505.3 7838.3 8043.2 8291.3 7851.6 8246.9 8260.3 8261.8 8262.4 1991.3 2301.2 2332.3 2333.2 2338.2 2278.0 2323.7 2337.4 2347.3 2348.4 2284.4 2298.1 2326.6 2328.8 2330.1 2138.2 2166.9 2189.1 2196.3 2200.2 2092.6 2311.7 2326.2 2339.3 2341.0 8011.1 8357.7 8366.4 8390.5 8395.5 5016.3 5235.1 5242.0 5254.7 5258.9 3385.6 3551.9 3572.7 3622.3 3622.3 1055.8 1078.3 1081.2 1089.5 1102.2 10879.9 10951.0 10991.2 11006.7 11022.9 3998.0 4258.4 4405.7 4485.1 4492.1 8271.7 8617.9 8635.0 8639.9 8643.2 5046.5 5053.2 5055.6 5056.4 5067.5 4028.3 4242.0 4390.4 4402.1 4628.0 3661.5 3661.9 3748.7 3762.5 3766.8 1150.8 1152.5 1163.0 1167.0 1171.4 794.4 826.5 864.8 874.0 884.9 5207.3 5344.9 5385.3 5433.2 5439.6 418.9 420.4 423.7 433.2 455.9 3614.5 3622.3 3628.4 3639.8 3675.7 9720.7 10256.5 10261.4 10264.6 10301.2 7727.7 9928.0 10203.7 10232.5 10465.6 7801.2 7905.1 7925.5 7928.2 7935.2 5236.8 5287.3 5384.9 5472.4 5504.2 9093.2 9297.5 9409.9 9428.5 9546.9 8855.2 8999.0 9408.6 9597.1 9716.0 6696.3 6776.0 6784.9 6787.9 6803.4 7815.0 7902.3 7986.3 7992.2 8003.7 7050.1 7173.0 7191.1 7192.6 7328.6 6993.2 7035.4 7085.3 7114.6 7140.6 6597.1 6683.6 6778.3 6786.6 6790.7 4716.9 4742.3 4762.9 4858.3 4881.5 4753.4 4778.2 4811.3 4921.1 5044.8 18:06:27 INFO - PROCESS | 1638 | 18:06:27 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4282.28 Median:4709.83 stddev:1323.76 (28.1%) stddev-sans-first:1292.80 18:06:27 INFO - PROCESS | 1638 | Values: 272.2 280.7 298.7 305.9 313.0 4907.4 4976.5 4983.2 4985.7 4988.8 4892.3 4921.8 4925.6 4935.7 5074.9 3593.3 3657.4 3972.2 3978.4 3986.8 3336.0 3752.5 3865.5 4028.4 4042.4 3337.6 3756.9 3921.3 3978.7 4050.2 3348.4 3704.9 3934.4 3948.0 3957.3 2844.0 2940.0 3010.5 3308.8 3436.3 4210.6 5064.5 5072.2 5080.7 5105.6 3295.6 3936.4 4427.6 4454.4 4478.8 4629.0 4700.5 4832.3 5408.4 5415.9 3353.5 3735.7 3735.7 3751.0 3788.4 4279.6 5427.5 5444.5 5465.8 5754.5 3607.4 3789.3 3794.2 3794.5 3820.5 3244.9 3860.9 3865.3 3873.4 3892.3 4430.5 4441.9 4487.6 4491.8 4520.5 4597.9 5236.4 5446.3 5520.9 5541.3 1080.2 1128.7 1183.9 1253.2 1258.3 717.9 728.7 799.1 852.0 853.1 3960.8 4276.7 4816.7 4827.4 4843.3 4276.7 4871.4 5142.9 5262.5 5287.7 4135.4 4363.5 4521.6 4772.9 4793.5 2579.9 2642.8 2775.9 2809.5 2810.9 4719.2 4978.9 4985.6 5411.0 5436.8 4441.6 4881.2 5385.4 5436.5 5443.5 4441.3 5280.9 5366.0 5560.3 5628.7 4526.7 5198.4 5683.3 5688.3 5746.4 4206.4 4354.4 4441.4 5228.5 5646.9 5425.3 5665.1 5780.4 5815.6 5821.6 3559.4 3791.4 3797.9 3953.6 3960.8 4407.7 5329.4 5394.0 5605.9 5627.6 4938.2 5045.7 5434.6 5467.8 5517.6 5029.1 5465.7 5469.8 5511.9 5516.0 5357.9 5367.1 5377.8 5383.8 5427.4 4399.7 4531.4 5281.3 5506.4 5518.3 4480.9 5164.5 5329.6 5372.6 5376.7 18:06:27 INFO - PROCESS | 1638 | 18:06:27 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:20168.13 Median:4520.42 stddev:30096.28 (665.8%) stddev-sans-first:30145.16 18:06:27 INFO - PROCESS | 1638 | Values: 686.5 737.0 737.5 747.3 751.7 14164.2 14836.7 14853.8 14858.9 14900.3 13030.7 13072.0 13075.7 13186.0 13189.9 641.3 655.0 658.9 763.4 790.1 788.3 795.3 801.3 804.9 805.0 843.9 844.3 845.6 847.8 850.4 661.5 837.0 838.5 838.6 838.7 512.0 544.4 627.7 631.6 631.7 12523.7 12557.7 12557.8 12558.5 12564.9 12868.8 13093.8 13232.9 13269.5 13340.7 11712.4 11937.4 12126.5 12162.1 12341.3 4362.0 4371.4 4380.7 4395.1 4537.6 79338.0 83232.8 85949.1 86293.6 88186.1 9741.3 9763.4 9983.7 10069.1 10377.1 19049.5 19085.8 19271.0 19308.0 19376.6 7278.6 7279.2 7304.6 7471.4 7482.7 3588.4 3987.6 4020.2 4085.8 4190.6 3243.2 3345.5 3349.4 3358.8 3527.0 643.6 643.8 671.1 675.6 681.5 606.0 643.5 651.7 652.6 669.8 8600.3 8924.9 9029.6 9043.7 9080.6 547.7 613.2 638.5 644.4 653.3 4054.6 4188.6 4361.3 4464.9 4503.2 112830.0 117177.4 117249.0 119501.0 122711.0 66972.3 67098.0 91293.0 91338.6 93555.5 79894.0 82078.8 86276.0 86501.6 86881.8 8387.3 8477.0 8544.5 8604.7 8639.0 52610.5 53096.7 53256.5 55171.0 55229.0 76516.5 79565.2 81492.2 81937.0 84973.6 10189.6 10373.7 10728.9 10791.2 11149.9 11686.2 11855.9 12069.0 12173.8 12248.1 10746.0 11136.6 11945.7 12105.8 12121.2 11335.7 12174.6 12479.8 12678.6 12679.6 11876.4 11952.3 12162.6 12512.4 12576.7 10425.7 10561.4 10989.6 11028.9 11128.3 10455.7 10528.2 10699.8 10878.0 11112.4 18:06:27 INFO - PROCESS | 1638 | -------- Summary: end -------- 18:06:27 INFO - PROCESS | 1638 | 18:06:28 INFO - TEST-INFO | 1638: exit 0 18:06:28 INFO - TEST-OK | dromaeo_css | took 1120549ms 18:06:28 INFO - TEST-START | kraken 18:06:28 INFO - operating with platform_type : mac_ 18:06:28 INFO - Initialising browser for kraken test... 18:06:28 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/tmp8LhR5d/profile http://localhost:49241/getInfo.html) 18:06:30 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 18:06:30 INFO - PROCESS | 1650 | colorDepth:24 18:06:30 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 18:06:30 INFO - PROCESS | 1650 | __metrics 18:06:31 INFO - PROCESS | 1650 | 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" 18:06:31 INFO - PROCESS | 1650 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:06:31 INFO - TEST-INFO | 1650: exit 0 18:06:31 INFO - Browser initialized. 18:06:31 INFO - Running cycle 1/1 for kraken test... 18:06:31 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/tmp8LhR5d/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:06:44 INFO - PROCESS | 1651 | RSS: Main: 222670848 18:06:44 INFO - PROCESS | 1651 | 18:06:46 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 18:06:47 INFO - PROCESS | 1651 | RSS: Main: 271839232 18:06:47 INFO - PROCESS | 1651 | 18:06:50 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 18:06:51 INFO - PROCESS | 1651 | RSS: Main: 282517504 18:06:51 INFO - PROCESS | 1651 | 18:06:54 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 18:06:54 INFO - PROCESS | 1651 | RSS: Main: 381263872 18:06:54 INFO - PROCESS | 1651 | 18:06:56 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 18:06:57 INFO - PROCESS | 1651 | RSS: Main: 282079232 18:06:57 INFO - PROCESS | 1651 | 18:06:59 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:07:00 INFO - PROCESS | 1651 | RSS: Main: 283000832 18:07:00 INFO - PROCESS | 1651 | 18:07:02 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 18:07:03 INFO - PROCESS | 1651 | RSS: Main: 325734400 18:07:03 INFO - PROCESS | 1651 | 18:07:06 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 18:07:06 INFO - PROCESS | 1651 | RSS: Main: 326635520 18:07:06 INFO - PROCESS | 1651 | 18:07:09 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 18:07:10 INFO - PROCESS | 1651 | RSS: Main: 323485696 18:07:10 INFO - PROCESS | 1651 | 18:07:11 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:07:12 INFO - PROCESS | 1651 | RSS: Main: 300412928 18:07:12 INFO - PROCESS | 1651 | 18:07:14 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:07:15 INFO - PROCESS | 1651 | RSS: Main: 299741184 18:07:15 INFO - PROCESS | 1651 | 18:07:17 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:07:17 INFO - PROCESS | 1651 | RSS: Main: 303296512 18:07:17 INFO - PROCESS | 1651 | 18:07:20 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:07:21 INFO - PROCESS | 1651 | RSS: Main: 319631360 18:07:21 INFO - PROCESS | 1651 | 18:07:24 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:07:25 INFO - PROCESS | 1651 | RSS: Main: 313593856 18:07:25 INFO - PROCESS | 1651 | 18:07:26 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 18:07:27 INFO - PROCESS | 1651 | RSS: Main: 358002688 18:07:27 INFO - PROCESS | 1651 | 18:07:27 INFO - PROCESS | 1651 | __start_tp_report 18:07:27 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 18:07:27 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 18:07:27 INFO - PROCESS | 1651 | |i|pagename|runs| 18:07:27 INFO - PROCESS | 1651 | |0;ai-astar;115;114;113;115;114;115;115;114;113;113 18:07:27 INFO - PROCESS | 1651 | |1;audio-beat-detection;146;110;110;143;122;138;132;136;175;110 18:07:27 INFO - PROCESS | 1651 | |2;audio-dft;184;185;184;185;185;184;184;181;185;185 18:07:27 INFO - PROCESS | 1651 | |3;audio-fft;80;59;59;86;64;78;81;62;83;58 18:07:27 INFO - PROCESS | 1651 | |4;audio-oscillator;90;70;94;70;68;68;89;67;68;92 18:07:27 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;88;87;89;85;94;84;88;85;87;85 18:07:27 INFO - PROCESS | 1651 | |6;imaging-darkroom;93;93;94;93;93;93;94;93;94;94 18:07:27 INFO - PROCESS | 1651 | |7;imaging-desaturate;82;81;88;113;81;83;85;83;81;81 18:07:27 INFO - PROCESS | 1651 | |8;json-parse-financial;53;53;53;53;57;57;57;56;57;57 18:07:27 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;72;70;65;83;76;72;75;103;102;90 18:07:27 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;84;85;91;86;86;83;83;84;83;85 18:07:27 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;148;141;141;140;143;142;154;150;215;140 18:07:27 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;164;165;167;164;168;170;164;167;166;162 18:07:27 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;67;68;68;68;68;68;68;68;67;68 18:07:27 INFO - PROCESS | 1651 | __end_tp_report 18:07:27 INFO - PROCESS | 1651 | __start_cc_report 18:07:27 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,1984 18:07:27 INFO - PROCESS | 1651 | __end_cc_report 18:07:27 INFO - PROCESS | 1651 | __startTimestamp1482286047001__endTimestamp 18:07:27 INFO - PROCESS | 1651 | 18:07:27 INFO - PROCESS | 1651 | ------- Summary: start ------- 18:07:27 INFO - PROCESS | 1651 | Number of tests: 14 18:07:27 INFO - PROCESS | 1651 | 18:07:27 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:114.10 Median:114.50 stddev:0.88 (0.8%) stddev-sans-first:0.87 18:07:27 INFO - PROCESS | 1651 | Values: 115.0 114.0 113.0 115.0 114.0 115.0 115.0 114.0 113.0 113.0 18:07:27 INFO - PROCESS | 1651 | 18:07:27 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:132.20 Median:137.00 stddev:20.47 (14.9%) stddev-sans-first:21.09 18:07:27 INFO - PROCESS | 1651 | Values: 146.0 110.0 110.0 143.0 122.0 138.0 132.0 136.0 175.0 110.0 18:07:27 INFO - PROCESS | 1651 | 18:07:27 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:184.20 Median:185.00 stddev:1.23 (0.7%) stddev-sans-first:1.30 18:07:27 INFO - PROCESS | 1651 | Values: 184.0 185.0 184.0 185.0 185.0 184.0 184.0 181.0 185.0 185.0 18:07:27 INFO - PROCESS | 1651 | 18:07:27 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:71.00 Median:79.00 stddev:11.48 (14.5%) stddev-sans-first:11.70 18:07:27 INFO - PROCESS | 1651 | Values: 80.0 59.0 59.0 86.0 64.0 78.0 81.0 62.0 83.0 58.0 18:07:27 INFO - PROCESS | 1651 | 18:07:27 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:79.50 stddev:11.85 (14.9%) stddev-sans-first:11.69 18:07:27 INFO - PROCESS | 1651 | Values: 90.0 70.0 94.0 70.0 68.0 68.0 89.0 67.0 68.0 92.0 18:07:27 INFO - PROCESS | 1651 | 18:07:27 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:87.20 Median:87.50 stddev:2.90 (3.3%) stddev-sans-first:3.06 18:07:27 INFO - PROCESS | 1651 | Values: 88.0 87.0 89.0 85.0 94.0 84.0 88.0 85.0 87.0 85.0 18:07:27 INFO - PROCESS | 1651 | 18:07:27 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:93.40 Median:93.50 stddev:0.52 (0.6%) stddev-sans-first:0.53 18:07:27 INFO - PROCESS | 1651 | Values: 93.0 93.0 94.0 93.0 93.0 93.0 94.0 93.0 94.0 94.0 18:07:27 INFO - PROCESS | 1651 | 18:07:27 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:85.80 Median:82.50 stddev:9.82 (11.9%) stddev-sans-first:10.32 18:07:27 INFO - PROCESS | 1651 | Values: 82.0 81.0 88.0 113.0 81.0 83.0 85.0 83.0 81.0 81.0 18:07:27 INFO - PROCESS | 1651 | 18:07:27 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:57.00 stddev:2.00 (3.5%) stddev-sans-first:1.94 18:07:27 INFO - PROCESS | 1651 | Values: 53.0 53.0 53.0 53.0 57.0 57.0 57.0 56.0 57.0 57.0 18:07:27 INFO - PROCESS | 1651 | 18:07:27 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:80.80 Median:73.50 stddev:13.37 (18.2%) stddev-sans-first:13.80 18:07:27 INFO - PROCESS | 1651 | Values: 72.0 70.0 65.0 83.0 76.0 72.0 75.0 103.0 102.0 90.0 18:07:27 INFO - PROCESS | 1651 | 18:07:27 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:85.00 Median:85.00 stddev:2.40 (2.8%) stddev-sans-first:2.52 18:07:27 INFO - PROCESS | 1651 | Values: 84.0 85.0 91.0 86.0 86.0 83.0 83.0 84.0 83.0 85.0 18:07:27 INFO - PROCESS | 1651 | 18:07:27 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:151.40 Median:145.50 stddev:22.85 (15.7%) stddev-sans-first:24.21 18:07:27 INFO - PROCESS | 1651 | Values: 148.0 141.0 141.0 140.0 143.0 142.0 154.0 150.0 215.0 140.0 18:07:27 INFO - PROCESS | 1651 | 18:07:27 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.70 Median:166.50 stddev:2.36 (1.4%) stddev-sans-first:2.42 18:07:27 INFO - PROCESS | 1651 | Values: 164.0 165.0 167.0 164.0 168.0 170.0 164.0 167.0 166.0 162.0 18:07:27 INFO - PROCESS | 1651 | 18:07:27 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:68.00 stddev:0.42 (0.6%) stddev-sans-first:0.33 18:07:27 INFO - PROCESS | 1651 | Values: 67.0 68.0 68.0 68.0 68.0 68.0 68.0 68.0 67.0 68.0 18:07:27 INFO - PROCESS | 1651 | -------- Summary: end -------- 18:07:27 INFO - PROCESS | 1651 | 18:07:28 INFO - TEST-INFO | 1651: exit 0 18:07:28 INFO - TEST-OK | kraken | took 59663ms 18:07:28 INFO - SUITE-END | took 1180s 18:07:28 INFO - Completed test suite (00:19:40) 18:07:28 INFO - javascript benchmark 18:07:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2999.640043194817, 3273.247153154773, 3328.9667103328966, 3353.6311005789366, 3408.977306807957, 5676.574256930731, 5752.108423194401, 5756.913646295298, 5772.567057470691, 5807.854803629912, 5676.971615141929, 5745.138229265613, 5746.885062298751, 5752.424757524245, 5770.076787713967, 5213.530782229599, 5239.580833533318, 5240.580753539727, 5252.107141785906, 5277.340332458442, 4996.225585034307, 5013.248012798073, 5016.548510634042, 5312.0, 5323.494268044529, 5069.898602027957, 5089.185730283156, 5089.923651145235, 5091.719955402451, 5161.30322406476, 5228.0, 5257.658252213613, 5268.578513718893, 5284.709340986245, 5296.576273898079, 5225.608079394061, 5262.315898933152, 5271.024860400838, 5291.576673866081, 5300.973495132538, 5561.860953476155, 5642.125470552071, 5765.682887441189, 5782.855428614276, 5858.648481091105, 6106.710922515801, 6140.60946423305, 6145.754169833202, 6171.969140154315, 6194.473469755077, 5273.920891186635, 6264.780732674334, 6273.435390814803, 6304.621722696607, 6318.368163183645, 5644.689542075143, 5768.0, 5769.07694768835, 5790.073588225863, 5795.913061304083, 6234.906476402857, 6275.905861411991, 6312.0, 6319.020551814476, 6327.082573026885, 6163.537734669918, 6167.105769663373, 6177.444030037318, 6178.505719542426, 6184.319724830265, 6274.0, 6282.246130464282, 6312.495000399957, 6320.62076275425, 6321.683915804283, 6058.0610005449225, 6082.239720034996, 6118.082287656798, 6136.8772624547255, 6157.138000679908, 5215.921761173586, 5233.738313084331, 6246.312905580383, 6247.156633854372, 6261.5616906816085, 4837.516248375135, 4847.957689096861, 4900.240462728283, 4920.507949205051, 4941.135301322254, 4316.525182229952, 4399.494058183286, 4408.713433626804, 4419.513853476115, 4420.270655341833, 5679.716014199274, 5698.715064246771, 5701.144828275792, 5705.657660540381, 5707.486326230659, 6197.101420294032, 6200.658963756947, 6215.378462153839, 6271.529635277372, 6274.874502509924, 5722.7424765885635, 5764.193012978187, 5765.1928729977835, 5773.797917072882, 5788.160716696056, 5424.159255315432, 5425.674459532362, 5438.592105592096, 5439.809606663827, 5448.673079615157, 6212.534059945432, 6281.560290779785, 6283.151774510383, 6283.2460104787715, 6331.398517140957, 6255.280642726145, 6302.023186406114, 6332.366763323631, 6340.556161068866, 6344.936550634435, 4877.609791216623, 6230.7819226326865, 6241.344658810759, 6314.778982735582, 6412.487001039813, 6081.787137450168, 6129.295131059986, 6187.588980815032, 6233.376662333731, 6245.875082498415, 6155.076738489262, 6187.257529096537, 6240.937590624218, 6290.748370065329, 6301.842453938688, 5966.671833049224, 6116.8776624468155, 6129.264488261437, 6137.018077107641, 6150.200473938538, 5898.970505147447, 6100.0, 6102.9257733398435, 6123.526657168115, 6159.969200153971, 5836.0, 5892.35184129754, 5895.086261629454, 5912.054071348563, 5916.940830591811, 6025.276966764016, 6026.337102918763, 6028.005379112397, 6035.0, 6035.336113027652, 5826.329972053268, 5863.325717542367, 5873.9412605873395, 5879.088741245114, 5879.5002424794375], "value": 5673.126854704032, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [448.65901914544946, 491.34160225298115, 518.0648928683725, 520.700597156635, 557.6431084106173, 27598.586021209674, 31546.369072618563, 31674.68325316749, 38999.41500877489, 39963.0, 40998.59001409985, 41308.17383652325, 41395.58604413955, 41422.58577414225, 41483.585164148346, 9295.349325547231, 9446.480443575601, 9457.952710236439, 9470.10034046768, 9470.621175152986, 9305.34862559621, 9415.62337506499, 9417.434953902753, 9419.62321507139, 9441.90558094417, 9366.438013719164, 9423.293253006052, 9424.01047889972, 9435.056494350545, 9449.385789923634, 9314.115159059878, 9361.344705870591, 9384.20234280087, 9392.530373481299, 9396.812063758754, 9335.813283734355, 9403.717888463358, 9404.62381504739, 9407.576659050359, 9416.90583094167, 38915.61084389148, 39224.607753922384, 39256.214875702324, 39262.41106383378, 39319.41020884689, 2791.623130877326, 3269.6076470823414, 3292.9012129635908, 3315.9388995521444, 3366.192113892648, 28389.076587832955, 28869.56695649567, 29071.854640726662, 29453.410931781244, 29555.522223888718, 3146.0876345859674, 3198.9040328790174, 3213.6304325002457, 3214.7428205743486, 3217.469117595594, 612647.0, 615586.9220653913, 617786.0, 623012.0, 631748.0, 56300.71849640726, 56718.432815671316, 57499.0, 57671.13493297604, 58051.70974145102, 34557.13607159791, 63491.0, 63870.36129638737, 63911.68044159749, 64042.0, 12302.138850280573, 15030.774538381933, 15086.471973481095, 15087.170205638795, 15143.318550665246, 11031.944840275748, 11270.605528806616, 11376.317420954769, 11444.0, 11493.252938558968, 18071.54821129456, 18149.909250453664, 18734.437966860743, 18793.15430805617, 18866.622667546573, 6427.485801135898, 7553.924460755432, 7653.961730191313, 7696.807579810549, 7772.961135194401, 844.6621351459465, 1014.5586669798661, 1018.2312354172552, 1018.4672068536953, 1052.8789189243182, 27221.591676124874, 28919.0, 29335.297513586585, 29392.0, 29746.85126574353, 994.7463396833906, 998.5905778630727, 1073.7798751255973, 1075.2580719303655, 1190.3155685480883, 14420.06269592494, 14656.487022954145, 15130.39478420873, 15202.0, 15381.846181538265, 676894.0, 680681.0, 681765.0, 682466.0, 683598.0, 56080.71959640176, 56846.0, 56978.43021569731, 57029.0, 57159.0, 1220.1032241302635, 1260.5084017233103, 1349.1230700045048, 1358.5448874627111, 1388.1809732258016, 28136.859315703292, 29096.418071638025, 29217.41565168727, 29863.95476158324, 29977.0, 49166.75416622894, 49740.0, 49744.25383619321, 49883.002339953724, 49969.250461243835, 426766.0, 428034.0, 429573.0, 429711.0, 430012.0, 26531.806068726568, 26631.733682662925, 26697.14058579531, 26785.464290713688, 26799.598006030315, 45893.31160032669, 45922.540774593166, 47213.763931180125, 47507.76246118747, 49122.0, 37291.0, 37935.62064379431, 38015.239695206496, 39104.02239944024, 39379.621713241046, 37220.81389593035, 37581.624183758904, 38041.37683614614, 38773.80613096916, 39022.804885976526, 31851.044468665976, 32522.0, 32619.34761304808, 36223.81888090648, 36461.45307820438, 27521.449571008867, 28605.0, 30551.2362190947, 33216.50175247325, 34123.829380852934, 24771.87614061918, 25127.623085654097, 25161.87419062893, 25625.10312139066, 25660.486790264462], "value": 19329.43526644732, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.96906262967644, 366.45764268882056, 376.5142965574409, 384.69609008882986, 392.71331927692785, 19025.0, 19910.800891991075, 20989.790102098974, 21056.684149737746, 21198.0, 18832.058397080127, 18986.335478258265, 19273.0, 19386.93371864547, 19770.604587908234, 3857.7492462989817, 5687.17535957286, 5946.197263369455, 5968.253968253968, 6520.9673951630175, 6487.18910136233, 6541.018847172938, 6582.0, 6589.571677840949, 6602.339766023408, 6237.656928868933, 6452.677366131698, 6484.124643173182, 6495.837604059889, 6517.641529715864, 5619.381867994558, 6451.258105317878, 6490.0, 6494.610323380589, 6502.317256688027, 5701.743421546041, 5821.09772985188, 5822.184894114785, 5829.7376618052285, 5830.15462757902, 12998.740025199442, 13112.344382780822, 13156.934215328862, 13315.467381304736, 13330.800037999437, 2116.6613341865227, 2152.6017686728005, 2229.799318061382, 2472.765087316702, 2786.5959435881887, 9625.90374096257, 10355.844662330072, 10442.164626829835, 14473.710525789425, 14711.190884501348, 1824.2885274742862, 1868.7570615819993, 1923.3268356075403, 1925.3068895197719, 1927.9878064016364, 79051.20948790554, 91489.0, 99257.50371248098, 116910.0, 121175.3941230297, 10756.892431075745, 11055.351733927784, 11363.375014374124, 11515.884841151481, 12003.339816310012, 14377.0, 14480.34838432252, 14496.0, 14501.927490362692, 14571.781423278448, 7998.080220774569, 8278.71024514139, 8280.668773249003, 8365.623546940402, 8490.745277641681, 7705.499142555716, 8080.313173380328, 8087.272145506932, 8203.05664848538, 8230.325144328117, 4481.081378317437, 4524.977375113169, 4527.411436513298, 4673.088747694235, 4676.929846052312, 1862.580919293148, 1907.437305994735, 1907.9141438635295, 1916.338863092231, 1939.8933058681907, 783.1268136028287, 853.8447807374165, 855.2986551028097, 869.9304055675531, 884.9690260840969, 20104.798952010584, 21175.68236476454, 21239.044243009103, 21276.468088297916, 21435.67846482304, 881.713443130985, 897.8159477307137, 910.6038873090226, 912.2473958983865, 939.145377706284, 10397.896021039693, 10691.893081069245, 10897.509612067317, 10930.398828064373, 10944.343339399662, 547577.0, 547804.0, 575216.0, 594470.0, 596504.0, 134185.32907335402, 136971.0, 137953.0, 139330.0, 140105.299473504, 938.5448057692014, 947.1664934857281, 968.4528241543505, 970.4807927758616, 971.1405406215409, 18287.45137645873, 18573.0, 18867.90566047161, 18869.71695424597, 18877.622447551246, 56057.15914261371, 57753.0, 57784.0, 58612.41387586069, 58793.412065880504, 70232.0, 73257.0, 74394.0, 74394.62802685951, 81861.59069204616, 5781.988152073286, 6573.3098024706715, 6962.23415424313, 7161.0, 7553.357964573072, 10718.517666704862, 12594.3073130975, 12610.810837837624, 13037.804432933324, 13081.672958176121, 10677.558529598715, 10852.40311782828, 10923.399213043358, 10931.83602245983, 11237.831432528683, 10795.082417994558, 10916.454177291262, 10935.507902144263, 11134.498947547218, 11440.141989351, 10569.63006294776, 10669.03978641926, 10828.404437755999, 11585.710357241136, 11751.588694395656, 7401.48189626721, 7547.132079810892, 7586.886196707164, 7596.81007974805, 7760.922390776246, 9261.629534818532, 10515.737106572396, 11107.757020908079, 11160.609378672032, 11288.951982964718], "value": 9340.419941554843, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1216.3431746856697, 1452.7530319845628, 1477.0768269831356, 1624.6263359427332, 1823.124900047977, 7438.256174382559, 7505.324520793141, 7838.333741631961, 8043.155468675785, 8291.336693064555, 7851.646675899584, 8246.91753082469, 8260.339172866172, 8261.793455163624, 8262.380321475883, 1991.2632326039386, 2301.1715782318392, 2332.3119679694446, 2333.218371845434, 2338.1582630253097, 2278.031836469502, 2323.7095363079616, 2337.415646088478, 2347.3427440316736, 2348.3894187511287, 2284.3832165315357, 2298.138198175684, 2326.581215381231, 2328.8253380996407, 2330.0912644068826, 2138.2302371146375, 2166.945826354338, 2189.0696454006984, 2196.2752291743686, 2200.207925146946, 2092.5605622819226, 2311.734150572689, 2326.162581470669, 2339.298210536849, 2341.040173528845, 8011.078725946532, 8357.707480238163, 8366.372522060808, 8390.454620449713, 8395.454295470774, 5016.347874776256, 5235.136202526579, 5242.0, 5254.710990895499, 5258.92111618326, 3385.6106547747077, 3551.94672079919, 3572.732045096602, 3622.257437225389, 3622.3298689742483, 1055.7782865598156, 1078.3314345106085, 1081.1675010242147, 1089.5206109311878, 1102.2339474065527, 10879.891201087967, 10951.0, 10991.230613857033, 11006.669799906016, 11022.944885275603, 3998.0, 4258.403823464655, 4405.691601587922, 4485.102979404067, 4492.124035813051, 8271.669133234724, 8617.913820861837, 8635.0, 8639.870401943976, 8643.222110010129, 5046.470120637354, 5053.2420136979745, 5055.570276526537, 5056.393232812013, 5067.4932506749765, 4028.335324671455, 4242.0455397535925, 4390.407295015196, 4402.119576084732, 4627.95372046282, 3661.5423072115987, 3661.908452288661, 3748.6626203641804, 3762.529683789526, 3766.8116594170183, 1150.7698460307972, 1152.5389844062443, 1162.9883701163048, 1166.9788934682153, 1171.3908767441053, 794.4200733464603, 826.5412695953684, 864.8183881384854, 874.0385575866496, 884.9336299777542, 5207.29701490294, 5344.866378340573, 5385.3268341457315, 5433.239346491494, 5439.619226654097, 418.907840275139, 420.4114240063935, 423.73940026883247, 433.20939285802905, 455.92933095370273, 3614.4578313252696, 3622.3298689742483, 3628.3650361186687, 3639.7816131032223, 3675.687566556819, 9720.659776907774, 10256.487175641338, 10261.384316941007, 10264.589416423409, 10301.175905927508, 7727.65225564851, 9927.950360248296, 10203.693889183336, 10232.53953572091, 10465.581376744996, 7801.1808760079375, 7905.130435652074, 7925.524468531907, 7928.1675424080795, 7935.166807485131, 5236.816711415082, 5287.259783630372, 5384.946150538444, 5472.398036216093, 5504.174373843955, 9093.22707569864, 9297.535123243675, 9409.858852117086, 9428.481433520948, 9546.85679714791, 8855.203031727175, 8999.0, 9408.623655053721, 9597.136257736558, 9716.0, 6696.296888826603, 6775.966120169368, 6784.948333008391, 6787.898181527183, 6803.421709154777, 7814.960925195338, 7902.288794008567, 7986.321162701235, 7992.160823113722, 8003.679852806055, 7050.083489146378, 7172.995780590826, 7191.137063552407, 7192.568445893263, 7328.5969271690565, 6993.160820701549, 7035.366816986496, 7085.256048114874, 7114.57312561248, 7140.6072666003865, 6597.142371491867, 6683.598984060972, 6778.288279730755, 6786.626735529593, 6790.694418751069, 4716.858494245178, 4742.288656701522, 4762.857114286577, 4858.3441235432765, 4881.463039065629, 4753.405824271966, 4778.235482322812, 4811.302361157683, 4921.064997650435, 5044.848654540369], "value": 4250.2878731481915, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [272.2038038736695, 280.69756330625887, 298.72517284098626, 305.8883507519755, 312.982785946773, 4907.386576677915, 4976.4774698656665, 4983.202687569989, 4985.651004429691, 4988.825391111312, 4892.339534162887, 4921.75391230438, 4925.556699897009, 4935.703857768536, 5074.873128171788, 3593.2633810068874, 3657.4330978698345, 3972.1857019311046, 3978.4032395140816, 3986.7807270600106, 3336.0, 3752.4746535485056, 3865.4538184726052, 4028.415879697442, 4042.4138499917494, 3337.5815278506657, 3756.924861502768, 3921.254961557295, 3978.7413818101877, 4050.23045621331, 3348.392771469684, 3704.907377315575, 3934.449177115206, 3948.0, 3957.3272543667504, 2843.971560284391, 2940.0, 3010.473167195754, 3308.8180150091735, 3436.329915666446, 4210.642095421893, 5064.493550644943, 5072.188449848007, 5080.720560369178, 5105.617078719074, 3295.5715756951445, 3936.350502167139, 4427.557244275579, 4454.416438465682, 4478.753668548228, 4629.04691979582, 4700.4594471635855, 4832.251001094836, 5408.4050754416985, 5415.918761218585, 3353.480210567366, 3735.6637902588895, 3735.6824669902817, 3751.0, 3788.3520668509013, 4279.641374140658, 5427.45725427458, 5444.482774136451, 5465.781368745285, 5754.4533269339345, 3607.404778211566, 3789.2800367930004, 3794.2221844521805, 3794.4766730124156, 3820.4842346283453, 3244.902652920416, 3860.8841734748003, 3865.342891708416, 3873.4383514390256, 3892.338302488584, 4430.5126436092005, 4441.911161776747, 4487.634274294279, 4491.820327186876, 4520.480144783326, 4597.908041839144, 5236.429071278601, 5446.2647542582035, 5520.889582208333, 5541.251930989345, 1080.173667144635, 1128.6896103571582, 1183.8579370475597, 1253.1666441816271, 1258.2953546014262, 717.9497435179592, 728.6502478810095, 799.0930294116104, 852.0, 853.0871966995255, 3960.782156981336, 4276.700630955803, 4816.662833601615, 4827.42070951481, 4843.294879417249, 4276.722013069203, 4871.369460491295, 5142.945696132284, 5262.500062494057, 5287.735613219324, 4135.379693046007, 4363.454568178978, 4521.603550118442, 4772.856814295507, 4793.49668284832, 2579.9355016124746, 2642.828216165943, 2775.9167224983285, 2809.4942910275936, 2810.9297267568068, 4719.206701453426, 4978.85486338137, 4985.626078044161, 5411.0, 5436.755346009439, 4441.555844415597, 4881.1689220252165, 5385.407605163429, 5436.537894278951, 5443.482869127427, 4441.267190913528, 5280.9438112377675, 5366.0, 5560.304961879765, 5628.718564071862, 4526.728396296233, 5198.428172901054, 5683.2895888014, 5688.265079150899, 5746.396628353963, 4206.369044643328, 4354.390385346117, 4441.37820705108, 5228.516454684147, 5646.943530564642, 5425.294711687456, 5665.07387488256, 5780.364159942487, 5815.5929084963645, 5821.621594596338, 3559.4126969050853, 3791.4123310886857, 3797.867074652374, 3953.584873588232, 3960.801959902051, 4407.735535867858, 5329.360476742816, 5394.002109609734, 5605.915911261252, 5627.577931655061, 4938.16051271292, 5045.672031317952, 5434.64674796137, 5467.781288748405, 5517.5585953124455, 5029.0773196906475, 5465.672059676433, 5469.753861076181, 5511.944880551143, 5515.979543784413, 5357.892842143057, 5367.060764366299, 5377.784888604412, 5383.811566595307, 5427.430119837518, 4399.6920215584605, 4531.43357080365, 5281.339832520935, 5506.439505629741, 5518.33779946409, 4480.887977800581, 5164.535191832753, 5329.57363410934, 5372.57626728925, 5376.650517716336], "value": 3857.955153384536, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [686.4817063117346, 736.9889451658224, 737.4800765460353, 747.2938073520526, 751.654239050037, 14164.220967846764, 14836.703265934675, 14853.777193342095, 14858.925705371485, 14900.329485173166, 13030.674233144176, 13072.019598530125, 13075.673108172301, 13186.0, 13189.868101318983, 641.3073880209386, 655.0305547789275, 658.8649326887977, 763.3969164360149, 790.0598288037231, 788.3181048393127, 795.308081968688, 801.2708435323857, 804.8873157757889, 804.9597520123999, 843.9324854011664, 844.252836239929, 845.6279237135672, 847.7711018025129, 850.4302117581235, 661.4972620808172, 837.0374069819695, 838.5052818836915, 838.5849004742661, 838.6938767349874, 511.9913769873344, 544.3957207499673, 627.6987046217841, 631.6462780842743, 631.6620607974758, 12523.686907827287, 12557.686057848627, 12557.811632825515, 12558.497660093677, 12564.937175314066, 12868.806967895489, 13093.803592946113, 13232.933835330858, 13269.535566255232, 13340.666483337896, 11712.355820429873, 11937.403129843473, 12126.454309556091, 12162.148649594532, 12341.259524428473, 4361.978190109029, 4371.3553300253525, 4380.693351465398, 4395.135057285751, 4537.614302784267, 79338.0, 83232.75150872742, 85949.14050859473, 86293.56853215756, 88186.11813881843, 9741.269404794668, 9763.414195148314, 9983.650572229937, 10069.093781559695, 10377.066064054272, 19049.523761905784, 19085.809141908405, 19271.036448177536, 19308.03459827031, 19376.612467750565, 7278.636068196569, 7279.180204948762, 7304.634768261566, 7471.402287816962, 7482.663280152297, 3588.4258518637416, 3987.6211759882763, 4020.2160578686876, 4085.816138273785, 4190.601892820177, 3243.2378391078078, 3345.514900339437, 3349.413852575838, 3358.8152651604396, 3526.9771766188032, 643.6202640442161, 643.8229486891142, 671.144291028942, 675.5575098310615, 681.5297444763098, 605.9939400605938, 643.5173619785161, 651.667649498759, 652.6214795418646, 669.7777645671753, 8600.268977137013, 8924.910750892408, 9029.63881444735, 9043.728688139367, 9080.636774528944, 547.6669388405343, 613.2212090644856, 638.5019684645983, 644.3846126948753, 653.2781276689285, 4054.553999060101, 4188.643965262926, 4361.302191649321, 4464.866054018385, 4503.234450143484, 112830.0, 117177.4141129306, 117249.0, 119501.0, 122711.0, 66972.33027669661, 67098.0, 91293.0, 91338.62992055125, 93555.53222233846, 79894.0, 82078.7660546136, 86276.0, 86501.56749216339, 86881.78709277464, 8387.287080598218, 8476.957615211884, 8544.530050847263, 8604.655813767378, 8639.0, 52610.473895260555, 53096.73451632717, 53256.467435325154, 55171.03460688557, 55229.0, 76516.46967060516, 79565.20434795578, 81492.18507814845, 81937.0, 84973.57513212394, 10189.643362482277, 10373.740656483647, 10728.89271107279, 10791.244612877023, 11149.888501114885, 11686.24039437434, 11855.940720296343, 12068.982744322368, 12173.817392738938, 12248.081393895316, 10746.032857042903, 11136.55453781872, 11945.730311241185, 12105.81841272364, 12121.151519393557, 11335.659930202108, 12174.634760957186, 12479.812802807783, 12678.619641410773, 12679.556215532411, 11876.406179690808, 11952.282863028246, 12162.574309899112, 12512.436940337524, 12576.74846503083, 10425.687229383131, 10561.366318020944, 10989.61536346224, 11028.944855275673, 11128.332300062022, 10455.66025558295, 10528.157747380037, 10699.839502407625, 10878.020978111692, 11112.388818615054], "value": 7059.928279366158, "unit": "score"}], "name": "dromaeo_css", "value": 7009.262319986781, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 113.0, 115.0, 114.0, 115.0, 115.0, 114.0, 113.0, 113.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 110.0, 110.0, 143.0, 122.0, 138.0, 132.0, 136.0, 175.0, 110.0], "value": 132.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 184.0, 185.0, 185.0, 184.0, 184.0, 181.0, 185.0, 185.0], "value": 184.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 59.0, 86.0, 64.0, 78.0, 81.0, 62.0, 83.0, 58.0], "value": 71.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 94.0, 70.0, 68.0, 68.0, 89.0, 67.0, 68.0, 92.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 89.0, 85.0, 94.0, 84.0, 88.0, 85.0, 87.0, 85.0], "value": 87.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 94.0, 93.0, 93.0, 93.0, 94.0, 93.0, 94.0, 94.0], "value": 93.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 88.0, 113.0, 81.0, 83.0, 85.0, 83.0, 81.0, 81.0], "value": 85.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 53.0, 53.0, 57.0, 57.0, 57.0, 56.0, 57.0, 57.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 70.0, 65.0, 83.0, 76.0, 72.0, 75.0, 103.0, 102.0, 90.0], "value": 80.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 91.0, 86.0, 86.0, 83.0, 83.0, 84.0, 83.0, 85.0], "value": 85.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 141.0, 141.0, 140.0, 143.0, 142.0, 154.0, 150.0, 215.0, 140.0], "value": 151.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 167.0, 164.0, 168.0, 170.0, 164.0, 167.0, 166.0, 162.0], "value": 165.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 68.0, 68.0, 68.0, 68.0, 68.0, 67.0, 68.0], "value": 67.8, "unit": "score"}], "name": "kraken", "value": 1451.5, "alertThreshold": 2.0}]} 18:07:28 INFO - Return code: 0 18:07:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:07:28 INFO - # TBPL SUCCESS # 18:07:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:07:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:07:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:07:28 INFO - 2016-12-20 18:07:28.312 screenresolution[1655:9731] starting screenresolution argv=screenresolution get 18:07:28 INFO - 2016-12-20 18:07:28.317 screenresolution[1655:9731] Display 0: 1600x1200x32@60 18:07:28 INFO - 2016-12-20 18:07:28.323 screenresolution[1656:9734] starting screenresolution argv=screenresolution list 18:07:28 INFO - Available Modes on Display 0 18:07:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:07:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:07:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:07:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:07:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:07:28 INFO - Intel Iris: 18:07:28 INFO - Chipset Model: Intel Iris 18:07:28 INFO - Type: GPU 18:07:28 INFO - Bus: Built-In 18:07:28 INFO - VRAM (Dynamic, Max): 1536 MB 18:07:28 INFO - Vendor: Intel (0x8086) 18:07:28 INFO - Device ID: 0x0a2e 18:07:28 INFO - Revision ID: 0x0009 18:07:28 INFO - Displays: 18:07:28 INFO - Display: 18:07:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:07:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:07:28 INFO - Display Serial Number: 1600x1200 60 18:07:28 INFO - Main Display: Yes 18:07:28 INFO - Mirror: Off 18:07:28 INFO - Online: Yes 18:07:28 INFO - Rotation: Supported 18:07:28 INFO - Return code: 0 18:07:28 INFO - Running post-action listener: _resource_record_post_action 18:07:28 INFO - [mozharness: 2016-12-21 02:07:28.564811Z] Finished run-tests step (success) 18:07:28 INFO - Running post-run listener: _resource_record_post_run 18:07:28 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 39134208; Write bytes: 656570368; Read time: 421; Write time: 2630 18:07:28 INFO - TinderboxPrint: CPU usage
53.4% 18:07:28 INFO - TinderboxPrint: I/O read bytes / time
39,134,208 / 421 18:07:28 INFO - TinderboxPrint: I/O write bytes / time
656,570,368 / 2,630 18:07:28 INFO - TinderboxPrint: CPU idle
2,261.6 (46.6%) 18:07:28 INFO - TinderboxPrint: CPU system
970.0 (20.0%) 18:07:28 INFO - TinderboxPrint: CPU user
1,618.1 (33.4%) 18:07:28 INFO - TinderboxPrint: Swap in / out
387,530,752 / 0 18:07:28 INFO - install - Wall time: 31s; CPU: 55.0%; Read bytes: 346098176; Write bytes: 345036288; Read time: 26730; Write time: 663 18:07:28 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 36840448; Write bytes: 304386560; Read time: 393; Write time: 1939 18:07:29 INFO - Running post-run listener: _upload_blobber_files 18:07:29 INFO - Blob upload gear active. 18:07:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:07:29 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 18:07:29 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'] 18:07:29 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 18:07:29 INFO - (blobuploader) - INFO - Open directory for files ... 18:07:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:07:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:07:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:07:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:07:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:07:30 INFO - (blobuploader) - INFO - Done attempting. 18:07:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:07:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:07:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:07:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:07:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:07:31 INFO - (blobuploader) - INFO - Done attempting. 18:07:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:07:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:07:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:07:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:07:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:07:33 INFO - (blobuploader) - INFO - Done attempting. 18:07:33 INFO - (blobuploader) - INFO - Iteration through files over. 18:07:33 INFO - Return code: 0 18:07:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:07:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:07:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/25023943abd70e57f106fb221164f5f85753281bde7c9a0fac70e9cb29159a6260093ca54dd9f36621c7cd17717dc761e5f554263b930bf495f876f3113ce7cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6262b34ca7566ec00b94608a7f5957a9402111cfe053733be6deea4c97b313c19b70e75f543883768cf1fefc8cfec3ad24697d335ccb707194ba65b5407cd009", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/861adba61fc4078b019e295d0275cef64496e628b2a117bf7ff5b080f3975ec352cc21d01e96aed3a2752fad7507630580c5ec57dae200cd206f540de84db32b"} 18:07:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:07:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:07:33 INFO - Contents: 18:07:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/25023943abd70e57f106fb221164f5f85753281bde7c9a0fac70e9cb29159a6260093ca54dd9f36621c7cd17717dc761e5f554263b930bf495f876f3113ce7cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6262b34ca7566ec00b94608a7f5957a9402111cfe053733be6deea4c97b313c19b70e75f543883768cf1fefc8cfec3ad24697d335ccb707194ba65b5407cd009", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/861adba61fc4078b019e295d0275cef64496e628b2a117bf7ff5b080f3975ec352cc21d01e96aed3a2752fad7507630580c5ec57dae200cd206f540de84db32b"} 18:07:33 INFO - Running post-run listener: copy_logs_to_upload_dir 18:07:33 INFO - Copying logs to upload dir... 18:07:33 INFO - mkdir: /builds/slave/test/build/upload/logs 18:07:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.952137 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-20 18:07:33.559623) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-20 18:07:33.563418) ========= 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.LKbNiRgKpx/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.yKqm16OKDZ/Listeners TMPDIR=/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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/25023943abd70e57f106fb221164f5f85753281bde7c9a0fac70e9cb29159a6260093ca54dd9f36621c7cd17717dc761e5f554263b930bf495f876f3113ce7cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6262b34ca7566ec00b94608a7f5957a9402111cfe053733be6deea4c97b313c19b70e75f543883768cf1fefc8cfec3ad24697d335ccb707194ba65b5407cd009", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/861adba61fc4078b019e295d0275cef64496e628b2a117bf7ff5b080f3975ec352cc21d01e96aed3a2752fad7507630580c5ec57dae200cd206f540de84db32b"} build_url:https://queue.taskcluster.net/v1/task/KsD4CFTWQ2aX0Fx_m1mQbQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010597 build_url: 'https://queue.taskcluster.net/v1/task/KsD4CFTWQ2aX0Fx_m1mQbQ/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/25023943abd70e57f106fb221164f5f85753281bde7c9a0fac70e9cb29159a6260093ca54dd9f36621c7cd17717dc761e5f554263b930bf495f876f3113ce7cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6262b34ca7566ec00b94608a7f5957a9402111cfe053733be6deea4c97b313c19b70e75f543883768cf1fefc8cfec3ad24697d335ccb707194ba65b5407cd009", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/861adba61fc4078b019e295d0275cef64496e628b2a117bf7ff5b080f3975ec352cc21d01e96aed3a2752fad7507630580c5ec57dae200cd206f540de84db32b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-20 18:07:33.596485) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 18:07:33.596805) ========= 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.LKbNiRgKpx/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.yKqm16OKDZ/Listeners TMPDIR=/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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.005016 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-20 18:07:33.645800) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-20 18:07:33.646133) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-20 18:07:33.646444) ========= ========= Total master_lag: 0.77 =========