builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0287 starttime: 1476449946.39 results: success (0) buildid: 20161014032941 builduid: 214b62323e2946a38c774ebd84800b2b revision: 1391a2889aeb2bdd61ad6ef838e65826e35aabc2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-14 05:59:06.393976) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-14 05:59:06.394394) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-14 05:59:06.394669) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-14 05:59:06.420685) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 05:59:06.421001) ========= 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.v9QLUcJFXG/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.ZpualKetCg/Listeners TMPDIR=/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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-10-14 05:59:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 68.3M=0s 2016-10-14 05:59:06 (68.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.119495 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 05:59:06.562629) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 05:59:06.562964) ========= 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.v9QLUcJFXG/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.ZpualKetCg/Listeners TMPDIR=/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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.027747 ========= master_lag: 0.56 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 05:59:07.148552) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-14 05:59:07.152919) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 1391a2889aeb2bdd61ad6ef838e65826e35aabc2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 1391a2889aeb2bdd61ad6ef838e65826e35aabc2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.v9QLUcJFXG/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.ZpualKetCg/Listeners TMPDIR=/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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-10-14 05:59:07,233 truncating revision to first 12 chars 2016-10-14 05:59:07,233 Setting DEBUG logging. 2016-10-14 05:59:07,233 attempt 1/10 2016-10-14 05:59:07,233 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/1391a2889aeb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-14 05:59:08,961 unpacking tar archive at: fx-team-1391a2889aeb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.987422 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-10-14 05:59:09.161875) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-14 05:59:09.162223) ========= script_repo_revision: 1391a2889aeb2bdd61ad6ef838e65826e35aabc2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-14 05:59:09.162604) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-14 05:59:09.162928) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-14 05:59:09.180671) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-10-14 05:59:09.180969) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.v9QLUcJFXG/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.ZpualKetCg/Listeners TMPDIR=/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 05:59:09 INFO - MultiFileLogger online at 20161014 05:59:09 in /builds/slave/test 05:59:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:59:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:59:09 INFO - {'append_to_log': False, 05:59:09 INFO - 'base_work_dir': '/builds/slave/test', 05:59:09 INFO - 'blob_upload_branch': 'Fx-Team', 05:59:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:59:09 INFO - 'branch': 'Fx-Team', 05:59:09 INFO - 'buildbot_json_path': 'buildprops.json', 05:59:09 INFO - 'config_files': ('talos/mac_config.py',), 05:59:09 INFO - 'default_actions': ('clobber', 05:59:09 INFO - 'read-buildbot-config', 05:59:09 INFO - 'download-and-extract', 05:59:09 INFO - 'populate-webroot', 05:59:09 INFO - 'create-virtualenv', 05:59:09 INFO - 'install', 05:59:09 INFO - 'run-tests'), 05:59:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:59:09 INFO - 'download_minidump_stackwalk': True, 05:59:09 INFO - 'download_symbols': 'ondemand', 05:59:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:59:09 INFO - 'tooltool.py': '/tools/tooltool.py', 05:59:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:59:09 INFO - '/tools/misc-python/virtualenv.py')}, 05:59:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:59:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:59:09 INFO - 'installer_path': 'installer.exe', 05:59:09 INFO - 'log_level': 'info', 05:59:09 INFO - 'log_name': 'talos', 05:59:09 INFO - 'log_to_console': True, 05:59:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:59:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:59:09 INFO - 'opt_config_files': (), 05:59:09 INFO - 'pip_index': False, 05:59:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:59:09 INFO - 'cmd': ('bash', 05:59:09 INFO - '-c', 05:59:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:59:09 INFO - 'enabled': True, 05:59:09 INFO - 'halt_on_failure': False, 05:59:09 INFO - 'name': 'check_screen_resolution'},), 05:59:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:59:09 INFO - 'cmd': ('bash', 05:59:09 INFO - '-c', 05:59:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:59:09 INFO - 'enabled': True, 05:59:09 INFO - 'halt_on_failure': False, 05:59:09 INFO - 'name': 'check_screen_resolution'},), 05:59:09 INFO - 'run_cmd_checks_enabled': True, 05:59:09 INFO - 'sps_profile': False, 05:59:09 INFO - 'sps_profile_interval': 0, 05:59:09 INFO - 'suite': 'dromaeojs', 05:59:09 INFO - 'system_bits': '32', 05:59:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:59:09 INFO - 'title': 't-yosemite-r7-0287', 05:59:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:59:09 INFO - 'use_talos_json': True, 05:59:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:59:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:59:09 INFO - 'work_dir': 'build'} 05:59:09 INFO - [mozharness: 2016-10-14 12:59:09.380729Z] Running clobber step. 05:59:09 INFO - Running pre-action listener: _resource_record_pre_action 05:59:09 INFO - Running main action method: clobber 05:59:09 INFO - rmtree: /builds/slave/test/build 05:59:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:59:11 INFO - Running post-action listener: _resource_record_post_action 05:59:11 INFO - [mozharness: 2016-10-14 12:59:11.425709Z] Finished clobber step (success) 05:59:11 INFO - [mozharness: 2016-10-14 12:59:11.425895Z] Running read-buildbot-config step. 05:59:11 INFO - Running pre-action listener: _resource_record_pre_action 05:59:11 INFO - Running main action method: read_buildbot_config 05:59:11 INFO - Using buildbot properties: 05:59:11 INFO - { 05:59:11 INFO - "project": "", 05:59:11 INFO - "product": "firefox", 05:59:11 INFO - "script_repo_revision": "production", 05:59:11 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 05:59:11 INFO - "repository": "", 05:59:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 05:59:11 INFO - "buildid": "20161014032941", 05:59:11 INFO - "pgo_build": "False", 05:59:11 INFO - "basedir": "/builds/slave/test", 05:59:11 INFO - "buildnumber": 4, 05:59:11 INFO - "slavename": "t-yosemite-r7-0287", 05:59:11 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 05:59:11 INFO - "platform": "yosemite_r7", 05:59:11 INFO - "scheduler": "tests-fx-team-macosx64-talos", 05:59:11 INFO - "branch": "fx-team", 05:59:11 INFO - "repo_path": "integration/fx-team", 05:59:11 INFO - "revision": "1391a2889aeb2bdd61ad6ef838e65826e35aabc2", 05:59:11 INFO - "stage_platform": "macosx64", 05:59:11 INFO - "builduid": "214b62323e2946a38c774ebd84800b2b", 05:59:11 INFO - "slavebuilddir": "test" 05:59:11 INFO - } 05:59:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ls7i0ARgScyPMt4Yl5O_DA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 05:59:11 INFO - Running post-action listener: _resource_record_post_action 05:59:11 INFO - [mozharness: 2016-10-14 12:59:11.429089Z] Finished read-buildbot-config step (success) 05:59:11 INFO - [mozharness: 2016-10-14 12:59:11.429226Z] Running download-and-extract step. 05:59:11 INFO - Running pre-action listener: _resource_record_pre_action 05:59:11 INFO - Running main action method: download_and_extract 05:59:11 INFO - mkdir: /builds/slave/test/build/tests 05:59:11 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')]} 05:59:11 INFO - trying https://queue.taskcluster.net/v1/task/Ls7i0ARgScyPMt4Yl5O_DA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 05:59:11 INFO - Downloading https://queue.taskcluster.net/v1/task/Ls7i0ARgScyPMt4Yl5O_DA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:59:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ls7i0ARgScyPMt4Yl5O_DA/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 05:59:12 INFO - Downloaded 1199 bytes. 05:59:12 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:59:12 INFO - Using the following test package requirements: 05:59:12 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:59:12 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:59:12 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 05:59:12 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:59:12 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 05:59:12 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:59:12 INFO - u'jsshell-mac.zip'], 05:59:12 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:59:12 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 05:59:12 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:59:12 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:59:12 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 05:59:12 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:59:12 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 05:59:12 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:59:12 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 05:59:12 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:59:12 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 05:59:12 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:59:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ls7i0ARgScyPMt4Yl5O_DA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:59:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ls7i0ARgScyPMt4Yl5O_DA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:59:12 INFO - Fetch https://queue.taskcluster.net/v1/task/Ls7i0ARgScyPMt4Yl5O_DA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:59:13 INFO - Http code: 200 05:59:13 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 05:59:13 INFO - x-amz-cf-id: fB3nnAHCPguPIyyQ81e2FeWen9cQFl6rvtVi4Lr7xrUQlJ6cPTFV4w== 05:59:13 INFO - x-amz-version-id: FZtTKLjCih.544A3sKs.N87KgMq6zLFt 05:59:13 INFO - Expected file size: 16516925 05:59:13 INFO - Obtained file size: 16516925 05:59:20 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:59:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ls7i0ARgScyPMt4Yl5O_DA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:59:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ls7i0ARgScyPMt4Yl5O_DA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:59:20 INFO - Fetch https://queue.taskcluster.net/v1/task/Ls7i0ARgScyPMt4Yl5O_DA/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:59:21 INFO - Http code: 200 05:59:21 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 05:59:21 INFO - x-amz-cf-id: lYnZtzkSpXgY056r2SOPZ0BiLcmDO7jPx5lGavn8XwyOhX9ueOlHbQ== 05:59:21 INFO - x-amz-version-id: FZtTKLjCih.544A3sKs.N87KgMq6zLFt 05:59:21 INFO - Expected file size: 16516925 05:59:21 INFO - Obtained file size: 16516925 05:59:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ls7i0ARgScyPMt4Yl5O_DA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 05:59:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ls7i0ARgScyPMt4Yl5O_DA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:59:27 INFO - Fetch https://queue.taskcluster.net/v1/task/Ls7i0ARgScyPMt4Yl5O_DA/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 05:59:28 INFO - Http code: 200 05:59:28 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 05:59:28 INFO - x-amz-cf-id: GqIBa0TMAY0aCsIXJVyoBsFU2FoxXCl-fULVBmWuYbsN9S4o_KYhhA== 05:59:28 INFO - x-amz-version-id: cYpwpROUIUU040iBRERSRoqfMbumi_Yu 05:59:28 INFO - Expected file size: 14409006 05:59:28 INFO - Obtained file size: 14409006 05:59:29 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')]} 05:59:29 INFO - trying https://queue.taskcluster.net/v1/task/Ls7i0ARgScyPMt4Yl5O_DA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:59:29 INFO - Downloading https://queue.taskcluster.net/v1/task/Ls7i0ARgScyPMt4Yl5O_DA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 05:59:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ls7i0ARgScyPMt4Yl5O_DA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 05:59:32 INFO - Downloaded 111913696 bytes. 05:59:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ls7i0ARgScyPMt4Yl5O_DA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:59:32 INFO - mkdir: /builds/slave/test/properties 05:59:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:59:32 INFO - Writing to file /builds/slave/test/properties/build_url 05:59:32 INFO - Contents: 05:59:32 INFO - build_url:https://queue.taskcluster.net/v1/task/Ls7i0ARgScyPMt4Yl5O_DA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:59:32 INFO - Running post-action listener: _resource_record_post_action 05:59:32 INFO - Running post-action listener: set_extra_try_arguments 05:59:32 INFO - [mozharness: 2016-10-14 12:59:32.692389Z] Finished download-and-extract step (success) 05:59:32 INFO - [mozharness: 2016-10-14 12:59:32.692523Z] Running populate-webroot step. 05:59:32 INFO - Running pre-action listener: _resource_record_pre_action 05:59:32 INFO - Running main action method: populate_webroot 05:59:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:59:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:59:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:59:32 INFO - '--tppagecycles', 05:59:32 INFO - '7'], 05:59:32 INFO - 'tests': ['tsvgm']}}, 05:59:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:59:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:59:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:59:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:59:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:59:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:59:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:32 INFO - 'talos_options': ['--disable-e10s'], 05:59:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:59:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:59:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:32 INFO - 'talos_options': ['--disable-e10s'], 05:59:32 INFO - 'tests': ['damp', 'tps']}, 05:59:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:32 INFO - 'tests': ['damp', 'tps']}, 05:59:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:59:32 INFO - 'tests': ['dromaeo_dom']}, 05:59:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:59:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:59:32 INFO - 'tests': ['basic_compositor_video']}, 05:59:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:59:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:59:32 INFO - 'tests': ['a11yr', 05:59:32 INFO - 'ts_paint', 05:59:32 INFO - 'tpaint', 05:59:32 INFO - 'sessionrestore', 05:59:32 INFO - 'sessionrestore_no_auto_restore', 05:59:32 INFO - 'tabpaint']}, 05:59:32 INFO - 'other-e10s': {'tests': ['a11yr', 05:59:32 INFO - 'ts_paint', 05:59:32 INFO - 'tpaint', 05:59:32 INFO - 'sessionrestore', 05:59:32 INFO - 'sessionrestore_no_auto_restore', 05:59:32 INFO - 'tabpaint']}, 05:59:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:59:32 INFO - 'ts_paint', 05:59:32 INFO - 'tpaint', 05:59:32 INFO - 'sessionrestore', 05:59:32 INFO - 'sessionrestore_no_auto_restore', 05:59:32 INFO - 'tabpaint']}, 05:59:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:59:32 INFO - 'ts_paint', 05:59:32 INFO - 'tpaint', 05:59:32 INFO - 'sessionrestore', 05:59:32 INFO - 'sessionrestore_no_auto_restore', 05:59:32 INFO - 'tabpaint']}, 05:59:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:59:32 INFO - 'tests': ['a11yr', 05:59:32 INFO - 'ts_paint', 05:59:32 INFO - 'tpaint', 05:59:32 INFO - 'sessionrestore', 05:59:32 INFO - 'sessionrestore_no_auto_restore', 05:59:32 INFO - 'tabpaint']}, 05:59:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:59:32 INFO - 'tests': ['a11yr', 05:59:32 INFO - 'ts_paint', 05:59:32 INFO - 'tpaint', 05:59:32 INFO - 'sessionrestore', 05:59:32 INFO - 'sessionrestore_no_auto_restore', 05:59:32 INFO - 'tabpaint']}, 05:59:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:59:32 INFO - 'tests': ['tsvgx', 05:59:32 INFO - 'tsvgr_opacity', 05:59:32 INFO - 'tart', 05:59:32 INFO - 'tscrollx', 05:59:32 INFO - 'cart']}, 05:59:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:59:32 INFO - 'tsvgr_opacity', 05:59:32 INFO - 'tart', 05:59:32 INFO - 'tscrollx', 05:59:32 INFO - 'cart']}, 05:59:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:32 INFO - 'talos_options': ['--disable-e10s'], 05:59:32 INFO - 'tests': ['tp5o']}, 05:59:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:32 INFO - 'tests': ['tp5o']}, 05:59:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:32 INFO - 'talos_options': ['--disable-e10s', 05:59:32 INFO - '--xperf_path', 05:59:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:59:32 INFO - 'tests': ['tp5n']}, 05:59:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:32 INFO - 'talos_options': ['--xperf_path', 05:59:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:59:32 INFO - 'tests': ['tp5n']}}, 05:59:32 INFO - 'talos.zip': {'path': '', 05:59:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:59:32 INFO - Running post-action listener: _resource_record_post_action 05:59:32 INFO - [mozharness: 2016-10-14 12:59:32.711441Z] Finished populate-webroot step (success) 05:59:32 INFO - [mozharness: 2016-10-14 12:59:32.711586Z] Running create-virtualenv step. 05:59:32 INFO - Running pre-action listener: _resource_record_pre_action 05:59:32 INFO - Running main action method: create_virtualenv 05:59:32 INFO - Creating virtualenv /builds/slave/test/build/venv 05:59:32 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 05:59:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:59:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:59:32 INFO - Using real prefix '/tools/python27' 05:59:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:59:33 INFO - Installing distribute.............................................................................................................................................................................................done. 05:59:36 INFO - Installing pip.................done. 05:59:36 INFO - Return code: 0 05:59:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:59:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:59:36 INFO - Reading from file tmpfile_stdout 05:59:36 INFO - Output received: 05:59:36 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) 05:59:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f0e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f05e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb390cbaaf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eefcd30>, '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 0x10ed22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eef4b20>, '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.v9QLUcJFXG/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.ZpualKetCg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 05:59:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:59:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:59:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v9QLUcJFXG/Render', 05:59:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:36 INFO - 'HOME': '/Users/cltbld', 05:59:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:36 INFO - 'LOGNAME': 'cltbld', 05:59:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:36 INFO - 'MOZ_NO_REMOTE': '1', 05:59:36 INFO - 'NO_EM_RESTART': '1', 05:59:36 INFO - 'PAGER': '/bin/cat', 05:59:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:36 INFO - 'PWD': '/builds/slave/test', 05:59:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:36 INFO - 'SHELL': '/bin/bash', 05:59:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZpualKetCg/Listeners', 05:59:36 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 05:59:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:36 INFO - 'USER': 'cltbld', 05:59:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:36 INFO - 'XPC_FLAGS': '0x0', 05:59:36 INFO - 'XPC_SERVICE_NAME': '0', 05:59:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:59:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:36 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 05:59:36 INFO - Cleaning up... 05:59:36 INFO - Return code: 0 05:59:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f0e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f05e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb390cbaaf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eefcd30>, '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 0x10ed22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eef4b20>, '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.v9QLUcJFXG/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.ZpualKetCg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 05:59:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:59:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:59:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v9QLUcJFXG/Render', 05:59:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:36 INFO - 'HOME': '/Users/cltbld', 05:59:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:36 INFO - 'LOGNAME': 'cltbld', 05:59:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:36 INFO - 'MOZ_NO_REMOTE': '1', 05:59:36 INFO - 'NO_EM_RESTART': '1', 05:59:36 INFO - 'PAGER': '/bin/cat', 05:59:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:36 INFO - 'PWD': '/builds/slave/test', 05:59:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:36 INFO - 'SHELL': '/bin/bash', 05:59:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZpualKetCg/Listeners', 05:59:36 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 05:59:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:36 INFO - 'USER': 'cltbld', 05:59:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:36 INFO - 'XPC_FLAGS': '0x0', 05:59:36 INFO - 'XPC_SERVICE_NAME': '0', 05:59:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:59:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:37 INFO - Downloading/unpacking psutil>=3.1.1 05:59:37 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 05:59:37 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 05:59:37 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 05:59:37 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 05:59:37 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 05:59:37 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 05:59:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:59:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:59:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:59:40 INFO - Installing collected packages: psutil 05:59:40 INFO - Running setup.py install for psutil 05:59:40 INFO - building 'psutil._psutil_osx' extension 05:59:40 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:59:41 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 05:59:41 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 05:59:41 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 05:59:41 INFO - building 'psutil._psutil_posix' extension 05:59:41 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 05:59:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:59:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:59:41 INFO - ^ 05:59:41 INFO - 1 warning generated. 05:59:41 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 05:59:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:59:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:59:41 INFO - Successfully installed psutil 05:59:41 INFO - Cleaning up... 05:59:41 INFO - Return code: 0 05:59:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:59:41 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')]} 05:59:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:41 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')]} 05:59:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:41 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 0x10f0e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f05e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb390cbaaf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eefcd30>, '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 0x10ed22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eef4b20>, '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.v9QLUcJFXG/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.ZpualKetCg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 05:59:41 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 05:59:41 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 05:59:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v9QLUcJFXG/Render', 05:59:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:41 INFO - 'HOME': '/Users/cltbld', 05:59:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:41 INFO - 'LOGNAME': 'cltbld', 05:59:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:41 INFO - 'MOZ_NO_REMOTE': '1', 05:59:41 INFO - 'NO_EM_RESTART': '1', 05:59:41 INFO - 'PAGER': '/bin/cat', 05:59:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:41 INFO - 'PWD': '/builds/slave/test', 05:59:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:41 INFO - 'SHELL': '/bin/bash', 05:59:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZpualKetCg/Listeners', 05:59:41 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 05:59:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:41 INFO - 'USER': 'cltbld', 05:59:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:41 INFO - 'XPC_FLAGS': '0x0', 05:59:41 INFO - 'XPC_SERVICE_NAME': '0', 05:59:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:59:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:59:42 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 05:59:42 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 05:59:42 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 05:59:42 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 05:59:42 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 05:59:42 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 05:59:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:59:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:59:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:59:44 INFO - Installing collected packages: mozsystemmonitor 05:59:44 INFO - Running setup.py install for mozsystemmonitor 05:59:44 INFO - Successfully installed mozsystemmonitor 05:59:44 INFO - Cleaning up... 05:59:44 INFO - Return code: 0 05:59:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:59:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f0e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f05e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb390cbaaf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eefcd30>, '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 0x10ed22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eef4b20>, '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.v9QLUcJFXG/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.ZpualKetCg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 05:59:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:59:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:59:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v9QLUcJFXG/Render', 05:59:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:44 INFO - 'HOME': '/Users/cltbld', 05:59:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:44 INFO - 'LOGNAME': 'cltbld', 05:59:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:44 INFO - 'MOZ_NO_REMOTE': '1', 05:59:44 INFO - 'NO_EM_RESTART': '1', 05:59:44 INFO - 'PAGER': '/bin/cat', 05:59:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:44 INFO - 'PWD': '/builds/slave/test', 05:59:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:44 INFO - 'SHELL': '/bin/bash', 05:59:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZpualKetCg/Listeners', 05:59:44 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 05:59:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:44 INFO - 'USER': 'cltbld', 05:59:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:44 INFO - 'XPC_FLAGS': '0x0', 05:59:44 INFO - 'XPC_SERVICE_NAME': '0', 05:59:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:59:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:45 INFO - Downloading/unpacking jsonschema==2.5.1 05:59:45 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 05:59:45 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 05:59:45 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 05:59:45 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 05:59:45 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 05:59:45 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 05:59:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:59:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:59: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 05:59: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 05:59: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 05:59: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 05:59: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 05:59: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 05:59:48 INFO - Downloading functools32-3.2.3-2.tar.gz 05:59:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:59:48 INFO - warning: no files found matching '*.txt' 05:59:48 INFO - no previously-included directories found matching 'build' 05:59:48 INFO - no previously-included directories found matching 'dist' 05:59:48 INFO - no previously-included directories found matching '.git*' 05:59:48 INFO - Installing collected packages: jsonschema, functools32 05:59:48 INFO - Running setup.py install for functools32 05:59:48 INFO - warning: no files found matching '*.txt' 05:59:48 INFO - no previously-included directories found matching 'build' 05:59:48 INFO - no previously-included directories found matching 'dist' 05:59:48 INFO - no previously-included directories found matching '.git*' 05:59:48 INFO - Successfully installed jsonschema functools32 05:59:48 INFO - Cleaning up... 05:59:48 INFO - Return code: 0 05:59:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:59:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f0e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f05e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb390cbaaf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eefcd30>, '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 0x10ed22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eef4b20>, '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.v9QLUcJFXG/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.ZpualKetCg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 05:59:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:59:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:59:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v9QLUcJFXG/Render', 05:59:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:48 INFO - 'HOME': '/Users/cltbld', 05:59:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:48 INFO - 'LOGNAME': 'cltbld', 05:59:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:48 INFO - 'MOZ_NO_REMOTE': '1', 05:59:48 INFO - 'NO_EM_RESTART': '1', 05:59:48 INFO - 'PAGER': '/bin/cat', 05:59:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:48 INFO - 'PWD': '/builds/slave/test', 05:59:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:48 INFO - 'SHELL': '/bin/bash', 05:59:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZpualKetCg/Listeners', 05:59:48 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 05:59:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:48 INFO - 'USER': 'cltbld', 05:59:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:48 INFO - 'XPC_FLAGS': '0x0', 05:59:48 INFO - 'XPC_SERVICE_NAME': '0', 05:59:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:59:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:59:48 INFO - Cleaning up... 05:59:48 INFO - Return code: 0 05:59:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:59:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f0e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f05e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb390cbaaf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eefcd30>, '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 0x10ed22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eef4b20>, '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.v9QLUcJFXG/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.ZpualKetCg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 05:59:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:59:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:59:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v9QLUcJFXG/Render', 05:59:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:48 INFO - 'HOME': '/Users/cltbld', 05:59:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:48 INFO - 'LOGNAME': 'cltbld', 05:59:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:48 INFO - 'MOZ_NO_REMOTE': '1', 05:59:48 INFO - 'NO_EM_RESTART': '1', 05:59:48 INFO - 'PAGER': '/bin/cat', 05:59:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:48 INFO - 'PWD': '/builds/slave/test', 05:59:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:48 INFO - 'SHELL': '/bin/bash', 05:59:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZpualKetCg/Listeners', 05:59:48 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 05:59:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:48 INFO - 'USER': 'cltbld', 05:59:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:48 INFO - 'XPC_FLAGS': '0x0', 05:59:48 INFO - 'XPC_SERVICE_NAME': '0', 05:59:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:59:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:49 INFO - Downloading/unpacking blobuploader==1.2.4 05:59:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:51 INFO - Downloading blobuploader-1.2.4.tar.gz 05:59:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:59:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:59:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:59:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:59:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:59:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:59:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:59: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 05:59: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 05:59: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 05:59: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 05:59: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 05:59: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 05:59:52 INFO - Downloading docopt-0.6.1.tar.gz 05:59:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:59:52 INFO - Installing collected packages: blobuploader, requests, docopt 05:59:52 INFO - Running setup.py install for blobuploader 05:59:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:59:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:59:53 INFO - Running setup.py install for requests 05:59:53 INFO - Running setup.py install for docopt 05:59:53 INFO - Successfully installed blobuploader requests docopt 05:59:53 INFO - Cleaning up... 05:59:53 INFO - Return code: 0 05:59:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:59:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:59:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f0e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f05e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb390cbaaf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eefcd30>, '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 0x10ed22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eef4b20>, '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.v9QLUcJFXG/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.ZpualKetCg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 05:59:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:59:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:59:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v9QLUcJFXG/Render', 05:59:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:53 INFO - 'HOME': '/Users/cltbld', 05:59:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:53 INFO - 'LOGNAME': 'cltbld', 05:59:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:53 INFO - 'MOZ_NO_REMOTE': '1', 05:59:53 INFO - 'NO_EM_RESTART': '1', 05:59:53 INFO - 'PAGER': '/bin/cat', 05:59:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:53 INFO - 'PWD': '/builds/slave/test', 05:59:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:53 INFO - 'SHELL': '/bin/bash', 05:59:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZpualKetCg/Listeners', 05:59:53 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 05:59:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:53 INFO - 'USER': 'cltbld', 05:59:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:53 INFO - 'XPC_FLAGS': '0x0', 05:59:53 INFO - 'XPC_SERVICE_NAME': '0', 05:59:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:59:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:59:54 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-bYU2k2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:59:54 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-LfxOmP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:59:54 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-_0YHbB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:59:54 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-SGz2mT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:59:54 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-tQJkf7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:59:54 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-fLTxLq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:59:54 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-kV9QWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:59:54 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-nBTVcW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:59:54 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-VebmBs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:59:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:59:54 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-7mJyWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:59:55 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-b5uFOa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:59:55 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-8eMQUq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:59:55 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-EuSTf8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:59:55 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-q8bAyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:59:55 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-yIlRVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:59:55 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-J4LwAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:59:55 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-JZkXQ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:59:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:59:55 INFO - Running setup.py install for manifestparser 05:59:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:59:55 INFO - Running setup.py install for mozcrash 05:59:56 INFO - Running setup.py install for mozdebug 05:59:56 INFO - Running setup.py install for mozdevice 05:59:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:59:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:59:56 INFO - Running setup.py install for mozfile 05:59:56 INFO - Running setup.py install for mozhttpd 05:59:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:59:56 INFO - Running setup.py install for mozinfo 05:59:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:59:56 INFO - Running setup.py install for mozInstall 05:59:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:59:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:59:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:59:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:59:56 INFO - Running setup.py install for mozleak 05:59:57 INFO - Running setup.py install for mozlog 05:59:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:59:57 INFO - Running setup.py install for moznetwork 05:59:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:59:57 INFO - Running setup.py install for mozprocess 05:59:57 INFO - Running setup.py install for mozprofile 05:59:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:59:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:59:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:59:57 INFO - Running setup.py install for mozrunner 05:59:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:59:57 INFO - Running setup.py install for mozscreenshot 05:59:58 INFO - Running setup.py install for moztest 05:59:58 INFO - Running setup.py install for mozversion 05:59:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:59:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:59:58 INFO - Cleaning up... 05:59:58 INFO - Return code: 0 05:59:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:59: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')]} 05:59:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59: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')]} 05:59:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:58 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 0x10f0e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f05e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb390cbaaf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eefcd30>, '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 0x10ed22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eef4b20>, '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.v9QLUcJFXG/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.ZpualKetCg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 05:59:58 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 05:59:58 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 05:59:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v9QLUcJFXG/Render', 05:59:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:58 INFO - 'HOME': '/Users/cltbld', 05:59:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:58 INFO - 'LOGNAME': 'cltbld', 05:59:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:58 INFO - 'MOZ_NO_REMOTE': '1', 05:59:58 INFO - 'NO_EM_RESTART': '1', 05:59:58 INFO - 'PAGER': '/bin/cat', 05:59:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:58 INFO - 'PWD': '/builds/slave/test', 05:59:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:58 INFO - 'SHELL': '/bin/bash', 05:59:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZpualKetCg/Listeners', 05:59:58 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 05:59:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:58 INFO - 'USER': 'cltbld', 05:59:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:58 INFO - 'XPC_FLAGS': '0x0', 05:59:58 INFO - 'XPC_SERVICE_NAME': '0', 05:59:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:59:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:59:58 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-lBTZbz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:59:58 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:59:58 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-d4pU6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:59:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 05:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:59:58 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-j3J3Pu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:59:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:59:58 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-ThIbkQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:59:59 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)) 05:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:59:59 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-UIgnxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:59:59 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)) 05:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:59:59 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-N3zK3k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:59:59 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)) 05:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:59:59 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-xTUFgh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:59:59 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)) 05:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:59:59 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-3IB3ug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:59:59 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)) 05:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:59:59 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-qVkmZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:59:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:59:59 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-g7QRga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:59:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:59:59 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-aNUgFr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:59:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:59:59 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-r9S5uV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:59:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:59:59 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-wwFSwH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:00:00 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)) 06:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:00:00 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-AgMpeT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:00:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 06:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:00:00 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-4TdLCc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:00:00 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)) 06:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:00:00 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-ys9WVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:00:00 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)) 06:00:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:00:00 INFO - Running setup.py (path:/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/pip-HXC1Ke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:00:00 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)) 06:00:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:00:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:00:00 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)) 06:00:00 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)) 06:00:00 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)) 06:00:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:00:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:00:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:00:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:00:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:00:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:00:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:00:03 INFO - Downloading blessings-1.6.tar.gz 06:00:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:00:03 INFO - Installing collected packages: blessings 06:00:03 INFO - Running setup.py install for blessings 06:00:03 INFO - Successfully installed blessings 06:00:03 INFO - Cleaning up... 06:00:03 INFO - Return code: 0 06:00:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:00:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:00:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:00:03 INFO - Reading from file tmpfile_stdout 06:00:03 INFO - Current package versions: 06:00:03 INFO - blessings == 1.6 06:00:03 INFO - blobuploader == 1.2.4 06:00:03 INFO - docopt == 0.6.1 06:00:03 INFO - functools32 == 3.2.3-2 06:00:03 INFO - jsonschema == 2.5.1 06:00:03 INFO - manifestparser == 1.1 06:00:03 INFO - mozInstall == 1.12 06:00:03 INFO - mozcrash == 0.17 06:00:03 INFO - mozdebug == 0.1 06:00:03 INFO - mozdevice == 0.48 06:00:03 INFO - mozfile == 1.2 06:00:03 INFO - mozhttpd == 0.7 06:00:03 INFO - mozinfo == 0.9 06:00:03 INFO - mozleak == 0.1 06:00:03 INFO - mozlog == 3.4 06:00:03 INFO - moznetwork == 0.27 06:00:03 INFO - mozprocess == 0.23 06:00:03 INFO - mozprofile == 0.28 06:00:03 INFO - mozrunner == 6.12 06:00:03 INFO - mozscreenshot == 0.1 06:00:03 INFO - mozsystemmonitor == 0.3 06:00:03 INFO - moztest == 0.7 06:00:03 INFO - mozversion == 1.4 06:00:03 INFO - psutil == 3.1.1 06:00:03 INFO - requests == 1.2.3 06:00:03 INFO - wsgiref == 0.1.2 06:00:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:00:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:00:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:00:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:00: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')]} 06:00:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:00:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:00:04 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 0x10f0e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f05e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb390cbaaf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eefcd30>, '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 0x10ed22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eef4b20>, '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.v9QLUcJFXG/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.ZpualKetCg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 06:00:04 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 06:00:04 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 06:00:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v9QLUcJFXG/Render', 06:00:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:00:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:00:04 INFO - 'HOME': '/Users/cltbld', 06:00:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:00:04 INFO - 'LOGNAME': 'cltbld', 06:00:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:00:04 INFO - 'MOZ_NO_REMOTE': '1', 06:00:04 INFO - 'NO_EM_RESTART': '1', 06:00:04 INFO - 'PAGER': '/bin/cat', 06:00:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:00:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:00:04 INFO - 'PWD': '/builds/slave/test', 06:00:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:00:04 INFO - 'SHELL': '/bin/bash', 06:00:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZpualKetCg/Listeners', 06:00:04 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 06:00:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:00:04 INFO - 'USER': 'cltbld', 06:00:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:00:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:00:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:00:04 INFO - 'XPC_FLAGS': '0x0', 06:00:04 INFO - 'XPC_SERVICE_NAME': '0', 06:00:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:00:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:00:04 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)) 06:00:04 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)) 06:00:04 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)) 06:00:04 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)) 06:00:04 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)) 06:00:04 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)) 06:00:04 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)) 06:00:04 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)) 06:00:04 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)) 06:00:04 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)) 06:00:04 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)) 06:00:04 INFO - Cleaning up... 06:00:04 INFO - Return code: 0 06:00:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:00: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')]} 06:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:00:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:00: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')]} 06:00:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:00:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:00:04 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 0x10f0e8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f05e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb390cbaaf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eefcd30>, '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 0x10ed22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eef4b20>, '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.v9QLUcJFXG/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.ZpualKetCg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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 06:00:04 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 06:00:04 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 06:00:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v9QLUcJFXG/Render', 06:00:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:00:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:00:04 INFO - 'HOME': '/Users/cltbld', 06:00:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:00:04 INFO - 'LOGNAME': 'cltbld', 06:00:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:00:04 INFO - 'MOZ_NO_REMOTE': '1', 06:00:04 INFO - 'NO_EM_RESTART': '1', 06:00:04 INFO - 'PAGER': '/bin/cat', 06:00:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:00:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:00:04 INFO - 'PWD': '/builds/slave/test', 06:00:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:00:04 INFO - 'SHELL': '/bin/bash', 06:00:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZpualKetCg/Listeners', 06:00:04 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 06:00:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:00:04 INFO - 'USER': 'cltbld', 06:00:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:00:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:00:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:00:04 INFO - 'XPC_FLAGS': '0x0', 06:00:04 INFO - 'XPC_SERVICE_NAME': '0', 06:00:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:00:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:00:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:00:05 INFO - Cleaning up... 06:00:05 INFO - Return code: 0 06:00:05 INFO - Running post-action listener: _resource_record_post_action 06:00:05 INFO - Running post-action listener: _start_resource_monitoring 06:00:05 INFO - Starting resource monitoring. 06:00:05 INFO - [mozharness: 2016-10-14 13:00:05.263301Z] Finished create-virtualenv step (success) 06:00:05 INFO - [mozharness: 2016-10-14 13:00:05.264138Z] Running install step. 06:00:05 INFO - Running pre-action listener: _resource_record_pre_action 06:00:05 INFO - Running main action method: install 06:00:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:00:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:00:05 INFO - Reading from file tmpfile_stdout 06:00:05 INFO - Detecting whether we're running mozinstall >=1.0... 06:00:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:00:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:00:05 INFO - Reading from file tmpfile_stdout 06:00:05 INFO - Output received: 06:00:05 INFO - Usage: mozinstall [options] installer 06:00:05 INFO - Options: 06:00:05 INFO - -h, --help show this help message and exit 06:00:05 INFO - -d DEST, --destination=DEST 06:00:05 INFO - Directory to install application into. [default: 06:00:05 INFO - "/builds/slave/test"] 06:00:05 INFO - --app=APP Application being installed. [default: firefox] 06:00:05 INFO - mkdir: /builds/slave/test/build/application 06:00:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:00:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 06:00:40 INFO - Reading from file tmpfile_stdout 06:00:40 INFO - Output received: 06:00:40 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:00:40 INFO - Running post-action listener: _resource_record_post_action 06:00:40 INFO - [mozharness: 2016-10-14 13:00:40.051625Z] Finished install step (success) 06:00:40 INFO - [mozharness: 2016-10-14 13:00:40.051781Z] Running run-tests step. 06:00:40 INFO - Running pre-action listener: _resource_record_pre_action 06:00:40 INFO - Running main action method: run_tests 06:00:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:00:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:00:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:00:40 INFO - 2016-10-14 06:00:40.066 screenresolution[1642:7572] starting screenresolution argv=screenresolution get 06:00:40 INFO - 2016-10-14 06:00:40.069 screenresolution[1642:7572] Display 0: 1600x1200x32@60 06:00:40 INFO - 2016-10-14 06:00:40.075 screenresolution[1643:7574] starting screenresolution argv=screenresolution list 06:00:40 INFO - Available Modes on Display 0 06:00:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:00:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:00:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:00:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:00:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:00:40 INFO - Intel Iris: 06:00:40 INFO - Chipset Model: Intel Iris 06:00:40 INFO - Type: GPU 06:00:40 INFO - Bus: Built-In 06:00:40 INFO - VRAM (Dynamic, Max): 1536 MB 06:00:40 INFO - Vendor: Intel (0x8086) 06:00:40 INFO - Device ID: 0x0a2e 06:00:40 INFO - Revision ID: 0x0009 06:00:40 INFO - Displays: 06:00:40 INFO - Display: 06:00:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:00:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:00:40 INFO - Display Serial Number: 1600x1200 60 06:00:40 INFO - Main Display: Yes 06:00:40 INFO - Mirror: Off 06:00:40 INFO - Online: Yes 06:00:40 INFO - Rotation: Supported 06:00:40 INFO - Return code: 0 06:00:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:00:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:00:40 INFO - Python 2.7.3 06:00:40 INFO - Return code: 0 06:00:40 INFO - grabbing minidump binary from tooltool 06:00:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:00:40 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 0x10eefcd30>, '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 0x10ed22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eef4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:00:40 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 06:00:40 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 06:00:40 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 06:00:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:00:40 INFO - Return code: 0 06:00:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:00:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:00:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:00:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:00:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:00:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ls7i0ARgScyPMt4Yl5O_DA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0287', '--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 06:00:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ls7i0ARgScyPMt4Yl5O_DA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0287 --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 06:00:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.v9QLUcJFXG/Render', 06:00:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:00:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:00:40 INFO - 'HOME': '/Users/cltbld', 06:00:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:00:40 INFO - 'LOGNAME': 'cltbld', 06:00:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:00:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:00:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:00:40 INFO - 'MOZ_NO_REMOTE': '1', 06:00:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:00:40 INFO - 'NO_EM_RESTART': '1', 06:00:40 INFO - 'PAGER': '/bin/cat', 06:00:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:00:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:00:40 INFO - 'PWD': '/builds/slave/test', 06:00:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:00:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:00:40 INFO - 'SHELL': '/bin/bash', 06:00:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZpualKetCg/Listeners', 06:00:40 INFO - 'TMPDIR': '/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/', 06:00:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:00:40 INFO - 'USER': 'cltbld', 06:00:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:00:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:00:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:00:40 INFO - 'XPC_FLAGS': '0x0', 06:00:40 INFO - 'XPC_SERVICE_NAME': '0', 06:00:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:00:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ls7i0ARgScyPMt4Yl5O_DA/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0287', '--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 06:00:40 INFO - mozversion application_buildid: 20161014032941 06:00:40 INFO - mozversion application_changeset: 1391a2889aeb2bdd61ad6ef838e65826e35aabc2 06:00:40 INFO - mozversion application_display_name: Nightly 06:00:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:00:40 INFO - mozversion application_name: Firefox 06:00:40 INFO - mozversion application_remotingname: firefox 06:00:40 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 06:00:40 INFO - mozversion application_vendor: Mozilla 06:00:40 INFO - mozversion application_version: 52.0a1 06:00:40 INFO - mozversion platform_buildid: 20161014032941 06:00:40 INFO - mozversion platform_changeset: 1391a2889aeb2bdd61ad6ef838e65826e35aabc2 06:00:40 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 06:00:40 INFO - mozversion platform_version: 52.0a1 06:00:40 INFO - using testdate: 1476450040 06:00:40 INFO - actual date: 1476450040 06:00:40 INFO - starting webserver on 'localhost:49269' 06:00:40 INFO - SUITE-START | Running 2 tests 06:00:40 INFO - TEST-START | dromaeo_css 06:00:40 INFO - operating with platform_type : mac_ 06:00:40 INFO - Initialising browser for dromaeo_css test... 06:00:40 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/tmpmJ4A0j/profile http://localhost:49269/getInfo.html) 06:00:43 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 06:00:43 INFO - PROCESS | 1653 | colorDepth:24 06:00:43 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 06:00:43 INFO - PROCESS | 1653 | __metrics 06:00:43 INFO - PROCESS | 1653 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:00:43 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:00:44 INFO - TEST-INFO | 1653: exit 0 06:00:44 INFO - Browser initialized. 06:00:44 INFO - Running cycle 1/1 for dromaeo_css test... 06:00:44 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/tmpmJ4A0j/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:00:56 INFO - PROCESS | 1655 | RSS: Main: 231919616 06:00:56 INFO - PROCESS | 1655 | 06:01:47 INFO - PROCESS | 1655 | 1476450107165 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] 06:04:01 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49269/tests/dromaeo/cssquery-ext.html) 06:04:03 INFO - PROCESS | 1655 | RSS: Main: 400683008 06:04:03 INFO - PROCESS | 1655 | 06:07:06 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-ext.html (next: http://localhost:49269/tests/dromaeo/cssquery-jquery.html) 06:07:06 INFO - PROCESS | 1655 | RSS: Main: 372711424 06:07:06 INFO - PROCESS | 1655 | 06:10:09 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49269/tests/dromaeo/cssquery-mootools.html) 06:10:09 INFO - PROCESS | 1655 | RSS: Main: 352718848 06:10:09 INFO - PROCESS | 1655 | 06:13:12 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49269/tests/dromaeo/cssquery-prototype.html) 06:13:12 INFO - PROCESS | 1655 | RSS: Main: 332234752 06:13:12 INFO - PROCESS | 1655 | 06:16:16 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49269/tests/dromaeo/cssquery-yui.html) 06:16:16 INFO - PROCESS | 1655 | RSS: Main: 326967296 06:16:16 INFO - PROCESS | 1655 | 06:19:20 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-yui.html (next: http://localhost:49269/tests/dromaeo/cssquery-dojo.html) 06:19:20 INFO - PROCESS | 1655 | RSS: Main: 509145088 06:19:20 INFO - PROCESS | 1655 | 06:19:20 INFO - PROCESS | 1655 | __start_tp_report 06:19:20 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 06:19:20 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 06:19:20 INFO - PROCESS | 1655 | |i|pagename|runs| 06:19:20 INFO - PROCESS | 1655 | |0;dojo.html;3304.966950330496;3319.10384196267;3323.7174840138587;3337.332533493299;3360.5967283925934;4469.530699276578;5007.973365460081;5614.691191984439;5690.345610254812;5746.511546518545;5729.512991395726;5752.884942301152;5758.769649214027;5760.913586296209;5773.566982476321;5173.224016397542;5226.895462090756;5239.895202095956;5276.287701160332;5288.286081379003;5260.605454590902;5297.44376840432;5309.283246761695;5311.389190243133;5315.01672190646;5214.504622060898;5256.710880901549;5265.736713164346;5292.285541451913;5292.444293349201;5302.73486325686;5319.042572336978;5320.308359913187;5328.893422131575;5389.703566303851;5102.183650615921;5119.539241468248;5149.021685879671;5240.34495688039;5242.973785131088;5647.37878833328;5664.348599910981;5715.171300161495;5724.141378793214;5745.2818397700285;6229.844253893643;6245.6252624842655;6251.812445626593;6252.718627661766;6268.091126786636;6166.105914642397;6186.690665466709;6222.191115155046;6267.99712046075;6268.905966410507;5759.22250496186;5759.280089988752;5812.854678633068;5878.147668588031;5986.2517185351835;6239.032949892774;6244.718987645567;6256.843578910563;6262.937370626327;6277.027060805583;6050.6369617823075;6169.784057557964;6170.136180934583;6186.81439556814;6200.945839207346;6089.926292362085;6111.419415155553;6145.877082458326;6167.828112658581;6228.5017198624;6171.537134714914;6248.50011999039;6261.311255761862;6266.342034086355;6273.059041143865;6148.262208534915;6246.500279977591;6250.624962502174;6263.843403914848;6280.05799130125;4870.634702397334;4909.975450122726;4960.528749768766;4969.403671559364;5002.124628190125;4389.890252743643;4405.911881762347;4422.491413487426;4431.468223813163;4475.865724028285;5590.742083031286;5593.412691667317;5595.160725891149;5596.384397716248;5601.971990140023;6254.124422580842;6257.311695713468;6264.060390941304;6267.0286105653695;6280.183576135073;5443.319585051869;5541.33503979525;5548.250986116823;5549.361823390362;5617.8595535111135;5308.256844041837;5335.519803217651;5367.329083864517;5379.327584051994;5421.26812880264;6358.459530939922;6365.554411191172;6370.140031095928;6378.234611846516;6378.744850206125;6098.159935307505;6100.944524685144;6137.586222397516;6236.968815155895;6261;6123.418275263843;6190.071489276645;6264.843378915381;6278.623282602875;6280.434760871543;6205.565610407409;6222.751089956351;6224.937750622617;6235.906461403173;6251.812445626639;6194.0708893666315;6195.411435913581;6201.193844800147;6237.220347456568;6256.186695729526;5052.46949070357;6060.363661815622;6107.643995706967;6124.663143527148;6147.678249176449;5309.893802124013;6180.876382472416;6184.134221208944;6188.071789231652;6197.938020619736;6008.669523176267;6061.302950160788;6062.939370606237;6063;6072.696365181811;5010.549050585465;6009.759609615567;6069.969650151721;6085.969570152298;6096.116063170904;5984.940150598438;6001.969990150022;6013.037913933749;6045.244344456943;6053.485453736482 06:19:20 INFO - PROCESS | 1655 | |1;ext.html;461.1814030096578;498.2526210683974;518.0390375852793;525.7933043664789;533.1709192206118;23850.522989540197;31347;32178;32245.193870153227;40966.770996870066;41368;43537.693869183895;43627.78186109073;43842.780786096024;43905.682829515084;9565.56954937026;9728.951355243249;9743.123118919295;9745.610175592969;9755;9539.952300238489;9581.185599224074;9678.806423871518;9685.467299298536;9688.70933871985;9674.032596740304;9730.464824434654;9735.75660608487;9744.366616169962;9801.068898454636;9550.522473876279;9644.710658680251;9687.660931867422;9711.223102151811;9715.514224288758;8549.273311768507;9608.615655373776;9609.807803843954;9610.375325603884;9625.566849491719;41097.58902410968;41529;41579.58420415787;41601.583984160075;41812.581874181174;3282.3599398117376;3365.478350855597;3495.6504349565093;3539.4513850353237;3767.8116094195457;30467;30921.072367828776;31744.206394840083;31813.363732725447;31866;3214.1321843102464;3289.802611843297;3345.6821601947777;3355.4295769719206;3375.3418083473734;485639;486165.5691721554;488034;488810;499781;60486;60592.39407605868;60623.393766062654;60682.78634427287;60738.785224295265;60821.39178608246;63590.682046590395;63759;63882;63950.36049639537;15969.520914372355;16198.433054843024;16214.918925405298;16220.675586488203;16416.1791910404;12305.93847030777;12555.309457979716;12565;12574.937125314314;12576.496940122475;14157.716845663235;14498.637534061732;16627.66744665124;16787.83212167887;16893.831061689227;5439.428859969726;6529.608223506605;6557.311482294291;6581.638009909501;6628.933710662928;637.2703254773259;727.7002147152808;777.8016605765607;939.4738946190155;1041.1098510773331;26322.73677263203;28380.574291385645;29997.850010749808;30456.93400730964;30866.537001944987;1040.3341861208833;1045.6967479431032;1114.4483480677118;1122.0126288865747;1230.2618428942742;15332.616684582752;15796.289166987513;15819.440625859319;15851.286692098884;16237.944533605514;534207;536422;537765;539602;541423;59303;59378.81242375128;59411.40588594145;59419.108713369336;59493.70253148706;1216.7285186979627;1229.1150371732338;1275.113795911851;1322.6957799706122;1386.2652794019186;25274.74725252724;29949.85025074861;31050;31811;32648.673513265512;49638;50442.99114017773;50512.24231636602;50527.49472505375;50537.49462505328;524917.3754131205;525360;527092;527798;803526;25756.742432576182;25873.870630646725;26159.869200653873;26211.344716382242;26250.86874565615;46805.06389872252;47054.52945470638;47210.29184562303;48216.5178348212;48793.756031219615;39091.6090839088;39282.60717392789;39522;39924.40113398359;40759.59240407558;38742.80628596839;39032.41451378175;39080.804595976835;40226.79319620416;40380.788576342755;38056.61943380531;38711.806440967615;38795.8361249163;38828.80585597054;38910.4163437543;32014.51978220282;33188.200772818855;33299.00205987648;34191;34192;25207.99168033258;25329.98680052851;25806.967721290937;25864.612030819928;25990.87004564965 06:19:20 INFO - PROCESS | 1655 | |2;jquery.html;339.77405025657936;352.79361573479514;360.1554355037437;378.31336124933244;405.8052134975212;15007.024543404672;15222.847771522296;16211.351545938163;20424.38726838197;20773.27293544726;19746.30887918923;20183.09176087076;20655.27706530268;20950.26674066408;21296.787032129672;5329.147336426173;5791.7972870949525;6090.756369745205;6096.4817990470865;6101.986344506325;5859.44335288147;5972.641641501502;5980.88038239235;6040.063790112539;6043.123747056674;5664.178694089354;5886.352501224862;5906.468417842393;5976.4919981801595;6016.0975853622085;5810.099434587646;5887.558433117512;5958.046712526018;5963;5970.940290597082;5165.66423182492;5167.09575824233;5170.715610641413;5419.3225846769155;5500.724963751836;24350.147744829024;24744.473377749517;25059.87470062638;25271.36821579457;25451.23646290614;2142.2609199826193;2282.897269622871;2457.3242358351417;2515.2077095714976;2833.1925401260746;8361.372897032747;9730.610775568968;9959.402435853799;10377.014183652616;15080.170590617512;1494.8430414806505;1799.6040871008356;1827.5979284557377;1831.4963385069213;1872.1481725814722;82548.58725706393;105946.47026764817;115461;118046;119509;11359.375234362024;11504.42477876103;11715.589954351557;11843.170978031456;11844.88155118455;14356.425742970165;14378.784318235235;14438.783418248524;14526.273686315642;14559.708805824037;7314.817129571803;8214.712485062993;8349.248567628943;8546.487210767249;8597.312215022783;7007.649617519103;8180.550069746221;8317.750467485985;8353.916460835435;8456.32349412058;4456.3538286948215;4529.433820772403;4545.454545454567;4598.839040633562;4598.88502787433;1892.139076720089;1939.486036200408;1941.4563922101834;1951.3950675290705;1951.4243298227057;837.4807619276088;904.1952662130709;931.7297983584684;1008.369768894441;1045.813002242445;20088.283667521555;20726.58546829055;20729.68905466419;20811.791882080986;20910.47723806917;933.3746389918771;962.9470379129215;973.3673112476947;993.8111758765812;1006.5857470254305;10998.505067271992;11939.940300298616;12143.239230311556;12162.75674486523;12506.687332816571;261375;273946;525976;610937;614114.8588514058;138165;139956;141312;144250;147973.26013369864;1019.4851599942131;1066.6106870992187;1111.7832022755485;1112.0603090388609;1113.4766659669942;19132.521686957938;19340.613187735886;19380.32168874139;19668.01659917027;19796.307129250406;56629.86740265254;57010.42989570051;57130.42869571251;57732;57930;77174.58143150827;79646.80529791942;81830.59084704539;86371;86876.56561717151;7856.135825059125;8087.514749115072;8243.793905152419;8260.05009423924;8296.128906464734;12937.676558086123;13266.53567125146;13376.732465350833;13530.72938541204;13615.319234038061;10336.483175841027;11167.609133680282;11238.269512481662;11602.76794464123;11684.357360345262;10135.834379046504;11177.66467005991;11291.548338066714;11394.17693169089;11630.767384652429;11013.559457621606;11563;11639.518785267564;11889.464974076014;11994.400279985792;7884.6057697113765;7946.205379462008;8144.30773384269;8411.747647570583;8430;8380.12008739098;10138.847917281395;11539.423028848692;11786.469608867794;11899.76200476003 06:19:20 INFO - PROCESS | 1655 | |3;mootools.html;1385.3488860235686;1546.5051183621238;1590.00624609619;1825.1969133581224;1982.355734386325;7742.9612851935735;8212.466189697672;8537.701180458693;8693.69572064978;8818.735437936855;8616.181462761027;8705.608247628856;8709.825803483942;8711.520866352348;8717.520536370497;2336.264076815806;2366.9171578994738;2368.4789346343778;2369.869657168864;2377.3105799318178;2310.1914329984456;2342.121704360865;2372.5017746273215;2373.881305934705;2374.0741110966737;2348.7181538215436;2375.228050883461;2376.1683410806254;2382.2614989353356;2385.630227314769;2317.571249318881;2341.203990643181;2345.6950596422525;2348.0959830465395;2361.9881900590385;2315;2317.3974766560646;2317.5133222023396;2322.547103314854;2329.77867102625;8570.971483421967;8651.091635378258;8748.737537873874;8761.123887611251;8784.64861405543;5128.333316668809;5171.120909445403;5336.706481143536;5338.866528336784;5377;3464.7574669773126;3577.94633080504;3654.1960768631125;3661.4324779659196;3677.2829298286847;925.3528718880403;1082.691432941608;1100.2463312630794;1106.7675788084512;1107.8061339265596;11068.335899845955;11396.829047564293;11442.599509017227;11453.65639030822;11559.364234967074;4361.781910904442;4469.173202957463;4550.407357424875;4591.9081618367445;4594.081183763261;9016.549172541347;9031.232345250595;9033.728988130366;9034.954825225963;9050.728478145666;5212.113940630102;5224.9738751306;5236.450172731885;5243.68537887728;5256.500632439988;4372.715773474714;4384.561543845653;4523.208438523246;4706.247000479907;4888.511148885154;3652.7990960497427;3742.401215805458;3791.867284645024;3804;3880.9223815524097;1140.931544107356;1157.5311998640564;1171.6016554371554;1179.7994340962057;1181.9670167312208;878.4685265414332;901.1439132823843;901.4230892228978;912.8493798523302;945.9337846350827;5571.13647384656;5577.470140336662;5844.476475957243;6045.244344456943;6046.818595442144;424.57966613052855;437.58866665334483;443.255331043843;447.25531989237555;449.2385406735617;3758;3868.825902834379;3922.646961773454;4006.45912801768;4014.879553613396;10184.643537476153;10497.107745841537;10579.84130238047;10582.735431614272;10586.894131058745;8914.821703566022;10066.798664026679;10558.049775520087;10573.524191411405;10574.365538067741;6937.410320122745;8154.8776768348525;8167.264946154874;8177.632006559719;8190.8771368429525;4958.7024778513405;5105.846824595268;5648.971755141362;5707.571932105026;5753.971230143822;9584.185344245818;9605.759856003655;9832.60669573209;9906;9960.103590676874;8949.686760963335;9829.066238707312;9961.153301969123;10082.8487572685;10141.594336226763;6501.837454063497;6716.328367163244;6785.982102684637;7080.929190708233;7094.680739366844;7614.466987310835;8314.833703326021;8380.958095209486;8496.787580310294;8516.105808890225;7330.450216233698;7447.813804654927;7448.441366897396;7511.661975211019;7577.393808495197;7070.257622949722;7265.600391978492;7309.963450182715;7349.338559529668;7411.110666720028;6840.863182736417;6887.965560172167;6943.027976083454;6954;6959.860802784017;4885.4870238624435;4925.261210818406;4982.701037937591;4983.651144419856;5041;4819.638527110551;4855.1260773061185;4909.975450122726;4934.358533390637;4960.528749768766 06:19:20 INFO - PROCESS | 1655 | |4;prototype.html;260.5830670926518;297.1427431859087;306.30468835742863;311.9610048743907;314.2301361663924;4127.876163715089;4270.017895883949;5303.867403314919;5314.681119132854;5335.4131045585;4093.0995181060193;4478.8880277992985;4925.817803727097;5054.3429354184;5108.8978220435565;4030.1939612077554;4047.392891066349;4053.4394258210655;4053.8649178229994;4086.5913408659044;3376.9700241426353;3860.613938606145;4054.4121102440276;4078.9796051019703;4094.9181016379803;3255.209634700917;3330.4671252599587;3502.8248587570647;3724.459953306769;4029.8313489088127;3604.477350784148;3728.422094575345;4010.7794071326066;4022.4368588397647;4025.933127721171;3172.4924012157944;3223.8549265282886;3480.7041401480906;3501.2822371414054;3526.347625689256;4954.405471343426;5021.322121513622;5075.746212689351;5083.415407228142;5116.565091967187;4550.817967281305;4599.44806623204;4607.746573938432;4637.6289896808175;4643.442786865564;5550.86122846928;5553.055980483327;5554.583406244547;5557.722113894289;5625.80309689163;3722.3858063419502;3816.0650640592953;3861.073342397832;3867.1878905429894;3884.4173373993845;4919.3358896549225;5760.683162426065;5766.88466230673;5780.653160810365;5814.680192589406;3496.8950931471586;3632.3461776880417;3714.7741245388956;3763.2285381496727;3909.8886601235013;3949.3483575210344;3977.2443235785686;3978.542467616261;3986.4054378248934;4027.7784721839994;3865.7771781225906;4202.978985105116;4398.722951401162;4586.220342541776;4632.351470794091;5551.639848237208;5557.360903496069;5564.777408903598;5568.9443105569235;5586.692731899703;1298.5909438526835;1325.6089453611207;1331.6870535424168;1354.959351219465;1360.9931950340383;726.5204964723257;757.9924200758032;895.3911340288666;899.3704406915185;929.9674511392069;4087.2847251730827;4504.527024662429;5011.974940125349;5013.069968062322;5101.744912754348;5569.27599412082;5569.749361278671;5580.665160090326;5583.972080139654;5598.412166722517;4029;4828.855134345975;4893.877653058702;5064.240363945364;5086.440491545927;2473.5547601431913;2731.3717844895787;2760.4755096531594;2906.052944527172;2936.6769655337894;4893.926591101136;5109.463506331856;5659.754853932211;5813.273340832396;5842.2405087338375;4558.108385020246;5414.047745146066;5721.942780572141;5745.6552606843725;5754.367019627837;5086.685558071143;5734.885302293931;5757.827265182052;5774.393688662714;5811.651300921958;4408.060453400622;5106.7613880478375;5459.2902922619805;5596.720163991866;5601.719914004365;5486.451354864482;5507.807226747044;5525.530329922001;5527.391986881359;5536.280283563111;4839.491853355347;5455.045367060828;5720.599558030898;5773.884522309614;5791.536677065909;3704;3713.424419215026;3870.8194000830017;3901.4537964685533;3925.7644541327613;4887.437944636412;5570.86072848182;5591.496765291144;5604.411536788579;5662.575306852085;4969.975150124226;5440.755166017618;5455.727213639223;5508.44915508446;5510.685512085014;4166.41670166183;4510.819567217274;4669.953300466952;5374.408815030265;5420.7560659769615;4606.26299792045;5006.6495345325475;5139.8458046258675;5309.681419114865;5329.308699107081;4549.340345649928;4728.642663708805;5386.784528618811;5412.918806217988;5425.294711687456;5452.5365343946205;5463.344398672026;5465.699386533779;5467.890642187054;5502.312210973629 06:19:20 INFO - PROCESS | 1655 | |5;yui.html;778.3567253491451;778.9298963093321;782.3545574900709;808.4906508899393;813.9959300203498;15274.84725152748;15906.125163116052;15937.521874343774;15950.92637957064;15963.680726385466;14398.49605263816;14477.276136193175;14483.91370647203;14499.855001449982;14500.782488262657;698.8287869471986;721.7654262364726;722.8554289142193;873.5021038008338;894.9328800339968;871.1332224436662;886.5655828643951;903.8418276801566;905.9094090590921;906.4742449379344;918.8300164469562;919.9402038867486;936.7611259128912;960.2653969713175;964.4647220792457;736.8121129112096;757.0461218864216;913.5980168725773;922.6724512797928;954.4988880837547;703.142166556805;704.6828926982878;705.9505834591581;707.2114592229627;710.0946293475808;14679;15118.244087795567;15146.318415671321;15168.24158792056;15179.089254644647;13417.66455838612;13879;13979.860201397958;14032.649183770385;14109.78835317471;12687.238765673997;12793.55222567215;12917.160384574972;12969.351532423436;13403.994700397508;4413.757243351614;4451.332300154971;4470.75410852403;4557.384753058328;4616.976915115437;111984;112888.8400044;113062;114006;116507;11145.052670522935;11185.384803835705;11256.437178141061;11303.604373846876;11351.60269390584;20164.798352016292;21062.47343816417;22880.771192288197;22899.885500572724;22907;7731.806704832424;7830.569318687527;7888.2506161914525;7969.442139050211;8033.875257463959;4379.737215767064;4437.556244375537;4441.289393697058;4455.710378825366;4535.115652447741;3594.388953877847;3657.122290650278;3682.9447558286647;3756.436534519844;3763.5107436033686;671.5198632977347;675.6148995072762;679.2697849811374;679.755288096285;695.2526034512919;440.2779441715589;634.3783092569216;654.842837718944;681.6693903456819;694.8992396102536;7238.565686058748;8398.118197589165;8553.74338769838;8849.867251991225;9095.454272743656;628.6856571714143;628.82096069869;629.4460874430472;661.4939571228015;661.8510835062073;4355.73865568067;4399.098184872094;4452.398926144929;4543.54564543543;5037.647364684437;146886;149281.25359373132;150069;155755.4424455741;156672.38301893277;95368.52315738377;128302;129326;130892;133712;90318.54840726017;98445.50777246068;98493;99855;101749;8148;8487;9065.138811812867;9624.951875240578;9627.374220675634;42949.35575966426;56192.71903640455;56198;57006.7149664249;58404.4159558416;61492.69253653703;79058.6047069761;89702.55148724215;91736.54131729298;92976;13422.798658020332;13515.662108447368;13518.83698608218;14248.430062797372;14454.710905782036;15209.847901521285;15258.847411525743;15265.542033739006;15359.232038397811;15483.5354939352;12015.283472500905;12294.262344259372;13749.931250344085;14196.645083872985;14437.639059023608;13899;14107.717845642825;14334.996550241383;14630.293395721399;14671.25556668602;13647.522336718168;13882.958778091886;13991.93004035014;14229.14625122496;14460.27698615086;10199.13307368882;10675.985781350759;11501.194916355775;11636.476358564054;11754.52297927177;8447.746567602982;9297.442153470813;9932.453715045742;10559.155267578422;10858.185636077484 06:19:20 INFO - PROCESS | 1655 | __end_tp_report 06:19:20 INFO - PROCESS | 1655 | __start_cc_report 06:19:20 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,2183 06:19:20 INFO - PROCESS | 1655 | __end_cc_report 06:19:20 INFO - PROCESS | 1655 | __startTimestamp1476451160475__endTimestamp 06:19:20 INFO - PROCESS | 1655 | 06:19:20 INFO - PROCESS | 1655 | ------- Summary: start ------- 06:19:20 INFO - PROCESS | 1655 | Number of tests: 6 06:19:20 INFO - PROCESS | 1655 | 06:19:20 INFO - PROCESS | 1655 | [#0] dojo.html Cycles:165 Average:5725.10 Median:6013.04 stddev:652.26 (10.8%) stddev-sans-first:626.02 06:19:20 INFO - PROCESS | 1655 | Values: 3305.0 3319.1 3323.7 3337.3 3360.6 4469.5 5008.0 5614.7 5690.3 5746.5 5729.5 5752.9 5758.8 5760.9 5773.6 5173.2 5226.9 5239.9 5276.3 5288.3 5260.6 5297.4 5309.3 5311.4 5315.0 5214.5 5256.7 5265.7 5292.3 5292.4 5302.7 5319.0 5320.3 5328.9 5389.7 5102.2 5119.5 5149.0 5240.3 5243.0 5647.4 5664.3 5715.2 5724.1 5745.3 6229.8 6245.6 6251.8 6252.7 6268.1 6166.1 6186.7 6222.2 6268.0 6268.9 5759.2 5759.3 5812.9 5878.1 5986.3 6239.0 6244.7 6256.8 6262.9 6277.0 6050.6 6169.8 6170.1 6186.8 6200.9 6089.9 6111.4 6145.9 6167.8 6228.5 6171.5 6248.5 6261.3 6266.3 6273.1 6148.3 6246.5 6250.6 6263.8 6280.1 4870.6 4910.0 4960.5 4969.4 5002.1 4389.9 4405.9 4422.5 4431.5 4475.9 5590.7 5593.4 5595.2 5596.4 5602.0 6254.1 6257.3 6264.1 6267.0 6280.2 5443.3 5541.3 5548.3 5549.4 5617.9 5308.3 5335.5 5367.3 5379.3 5421.3 6358.5 6365.6 6370.1 6378.2 6378.7 6098.2 6100.9 6137.6 6237.0 6261.0 6123.4 6190.1 6264.8 6278.6 6280.4 6205.6 6222.8 6224.9 6235.9 6251.8 6194.1 6195.4 6201.2 6237.2 6256.2 5052.5 6060.4 6107.6 6124.7 6147.7 5309.9 6180.9 6184.1 6188.1 6197.9 6008.7 6061.3 6062.9 6063.0 6072.7 5010.5 6009.8 6070.0 6086.0 6096.1 5984.9 6002.0 6013.0 6045.2 6053.5 06:19:20 INFO - PROCESS | 1655 | 06:19:20 INFO - PROCESS | 1655 | [#1] ext.html Cycles:180 Average:67399.34 Median:41032.18 stddev:144573.62 (352.3%) stddev-sans-first:144891.83 06:19:20 INFO - PROCESS | 1655 | Values: 461.2 498.3 518.0 525.8 533.2 23850.5 31347.0 32178.0 32245.2 40966.8 41368.0 43537.7 43627.8 43842.8 43905.7 9565.6 9729.0 9743.1 9745.6 9755.0 9540.0 9581.2 9678.8 9685.5 9688.7 9674.0 9730.5 9735.8 9744.4 9801.1 9550.5 9644.7 9687.7 9711.2 9715.5 8549.3 9608.6 9609.8 9610.4 9625.6 41097.6 41529.0 41579.6 41601.6 41812.6 3282.4 3365.5 3495.7 3539.5 3767.8 30467.0 30921.1 31744.2 31813.4 31866.0 3214.1 3289.8 3345.7 3355.4 3375.3 485639.0 486165.6 488034.0 488810.0 499781.0 60486.0 60592.4 60623.4 60682.8 60738.8 60821.4 63590.7 63759.0 63882.0 63950.4 15969.5 16198.4 16214.9 16220.7 16416.2 12305.9 12555.3 12565.0 12574.9 12576.5 14157.7 14498.6 16627.7 16787.8 16893.8 5439.4 6529.6 6557.3 6581.6 6628.9 637.3 727.7 777.8 939.5 1041.1 26322.7 28380.6 29997.9 30456.9 30866.5 1040.3 1045.7 1114.4 1122.0 1230.3 15332.6 15796.3 15819.4 15851.3 16237.9 534207.0 536422.0 537765.0 539602.0 541423.0 59303.0 59378.8 59411.4 59419.1 59493.7 1216.7 1229.1 1275.1 1322.7 1386.3 25274.7 29949.9 31050.0 31811.0 32648.7 49638.0 50443.0 50512.2 50527.5 50537.5 524917.4 525360.0 527092.0 527798.0 803526.0 25756.7 25873.9 26159.9 26211.3 26250.9 46805.1 47054.5 47210.3 48216.5 48793.8 39091.6 39282.6 39522.0 39924.4 40759.6 38742.8 39032.4 39080.8 40226.8 40380.8 38056.6 38711.8 38795.8 38828.8 38910.4 32014.5 33188.2 33299.0 34191.0 34192.0 25208.0 25330.0 25807.0 25864.6 25990.9 06:19:20 INFO - PROCESS | 1655 | 06:19:20 INFO - PROCESS | 1655 | [#2] jquery.html Cycles:180 Average:31476.59 Median:13403.74 stddev:82659.46 (616.7%) stddev-sans-first:82858.28 06:19:20 INFO - PROCESS | 1655 | Values: 339.8 352.8 360.2 378.3 405.8 15007.0 15222.8 16211.4 20424.4 20773.3 19746.3 20183.1 20655.3 20950.3 21296.8 5329.1 5791.8 6090.8 6096.5 6102.0 5859.4 5972.6 5980.9 6040.1 6043.1 5664.2 5886.4 5906.5 5976.5 6016.1 5810.1 5887.6 5958.0 5963.0 5970.9 5165.7 5167.1 5170.7 5419.3 5500.7 24350.1 24744.5 25059.9 25271.4 25451.2 2142.3 2282.9 2457.3 2515.2 2833.2 8361.4 9730.6 9959.4 10377.0 15080.2 1494.8 1799.6 1827.6 1831.5 1872.1 82548.6 105946.5 115461.0 118046.0 119509.0 11359.4 11504.4 11715.6 11843.2 11844.9 14356.4 14378.8 14438.8 14526.3 14559.7 7314.8 8214.7 8349.2 8546.5 8597.3 7007.6 8180.6 8317.8 8353.9 8456.3 4456.4 4529.4 4545.5 4598.8 4598.9 1892.1 1939.5 1941.5 1951.4 1951.4 837.5 904.2 931.7 1008.4 1045.8 20088.3 20726.6 20729.7 20811.8 20910.5 933.4 962.9 973.4 993.8 1006.6 10998.5 11939.9 12143.2 12162.8 12506.7 261375.0 273946.0 525976.0 610937.0 614114.9 138165.0 139956.0 141312.0 144250.0 147973.3 1019.5 1066.6 1111.8 1112.1 1113.5 19132.5 19340.6 19380.3 19668.0 19796.3 56629.9 57010.4 57130.4 57732.0 57930.0 77174.6 79646.8 81830.6 86371.0 86876.6 7856.1 8087.5 8243.8 8260.1 8296.1 12937.7 13266.5 13376.7 13530.7 13615.3 10336.5 11167.6 11238.3 11602.8 11684.4 10135.8 11177.7 11291.5 11394.2 11630.8 11013.6 11563.0 11639.5 11889.5 11994.4 7884.6 7946.2 8144.3 8411.7 8430.0 8380.1 10138.8 11539.4 11786.5 11899.8 06:19:20 INFO - PROCESS | 1655 | 06:19:20 INFO - PROCESS | 1655 | [#3] mootools.html Cycles:180 Average:5558.27 Median:4946.53 stddev:3109.09 (62.9%) stddev-sans-first:3101.99 06:19:20 INFO - PROCESS | 1655 | Values: 1385.3 1546.5 1590.0 1825.2 1982.4 7743.0 8212.5 8537.7 8693.7 8818.7 8616.2 8705.6 8709.8 8711.5 8717.5 2336.3 2366.9 2368.5 2369.9 2377.3 2310.2 2342.1 2372.5 2373.9 2374.1 2348.7 2375.2 2376.2 2382.3 2385.6 2317.6 2341.2 2345.7 2348.1 2362.0 2315.0 2317.4 2317.5 2322.5 2329.8 8571.0 8651.1 8748.7 8761.1 8784.6 5128.3 5171.1 5336.7 5338.9 5377.0 3464.8 3577.9 3654.2 3661.4 3677.3 925.4 1082.7 1100.2 1106.8 1107.8 11068.3 11396.8 11442.6 11453.7 11559.4 4361.8 4469.2 4550.4 4591.9 4594.1 9016.5 9031.2 9033.7 9035.0 9050.7 5212.1 5225.0 5236.5 5243.7 5256.5 4372.7 4384.6 4523.2 4706.2 4888.5 3652.8 3742.4 3791.9 3804.0 3880.9 1140.9 1157.5 1171.6 1179.8 1182.0 878.5 901.1 901.4 912.8 945.9 5571.1 5577.5 5844.5 6045.2 6046.8 424.6 437.6 443.3 447.3 449.2 3758.0 3868.8 3922.6 4006.5 4014.9 10184.6 10497.1 10579.8 10582.7 10586.9 8914.8 10066.8 10558.0 10573.5 10574.4 6937.4 8154.9 8167.3 8177.6 8190.9 4958.7 5105.8 5649.0 5707.6 5754.0 9584.2 9605.8 9832.6 9906.0 9960.1 8949.7 9829.1 9961.2 10082.8 10141.6 6501.8 6716.3 6786.0 7080.9 7094.7 7614.5 8314.8 8381.0 8496.8 8516.1 7330.5 7447.8 7448.4 7511.7 7577.4 7070.3 7265.6 7310.0 7349.3 7411.1 6840.9 6888.0 6943.0 6954.0 6959.9 4885.5 4925.3 4982.7 4983.7 5041.0 4819.6 4855.1 4910.0 4934.4 4960.5 06:19:20 INFO - PROCESS | 1655 | 06:19:20 INFO - PROCESS | 1655 | [#4] prototype.html Cycles:180 Average:4414.40 Median:4922.58 stddev:1355.43 (27.5%) stddev-sans-first:1322.89 06:19:20 INFO - PROCESS | 1655 | Values: 260.6 297.1 306.3 312.0 314.2 4127.9 4270.0 5303.9 5314.7 5335.4 4093.1 4478.9 4925.8 5054.3 5108.9 4030.2 4047.4 4053.4 4053.9 4086.6 3377.0 3860.6 4054.4 4079.0 4094.9 3255.2 3330.5 3502.8 3724.5 4029.8 3604.5 3728.4 4010.8 4022.4 4025.9 3172.5 3223.9 3480.7 3501.3 3526.3 4954.4 5021.3 5075.7 5083.4 5116.6 4550.8 4599.4 4607.7 4637.6 4643.4 5550.9 5553.1 5554.6 5557.7 5625.8 3722.4 3816.1 3861.1 3867.2 3884.4 4919.3 5760.7 5766.9 5780.7 5814.7 3496.9 3632.3 3714.8 3763.2 3909.9 3949.3 3977.2 3978.5 3986.4 4027.8 3865.8 4203.0 4398.7 4586.2 4632.4 5551.6 5557.4 5564.8 5568.9 5586.7 1298.6 1325.6 1331.7 1355.0 1361.0 726.5 758.0 895.4 899.4 930.0 4087.3 4504.5 5012.0 5013.1 5101.7 5569.3 5569.7 5580.7 5584.0 5598.4 4029.0 4828.9 4893.9 5064.2 5086.4 2473.6 2731.4 2760.5 2906.1 2936.7 4893.9 5109.5 5659.8 5813.3 5842.2 4558.1 5414.0 5721.9 5745.7 5754.4 5086.7 5734.9 5757.8 5774.4 5811.7 4408.1 5106.8 5459.3 5596.7 5601.7 5486.5 5507.8 5525.5 5527.4 5536.3 4839.5 5455.0 5720.6 5773.9 5791.5 3704.0 3713.4 3870.8 3901.5 3925.8 4887.4 5570.9 5591.5 5604.4 5662.6 4970.0 5440.8 5455.7 5508.4 5510.7 4166.4 4510.8 4670.0 5374.4 5420.8 4606.3 5006.6 5139.8 5309.7 5329.3 4549.3 4728.6 5386.8 5412.9 5425.3 5452.5 5463.3 5465.7 5467.9 5502.3 06:19:20 INFO - PROCESS | 1655 | 06:19:20 INFO - PROCESS | 1655 | [#5] yui.html Cycles:180 Average:24181.10 Median:2419.69 stddev:38521.12 (1592.0%) stddev-sans-first:38589.10 06:19:20 INFO - PROCESS | 1655 | Values: 778.4 778.9 782.4 808.5 814.0 15274.8 15906.1 15937.5 15950.9 15963.7 14398.5 14477.3 14483.9 14499.9 14500.8 698.8 721.8 722.9 873.5 894.9 871.1 886.6 903.8 905.9 906.5 918.8 919.9 936.8 960.3 964.5 736.8 757.0 913.6 922.7 954.5 703.1 704.7 706.0 707.2 710.1 14679.0 15118.2 15146.3 15168.2 15179.1 13417.7 13879.0 13979.9 14032.6 14109.8 12687.2 12793.6 12917.2 12969.4 13404.0 4413.8 4451.3 4470.8 4557.4 4617.0 111984.0 112888.8 113062.0 114006.0 116507.0 11145.1 11185.4 11256.4 11303.6 11351.6 20164.8 21062.5 22880.8 22899.9 22907.0 7731.8 7830.6 7888.3 7969.4 8033.9 4379.7 4437.6 4441.3 4455.7 4535.1 3594.4 3657.1 3682.9 3756.4 3763.5 671.5 675.6 679.3 679.8 695.3 440.3 634.4 654.8 681.7 694.9 7238.6 8398.1 8553.7 8849.9 9095.5 628.7 628.8 629.4 661.5 661.9 4355.7 4399.1 4452.4 4543.5 5037.6 146886.0 149281.3 150069.0 155755.4 156672.4 95368.5 128302.0 129326.0 130892.0 133712.0 90318.5 98445.5 98493.0 99855.0 101749.0 8148.0 8487.0 9065.1 9625.0 9627.4 42949.4 56192.7 56198.0 57006.7 58404.4 61492.7 79058.6 89702.6 91736.5 92976.0 13422.8 13515.7 13518.8 14248.4 14454.7 15209.8 15258.8 15265.5 15359.2 15483.5 12015.3 12294.3 13749.9 14196.6 14437.6 13899.0 14107.7 14335.0 14630.3 14671.3 13647.5 13883.0 13991.9 14229.1 14460.3 10199.1 10676.0 11501.2 11636.5 11754.5 8447.7 9297.4 9932.5 10559.2 10858.2 06:19:20 INFO - PROCESS | 1655 | -------- Summary: end -------- 06:19:20 INFO - PROCESS | 1655 | 06:19:21 INFO - TEST-INFO | 1655: exit 0 06:19:21 INFO - TEST-OK | dromaeo_css | took 1120461ms 06:19:21 INFO - TEST-START | kraken 06:19:21 INFO - operating with platform_type : mac_ 06:19:21 INFO - Initialising browser for kraken test... 06:19:21 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/tmpgX8VUP/profile http://localhost:49269/getInfo.html) 06:19:23 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 06:19:23 INFO - PROCESS | 1664 | colorDepth:24 06:19:23 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 06:19:23 INFO - PROCESS | 1664 | __metrics 06:19:23 INFO - PROCESS | 1664 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:19:23 INFO - PROCESS | 1664 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:19:24 INFO - TEST-INFO | 1664: exit 0 06:19:24 INFO - Browser initialized. 06:19:24 INFO - Running cycle 1/1 for kraken test... 06:19:24 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/T/tmpgX8VUP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:19:36 INFO - PROCESS | 1665 | RSS: Main: 230670336 06:19:36 INFO - PROCESS | 1665 | 06:19:39 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-beat-detection) 06:19:39 INFO - PROCESS | 1665 | RSS: Main: 290930688 06:19:39 INFO - PROCESS | 1665 | 06:19:42 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-dft) 06:19:43 INFO - PROCESS | 1665 | RSS: Main: 291598336 06:19:43 INFO - PROCESS | 1665 | 06:19:46 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-fft) 06:19:47 INFO - PROCESS | 1665 | RSS: Main: 384364544 06:19:47 INFO - PROCESS | 1665 | 06:19:49 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-oscillator) 06:19:49 INFO - PROCESS | 1665 | RSS: Main: 286912512 06:19:49 INFO - PROCESS | 1665 | 06:19:51 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:19:52 INFO - PROCESS | 1665 | RSS: Main: 289796096 06:19:52 INFO - PROCESS | 1665 | 06:19:55 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-darkroom) 06:19:55 INFO - PROCESS | 1665 | RSS: Main: 328052736 06:19:55 INFO - PROCESS | 1665 | 06:19:58 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-desaturate) 06:19:59 INFO - PROCESS | 1665 | RSS: Main: 328925184 06:19:59 INFO - PROCESS | 1665 | 06:20:02 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49269/tests/kraken/driver.html?testName=json-parse-financial) 06:20:03 INFO - PROCESS | 1665 | RSS: Main: 326090752 06:20:03 INFO - PROCESS | 1665 | 06:20:04 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49269/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:20:05 INFO - PROCESS | 1665 | RSS: Main: 303296512 06:20:05 INFO - PROCESS | 1665 | 06:20:07 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:20:07 INFO - PROCESS | 1665 | RSS: Main: 305008640 06:20:07 INFO - PROCESS | 1665 | 06:20:09 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:20:10 INFO - PROCESS | 1665 | RSS: Main: 309231616 06:20:10 INFO - PROCESS | 1665 | 06:20:13 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:20:14 INFO - PROCESS | 1665 | RSS: Main: 319094784 06:20:14 INFO - PROCESS | 1665 | 06:20:17 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:20:18 INFO - PROCESS | 1665 | RSS: Main: 318472192 06:20:18 INFO - PROCESS | 1665 | 06:20:19 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49269/tests/kraken/driver.html?testName=ai-astar) 06:20:20 INFO - PROCESS | 1665 | RSS: Main: 363114496 06:20:20 INFO - PROCESS | 1665 | 06:20:20 INFO - PROCESS | 1665 | __start_tp_report 06:20:20 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 06:20:20 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 06:20:20 INFO - PROCESS | 1665 | |i|pagename|runs| 06:20:20 INFO - PROCESS | 1665 | |0;ai-astar;112;113;114;113;112;113;113;113;113;114 06:20:20 INFO - PROCESS | 1665 | |1;audio-beat-detection;151;113;115;150;114;115;112;114;145;112 06:20:20 INFO - PROCESS | 1665 | |2;audio-dft;185;183;184;182;183;185;183;181;181;186 06:20:20 INFO - PROCESS | 1665 | |3;audio-fft;82;60;61;88;60;60;60;59;85;58 06:20:20 INFO - PROCESS | 1665 | |4;audio-oscillator;89;71;95;71;70;71;93;68;68;90 06:20:20 INFO - PROCESS | 1665 | |5;imaging-gaussian-blur;88;85;88;86;89;85;89;87;89;86 06:20:20 INFO - PROCESS | 1665 | |6;imaging-darkroom;97;96;97;97;96;96;96;96;113;138 06:20:20 INFO - PROCESS | 1665 | |7;imaging-desaturate;82;82;80;77;122;123;100;83;81;83 06:20:20 INFO - PROCESS | 1665 | |8;json-parse-financial;57;54;54;53;55;56;57;54;55;56 06:20:20 INFO - PROCESS | 1665 | |9;json-stringify-tinderbox;65;70;82;109;105;98;70;71;70;70 06:20:20 INFO - PROCESS | 1665 | |10;stanford-crypto-aes;88;86;86;86;86;86;88;85;88;91 06:20:20 INFO - PROCESS | 1665 | |11;stanford-crypto-ccm;149;152;168;160;150;141;148;160;217;154 06:20:20 INFO - PROCESS | 1665 | |12;stanford-crypto-pbkdf2;173;180;179;172;175;175;174;173;173;176 06:20:20 INFO - PROCESS | 1665 | |13;stanford-crypto-sha256-iterative;71;70;69;69;69;69;66;70;68;69 06:20:20 INFO - PROCESS | 1665 | __end_tp_report 06:20:20 INFO - PROCESS | 1665 | __start_cc_report 06:20:20 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,2053 06:20:20 INFO - PROCESS | 1665 | __end_cc_report 06:20:20 INFO - PROCESS | 1665 | __startTimestamp1476451220082__endTimestamp 06:20:20 INFO - PROCESS | 1665 | 06:20:20 INFO - PROCESS | 1665 | ------- Summary: start ------- 06:20:20 INFO - PROCESS | 1665 | Number of tests: 14 06:20:20 INFO - PROCESS | 1665 | 06:20:20 INFO - PROCESS | 1665 | [#0] ai-astar Cycles:10 Average:113.00 Median:113.00 stddev:0.67 (0.6%) stddev-sans-first:0.60 06:20:20 INFO - PROCESS | 1665 | Values: 112.0 113.0 114.0 113.0 112.0 113.0 113.0 113.0 113.0 114.0 06:20:20 INFO - PROCESS | 1665 | 06:20:20 INFO - PROCESS | 1665 | [#1] audio-beat-detection Cycles:10 Average:124.10 Median:115.00 stddev:17.05 (14.8%) stddev-sans-first:15.05 06:20:20 INFO - PROCESS | 1665 | Values: 151.0 113.0 115.0 150.0 114.0 115.0 112.0 114.0 145.0 112.0 06:20:20 INFO - PROCESS | 1665 | 06:20:20 INFO - PROCESS | 1665 | [#2] audio-dft Cycles:10 Average:183.30 Median:183.50 stddev:1.70 (0.9%) stddev-sans-first:1.69 06:20:20 INFO - PROCESS | 1665 | Values: 185.0 183.0 184.0 182.0 183.0 185.0 183.0 181.0 181.0 186.0 06:20:20 INFO - PROCESS | 1665 | 06:20:20 INFO - PROCESS | 1665 | [#3] audio-fft Cycles:10 Average:67.30 Median:60.50 stddev:12.32 (20.4%) stddev-sans-first:11.86 06:20:20 INFO - PROCESS | 1665 | Values: 82.0 60.0 61.0 88.0 60.0 60.0 60.0 59.0 85.0 58.0 06:20:20 INFO - PROCESS | 1665 | 06:20:20 INFO - PROCESS | 1665 | [#4] audio-oscillator Cycles:10 Average:78.60 Median:80.00 stddev:11.48 (14.4%) stddev-sans-first:11.54 06:20:20 INFO - PROCESS | 1665 | Values: 89.0 71.0 95.0 71.0 70.0 71.0 93.0 68.0 68.0 90.0 06:20:20 INFO - PROCESS | 1665 | 06:20:20 INFO - PROCESS | 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:87.20 Median:88.00 stddev:1.62 (1.8%) stddev-sans-first:1.69 06:20:20 INFO - PROCESS | 1665 | Values: 88.0 85.0 88.0 86.0 89.0 85.0 89.0 87.0 89.0 86.0 06:20:20 INFO - PROCESS | 1665 | 06:20:20 INFO - PROCESS | 1665 | [#6] imaging-darkroom Cycles:10 Average:102.20 Median:96.00 stddev:13.63 (14.2%) stddev-sans-first:14.32 06:20:20 INFO - PROCESS | 1665 | Values: 97.0 96.0 97.0 97.0 96.0 96.0 96.0 96.0 113.0 138.0 06:20:20 INFO - PROCESS | 1665 | 06:20:20 INFO - PROCESS | 1665 | [#7] imaging-desaturate Cycles:10 Average:91.30 Median:81.50 stddev:17.55 (21.5%) stddev-sans-first:18.29 06:20:20 INFO - PROCESS | 1665 | Values: 82.0 82.0 80.0 77.0 122.0 123.0 100.0 83.0 81.0 83.0 06:20:20 INFO - PROCESS | 1665 | 06:20:20 INFO - PROCESS | 1665 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:55.50 stddev:1.37 (2.5%) stddev-sans-first:1.27 06:20:20 INFO - PROCESS | 1665 | Values: 57.0 54.0 54.0 53.0 55.0 56.0 57.0 54.0 55.0 56.0 06:20:20 INFO - PROCESS | 1665 | 06:20:20 INFO - PROCESS | 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:81.00 Median:70.00 stddev:16.63 (23.8%) stddev-sans-first:16.60 06:20:20 INFO - PROCESS | 1665 | Values: 65.0 70.0 82.0 109.0 105.0 98.0 70.0 71.0 70.0 70.0 06:20:20 INFO - PROCESS | 1665 | 06:20:20 INFO - PROCESS | 1665 | [#10] stanford-crypto-aes Cycles:10 Average:87.00 Median:87.00 stddev:1.76 (2.0%) stddev-sans-first:1.83 06:20:20 INFO - PROCESS | 1665 | Values: 88.0 86.0 86.0 86.0 86.0 86.0 88.0 85.0 88.0 91.0 06:20:20 INFO - PROCESS | 1665 | 06:20:20 INFO - PROCESS | 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:159.90 Median:157.00 stddev:21.44 (13.7%) stddev-sans-first:22.38 06:20:20 INFO - PROCESS | 1665 | Values: 149.0 152.0 168.0 160.0 150.0 141.0 148.0 160.0 217.0 154.0 06:20:20 INFO - PROCESS | 1665 | 06:20:20 INFO - PROCESS | 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.00 Median:175.00 stddev:2.67 (1.5%) stddev-sans-first:2.73 06:20:20 INFO - PROCESS | 1665 | Values: 173.0 180.0 179.0 172.0 175.0 175.0 174.0 173.0 173.0 176.0 06:20:20 INFO - PROCESS | 1665 | 06:20:20 INFO - PROCESS | 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.00 Median:69.00 stddev:1.33 (1.9%) stddev-sans-first:1.20 06:20:20 INFO - PROCESS | 1665 | Values: 71.0 70.0 69.0 69.0 69.0 69.0 66.0 70.0 68.0 69.0 06:20:20 INFO - PROCESS | 1665 | -------- Summary: end -------- 06:20:20 INFO - PROCESS | 1665 | 06:20:20 INFO - TEST-INFO | 1665: exit 0 06:20:20 INFO - TEST-OK | kraken | took 59839ms 06:20:20 INFO - SUITE-END | took 1180s 06:20:21 INFO - Completed test suite (00:19:40) 06:20:21 INFO - javascript benchmark 06:20:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3304.966950330496, 3319.10384196267, 3323.7174840138587, 3337.332533493299, 3360.5967283925934, 4469.530699276578, 5007.973365460081, 5614.691191984439, 5690.345610254812, 5746.511546518545, 5729.512991395726, 5752.884942301152, 5758.769649214027, 5760.913586296209, 5773.566982476321, 5173.224016397542, 5226.895462090756, 5239.895202095956, 5276.287701160332, 5288.286081379003, 5260.605454590902, 5297.44376840432, 5309.283246761695, 5311.389190243133, 5315.01672190646, 5214.504622060898, 5256.710880901549, 5265.736713164346, 5292.285541451913, 5292.444293349201, 5302.73486325686, 5319.042572336978, 5320.308359913187, 5328.893422131575, 5389.703566303851, 5102.183650615921, 5119.539241468248, 5149.021685879671, 5240.34495688039, 5242.973785131088, 5647.37878833328, 5664.348599910981, 5715.171300161495, 5724.141378793214, 5745.2818397700285, 6229.844253893643, 6245.6252624842655, 6251.812445626593, 6252.718627661766, 6268.091126786636, 6166.105914642397, 6186.690665466709, 6222.191115155046, 6267.99712046075, 6268.905966410507, 5759.22250496186, 5759.280089988752, 5812.854678633068, 5878.147668588031, 5986.2517185351835, 6239.032949892774, 6244.718987645567, 6256.843578910563, 6262.937370626327, 6277.027060805583, 6050.6369617823075, 6169.784057557964, 6170.136180934583, 6186.81439556814, 6200.945839207346, 6089.926292362085, 6111.419415155553, 6145.877082458326, 6167.828112658581, 6228.5017198624, 6171.537134714914, 6248.50011999039, 6261.311255761862, 6266.342034086355, 6273.059041143865, 6148.262208534915, 6246.500279977591, 6250.624962502174, 6263.843403914848, 6280.05799130125, 4870.634702397334, 4909.975450122726, 4960.528749768766, 4969.403671559364, 5002.124628190125, 4389.890252743643, 4405.911881762347, 4422.491413487426, 4431.468223813163, 4475.865724028285, 5590.742083031286, 5593.412691667317, 5595.160725891149, 5596.384397716248, 5601.971990140023, 6254.124422580842, 6257.311695713468, 6264.060390941304, 6267.0286105653695, 6280.183576135073, 5443.319585051869, 5541.33503979525, 5548.250986116823, 5549.361823390362, 5617.8595535111135, 5308.256844041837, 5335.519803217651, 5367.329083864517, 5379.327584051994, 5421.26812880264, 6358.459530939922, 6365.554411191172, 6370.140031095928, 6378.234611846516, 6378.744850206125, 6098.159935307505, 6100.944524685144, 6137.586222397516, 6236.968815155895, 6261.0, 6123.418275263843, 6190.071489276645, 6264.843378915381, 6278.623282602875, 6280.434760871543, 6205.565610407409, 6222.751089956351, 6224.937750622617, 6235.906461403173, 6251.812445626639, 6194.0708893666315, 6195.411435913581, 6201.193844800147, 6237.220347456568, 6256.186695729526, 5052.46949070357, 6060.363661815622, 6107.643995706967, 6124.663143527148, 6147.678249176449, 5309.893802124013, 6180.876382472416, 6184.134221208944, 6188.071789231652, 6197.938020619736, 6008.669523176267, 6061.302950160788, 6062.939370606237, 6063.0, 6072.696365181811, 5010.549050585465, 6009.759609615567, 6069.969650151721, 6085.969570152298, 6096.116063170904, 5984.940150598438, 6001.969990150022, 6013.037913933749, 6045.244344456943, 6053.485453736482], "value": 5683.451730154526, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [461.1814030096578, 498.2526210683974, 518.0390375852793, 525.7933043664789, 533.1709192206118, 23850.522989540197, 31347.0, 32178.0, 32245.193870153227, 40966.770996870066, 41368.0, 43537.693869183895, 43627.78186109073, 43842.780786096024, 43905.682829515084, 9565.56954937026, 9728.951355243249, 9743.123118919295, 9745.610175592969, 9755.0, 9539.952300238489, 9581.185599224074, 9678.806423871518, 9685.467299298536, 9688.70933871985, 9674.032596740304, 9730.464824434654, 9735.75660608487, 9744.366616169962, 9801.068898454636, 9550.522473876279, 9644.710658680251, 9687.660931867422, 9711.223102151811, 9715.514224288758, 8549.273311768507, 9608.615655373776, 9609.807803843954, 9610.375325603884, 9625.566849491719, 41097.58902410968, 41529.0, 41579.58420415787, 41601.583984160075, 41812.581874181174, 3282.3599398117376, 3365.478350855597, 3495.6504349565093, 3539.4513850353237, 3767.8116094195457, 30467.0, 30921.072367828776, 31744.206394840083, 31813.363732725447, 31866.0, 3214.1321843102464, 3289.802611843297, 3345.6821601947777, 3355.4295769719206, 3375.3418083473734, 485639.0, 486165.5691721554, 488034.0, 488810.0, 499781.0, 60486.0, 60592.39407605868, 60623.393766062654, 60682.78634427287, 60738.785224295265, 60821.39178608246, 63590.682046590395, 63759.0, 63882.0, 63950.36049639537, 15969.520914372355, 16198.433054843024, 16214.918925405298, 16220.675586488203, 16416.1791910404, 12305.93847030777, 12555.309457979716, 12565.0, 12574.937125314314, 12576.496940122475, 14157.716845663235, 14498.637534061732, 16627.66744665124, 16787.83212167887, 16893.831061689227, 5439.428859969726, 6529.608223506605, 6557.311482294291, 6581.638009909501, 6628.933710662928, 637.2703254773259, 727.7002147152808, 777.8016605765607, 939.4738946190155, 1041.1098510773331, 26322.73677263203, 28380.574291385645, 29997.850010749808, 30456.93400730964, 30866.537001944987, 1040.3341861208833, 1045.6967479431032, 1114.4483480677118, 1122.0126288865747, 1230.2618428942742, 15332.616684582752, 15796.289166987513, 15819.440625859319, 15851.286692098884, 16237.944533605514, 534207.0, 536422.0, 537765.0, 539602.0, 541423.0, 59303.0, 59378.81242375128, 59411.40588594145, 59419.108713369336, 59493.70253148706, 1216.7285186979627, 1229.1150371732338, 1275.113795911851, 1322.6957799706122, 1386.2652794019186, 25274.74725252724, 29949.85025074861, 31050.0, 31811.0, 32648.673513265512, 49638.0, 50442.99114017773, 50512.24231636602, 50527.49472505375, 50537.49462505328, 524917.3754131205, 525360.0, 527092.0, 527798.0, 803526.0, 25756.742432576182, 25873.870630646725, 26159.869200653873, 26211.344716382242, 26250.86874565615, 46805.06389872252, 47054.52945470638, 47210.29184562303, 48216.5178348212, 48793.756031219615, 39091.6090839088, 39282.60717392789, 39522.0, 39924.40113398359, 40759.59240407558, 38742.80628596839, 39032.41451378175, 39080.804595976835, 40226.79319620416, 40380.788576342755, 38056.61943380531, 38711.806440967615, 38795.8361249163, 38828.80585597054, 38910.4163437543, 32014.51978220282, 33188.200772818855, 33299.00205987648, 34191.0, 34192.0, 25207.99168033258, 25329.98680052851, 25806.967721290937, 25864.612030819928, 25990.87004564965], "value": 19600.721815424396, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.77405025657936, 352.79361573479514, 360.1554355037437, 378.31336124933244, 405.8052134975212, 15007.024543404672, 15222.847771522296, 16211.351545938163, 20424.38726838197, 20773.27293544726, 19746.30887918923, 20183.09176087076, 20655.27706530268, 20950.26674066408, 21296.787032129672, 5329.147336426173, 5791.7972870949525, 6090.756369745205, 6096.4817990470865, 6101.986344506325, 5859.44335288147, 5972.641641501502, 5980.88038239235, 6040.063790112539, 6043.123747056674, 5664.178694089354, 5886.352501224862, 5906.468417842393, 5976.4919981801595, 6016.0975853622085, 5810.099434587646, 5887.558433117512, 5958.046712526018, 5963.0, 5970.940290597082, 5165.66423182492, 5167.09575824233, 5170.715610641413, 5419.3225846769155, 5500.724963751836, 24350.147744829024, 24744.473377749517, 25059.87470062638, 25271.36821579457, 25451.23646290614, 2142.2609199826193, 2282.897269622871, 2457.3242358351417, 2515.2077095714976, 2833.1925401260746, 8361.372897032747, 9730.610775568968, 9959.402435853799, 10377.014183652616, 15080.170590617512, 1494.8430414806505, 1799.6040871008356, 1827.5979284557377, 1831.4963385069213, 1872.1481725814722, 82548.58725706393, 105946.47026764817, 115461.0, 118046.0, 119509.0, 11359.375234362024, 11504.42477876103, 11715.589954351557, 11843.170978031456, 11844.88155118455, 14356.425742970165, 14378.784318235235, 14438.783418248524, 14526.273686315642, 14559.708805824037, 7314.817129571803, 8214.712485062993, 8349.248567628943, 8546.487210767249, 8597.312215022783, 7007.649617519103, 8180.550069746221, 8317.750467485985, 8353.916460835435, 8456.32349412058, 4456.3538286948215, 4529.433820772403, 4545.454545454567, 4598.839040633562, 4598.88502787433, 1892.139076720089, 1939.486036200408, 1941.4563922101834, 1951.3950675290705, 1951.4243298227057, 837.4807619276088, 904.1952662130709, 931.7297983584684, 1008.369768894441, 1045.813002242445, 20088.283667521555, 20726.58546829055, 20729.68905466419, 20811.791882080986, 20910.47723806917, 933.3746389918771, 962.9470379129215, 973.3673112476947, 993.8111758765812, 1006.5857470254305, 10998.505067271992, 11939.940300298616, 12143.239230311556, 12162.75674486523, 12506.687332816571, 261375.0, 273946.0, 525976.0, 610937.0, 614114.8588514058, 138165.0, 139956.0, 141312.0, 144250.0, 147973.26013369864, 1019.4851599942131, 1066.6106870992187, 1111.7832022755485, 1112.0603090388609, 1113.4766659669942, 19132.521686957938, 19340.613187735886, 19380.32168874139, 19668.01659917027, 19796.307129250406, 56629.86740265254, 57010.42989570051, 57130.42869571251, 57732.0, 57930.0, 77174.58143150827, 79646.80529791942, 81830.59084704539, 86371.0, 86876.56561717151, 7856.135825059125, 8087.514749115072, 8243.793905152419, 8260.05009423924, 8296.128906464734, 12937.676558086123, 13266.53567125146, 13376.732465350833, 13530.72938541204, 13615.319234038061, 10336.483175841027, 11167.609133680282, 11238.269512481662, 11602.76794464123, 11684.357360345262, 10135.834379046504, 11177.66467005991, 11291.548338066714, 11394.17693169089, 11630.767384652429, 11013.559457621606, 11563.0, 11639.518785267564, 11889.464974076014, 11994.400279985792, 7884.6057697113765, 7946.205379462008, 8144.30773384269, 8411.747647570583, 8430.0, 8380.12008739098, 10138.847917281395, 11539.423028848692, 11786.469608867794, 11899.76200476003], "value": 9582.583397034801, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1385.3488860235686, 1546.5051183621238, 1590.00624609619, 1825.1969133581224, 1982.355734386325, 7742.9612851935735, 8212.466189697672, 8537.701180458693, 8693.69572064978, 8818.735437936855, 8616.181462761027, 8705.608247628856, 8709.825803483942, 8711.520866352348, 8717.520536370497, 2336.264076815806, 2366.9171578994738, 2368.4789346343778, 2369.869657168864, 2377.3105799318178, 2310.1914329984456, 2342.121704360865, 2372.5017746273215, 2373.881305934705, 2374.0741110966737, 2348.7181538215436, 2375.228050883461, 2376.1683410806254, 2382.2614989353356, 2385.630227314769, 2317.571249318881, 2341.203990643181, 2345.6950596422525, 2348.0959830465395, 2361.9881900590385, 2315.0, 2317.3974766560646, 2317.5133222023396, 2322.547103314854, 2329.77867102625, 8570.971483421967, 8651.091635378258, 8748.737537873874, 8761.123887611251, 8784.64861405543, 5128.333316668809, 5171.120909445403, 5336.706481143536, 5338.866528336784, 5377.0, 3464.7574669773126, 3577.94633080504, 3654.1960768631125, 3661.4324779659196, 3677.2829298286847, 925.3528718880403, 1082.691432941608, 1100.2463312630794, 1106.7675788084512, 1107.8061339265596, 11068.335899845955, 11396.829047564293, 11442.599509017227, 11453.65639030822, 11559.364234967074, 4361.781910904442, 4469.173202957463, 4550.407357424875, 4591.9081618367445, 4594.081183763261, 9016.549172541347, 9031.232345250595, 9033.728988130366, 9034.954825225963, 9050.728478145666, 5212.113940630102, 5224.9738751306, 5236.450172731885, 5243.68537887728, 5256.500632439988, 4372.715773474714, 4384.561543845653, 4523.208438523246, 4706.247000479907, 4888.511148885154, 3652.7990960497427, 3742.401215805458, 3791.867284645024, 3804.0, 3880.9223815524097, 1140.931544107356, 1157.5311998640564, 1171.6016554371554, 1179.7994340962057, 1181.9670167312208, 878.4685265414332, 901.1439132823843, 901.4230892228978, 912.8493798523302, 945.9337846350827, 5571.13647384656, 5577.470140336662, 5844.476475957243, 6045.244344456943, 6046.818595442144, 424.57966613052855, 437.58866665334483, 443.255331043843, 447.25531989237555, 449.2385406735617, 3758.0, 3868.825902834379, 3922.646961773454, 4006.45912801768, 4014.879553613396, 10184.643537476153, 10497.107745841537, 10579.84130238047, 10582.735431614272, 10586.894131058745, 8914.821703566022, 10066.798664026679, 10558.049775520087, 10573.524191411405, 10574.365538067741, 6937.410320122745, 8154.8776768348525, 8167.264946154874, 8177.632006559719, 8190.8771368429525, 4958.7024778513405, 5105.846824595268, 5648.971755141362, 5707.571932105026, 5753.971230143822, 9584.185344245818, 9605.759856003655, 9832.60669573209, 9906.0, 9960.103590676874, 8949.686760963335, 9829.066238707312, 9961.153301969123, 10082.8487572685, 10141.594336226763, 6501.837454063497, 6716.328367163244, 6785.982102684637, 7080.929190708233, 7094.680739366844, 7614.466987310835, 8314.833703326021, 8380.958095209486, 8496.787580310294, 8516.105808890225, 7330.450216233698, 7447.813804654927, 7448.441366897396, 7511.661975211019, 7577.393808495197, 7070.257622949722, 7265.600391978492, 7309.963450182715, 7349.338559529668, 7411.110666720028, 6840.863182736417, 6887.965560172167, 6943.027976083454, 6954.0, 6959.860802784017, 4885.4870238624435, 4925.261210818406, 4982.701037937591, 4983.651144419856, 5041.0, 4819.638527110551, 4855.1260773061185, 4909.975450122726, 4934.358533390637, 4960.528749768766], "value": 4407.9217816879345, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [260.5830670926518, 297.1427431859087, 306.30468835742863, 311.9610048743907, 314.2301361663924, 4127.876163715089, 4270.017895883949, 5303.867403314919, 5314.681119132854, 5335.4131045585, 4093.0995181060193, 4478.8880277992985, 4925.817803727097, 5054.3429354184, 5108.8978220435565, 4030.1939612077554, 4047.392891066349, 4053.4394258210655, 4053.8649178229994, 4086.5913408659044, 3376.9700241426353, 3860.613938606145, 4054.4121102440276, 4078.9796051019703, 4094.9181016379803, 3255.209634700917, 3330.4671252599587, 3502.8248587570647, 3724.459953306769, 4029.8313489088127, 3604.477350784148, 3728.422094575345, 4010.7794071326066, 4022.4368588397647, 4025.933127721171, 3172.4924012157944, 3223.8549265282886, 3480.7041401480906, 3501.2822371414054, 3526.347625689256, 4954.405471343426, 5021.322121513622, 5075.746212689351, 5083.415407228142, 5116.565091967187, 4550.817967281305, 4599.44806623204, 4607.746573938432, 4637.6289896808175, 4643.442786865564, 5550.86122846928, 5553.055980483327, 5554.583406244547, 5557.722113894289, 5625.80309689163, 3722.3858063419502, 3816.0650640592953, 3861.073342397832, 3867.1878905429894, 3884.4173373993845, 4919.3358896549225, 5760.683162426065, 5766.88466230673, 5780.653160810365, 5814.680192589406, 3496.8950931471586, 3632.3461776880417, 3714.7741245388956, 3763.2285381496727, 3909.8886601235013, 3949.3483575210344, 3977.2443235785686, 3978.542467616261, 3986.4054378248934, 4027.7784721839994, 3865.7771781225906, 4202.978985105116, 4398.722951401162, 4586.220342541776, 4632.351470794091, 5551.639848237208, 5557.360903496069, 5564.777408903598, 5568.9443105569235, 5586.692731899703, 1298.5909438526835, 1325.6089453611207, 1331.6870535424168, 1354.959351219465, 1360.9931950340383, 726.5204964723257, 757.9924200758032, 895.3911340288666, 899.3704406915185, 929.9674511392069, 4087.2847251730827, 4504.527024662429, 5011.974940125349, 5013.069968062322, 5101.744912754348, 5569.27599412082, 5569.749361278671, 5580.665160090326, 5583.972080139654, 5598.412166722517, 4029.0, 4828.855134345975, 4893.877653058702, 5064.240363945364, 5086.440491545927, 2473.5547601431913, 2731.3717844895787, 2760.4755096531594, 2906.052944527172, 2936.6769655337894, 4893.926591101136, 5109.463506331856, 5659.754853932211, 5813.273340832396, 5842.2405087338375, 4558.108385020246, 5414.047745146066, 5721.942780572141, 5745.6552606843725, 5754.367019627837, 5086.685558071143, 5734.885302293931, 5757.827265182052, 5774.393688662714, 5811.651300921958, 4408.060453400622, 5106.7613880478375, 5459.2902922619805, 5596.720163991866, 5601.719914004365, 5486.451354864482, 5507.807226747044, 5525.530329922001, 5527.391986881359, 5536.280283563111, 4839.491853355347, 5455.045367060828, 5720.599558030898, 5773.884522309614, 5791.536677065909, 3704.0, 3713.424419215026, 3870.8194000830017, 3901.4537964685533, 3925.7644541327613, 4887.437944636412, 5570.86072848182, 5591.496765291144, 5604.411536788579, 5662.575306852085, 4969.975150124226, 5440.755166017618, 5455.727213639223, 5508.44915508446, 5510.685512085014, 4166.41670166183, 4510.819567217274, 4669.953300466952, 5374.408815030265, 5420.7560659769615, 4606.26299792045, 5006.6495345325475, 5139.8458046258675, 5309.681419114865, 5329.308699107081, 4549.340345649928, 4728.642663708805, 5386.784528618811, 5412.918806217988, 5425.294711687456, 5452.5365343946205, 5463.344398672026, 5465.699386533779, 5467.890642187054, 5502.312210973629], "value": 3983.2417306509446, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [778.3567253491451, 778.9298963093321, 782.3545574900709, 808.4906508899393, 813.9959300203498, 15274.84725152748, 15906.125163116052, 15937.521874343774, 15950.92637957064, 15963.680726385466, 14398.49605263816, 14477.276136193175, 14483.91370647203, 14499.855001449982, 14500.782488262657, 698.8287869471986, 721.7654262364726, 722.8554289142193, 873.5021038008338, 894.9328800339968, 871.1332224436662, 886.5655828643951, 903.8418276801566, 905.9094090590921, 906.4742449379344, 918.8300164469562, 919.9402038867486, 936.7611259128912, 960.2653969713175, 964.4647220792457, 736.8121129112096, 757.0461218864216, 913.5980168725773, 922.6724512797928, 954.4988880837547, 703.142166556805, 704.6828926982878, 705.9505834591581, 707.2114592229627, 710.0946293475808, 14679.0, 15118.244087795567, 15146.318415671321, 15168.24158792056, 15179.089254644647, 13417.66455838612, 13879.0, 13979.860201397958, 14032.649183770385, 14109.78835317471, 12687.238765673997, 12793.55222567215, 12917.160384574972, 12969.351532423436, 13403.994700397508, 4413.757243351614, 4451.332300154971, 4470.75410852403, 4557.384753058328, 4616.976915115437, 111984.0, 112888.8400044, 113062.0, 114006.0, 116507.0, 11145.052670522935, 11185.384803835705, 11256.437178141061, 11303.604373846876, 11351.60269390584, 20164.798352016292, 21062.47343816417, 22880.771192288197, 22899.885500572724, 22907.0, 7731.806704832424, 7830.569318687527, 7888.2506161914525, 7969.442139050211, 8033.875257463959, 4379.737215767064, 4437.556244375537, 4441.289393697058, 4455.710378825366, 4535.115652447741, 3594.388953877847, 3657.122290650278, 3682.9447558286647, 3756.436534519844, 3763.5107436033686, 671.5198632977347, 675.6148995072762, 679.2697849811374, 679.755288096285, 695.2526034512919, 440.2779441715589, 634.3783092569216, 654.842837718944, 681.6693903456819, 694.8992396102536, 7238.565686058748, 8398.118197589165, 8553.74338769838, 8849.867251991225, 9095.454272743656, 628.6856571714143, 628.82096069869, 629.4460874430472, 661.4939571228015, 661.8510835062073, 4355.73865568067, 4399.098184872094, 4452.398926144929, 4543.54564543543, 5037.647364684437, 146886.0, 149281.25359373132, 150069.0, 155755.4424455741, 156672.38301893277, 95368.52315738377, 128302.0, 129326.0, 130892.0, 133712.0, 90318.54840726017, 98445.50777246068, 98493.0, 99855.0, 101749.0, 8148.0, 8487.0, 9065.138811812867, 9624.951875240578, 9627.374220675634, 42949.35575966426, 56192.71903640455, 56198.0, 57006.7149664249, 58404.4159558416, 61492.69253653703, 79058.6047069761, 89702.55148724215, 91736.54131729298, 92976.0, 13422.798658020332, 13515.662108447368, 13518.83698608218, 14248.430062797372, 14454.710905782036, 15209.847901521285, 15258.847411525743, 15265.542033739006, 15359.232038397811, 15483.5354939352, 12015.283472500905, 12294.262344259372, 13749.931250344085, 14196.645083872985, 14437.639059023608, 13899.0, 14107.717845642825, 14334.996550241383, 14630.293395721399, 14671.25556668602, 13647.522336718168, 13882.958778091886, 13991.93004035014, 14229.14625122496, 14460.27698615086, 10199.13307368882, 10675.985781350759, 11501.194916355775, 11636.476358564054, 11754.52297927177, 8447.746567602982, 9297.442153470813, 9932.453715045742, 10559.155267578422, 10858.185636077484], "value": 7838.287910175098, "unit": "score"}], "name": "dromaeo_css", "value": 7264.144165900191, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 114.0, 113.0, 112.0, 113.0, 113.0, 113.0, 113.0, 114.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 113.0, 115.0, 150.0, 114.0, 115.0, 112.0, 114.0, 145.0, 112.0], "value": 124.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 184.0, 182.0, 183.0, 185.0, 183.0, 181.0, 181.0, 186.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 60.0, 61.0, 88.0, 60.0, 60.0, 60.0, 59.0, 85.0, 58.0], "value": 67.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 95.0, 71.0, 70.0, 71.0, 93.0, 68.0, 68.0, 90.0], "value": 78.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 86.0, 89.0, 85.0, 89.0, 87.0, 89.0, 86.0], "value": 87.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 97.0, 97.0, 96.0, 96.0, 96.0, 96.0, 113.0, 138.0], "value": 102.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 80.0, 77.0, 122.0, 123.0, 100.0, 83.0, 81.0, 83.0], "value": 91.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 54.0, 54.0, 53.0, 55.0, 56.0, 57.0, 54.0, 55.0, 56.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 70.0, 82.0, 109.0, 105.0, 98.0, 70.0, 71.0, 70.0, 70.0], "value": 81.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 86.0, 86.0, 86.0, 86.0, 88.0, 85.0, 88.0, 91.0], "value": 87.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 152.0, 168.0, 160.0, 150.0, 141.0, 148.0, 160.0, 217.0, 154.0], "value": 159.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 180.0, 179.0, 172.0, 175.0, 175.0, 174.0, 173.0, 173.0, 176.0], "value": 175.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 69.0, 69.0, 69.0, 69.0, 66.0, 70.0, 68.0, 69.0], "value": 69.0, "unit": "score"}], "name": "kraken", "value": 1474.0, "alertThreshold": 2.0}]} 06:20:21 INFO - Return code: 0 06:20:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:20:21 INFO - # TBPL SUCCESS # 06:20:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:20:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:20:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:20:21 INFO - 2016-10-14 06:20:21.421 screenresolution[1669:10044] starting screenresolution argv=screenresolution get 06:20:21 INFO - 2016-10-14 06:20:21.423 screenresolution[1669:10044] Display 0: 1600x1200x32@60 06:20:21 INFO - 2016-10-14 06:20:21.429 screenresolution[1670:10047] starting screenresolution argv=screenresolution list 06:20:21 INFO - Available Modes on Display 0 06:20:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:20:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:20:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:20:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:20:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:20:21 INFO - Intel Iris: 06:20:21 INFO - Chipset Model: Intel Iris 06:20:21 INFO - Type: GPU 06:20:21 INFO - Bus: Built-In 06:20:21 INFO - VRAM (Dynamic, Max): 1536 MB 06:20:21 INFO - Vendor: Intel (0x8086) 06:20:21 INFO - Device ID: 0x0a2e 06:20:21 INFO - Revision ID: 0x0009 06:20:21 INFO - Displays: 06:20:21 INFO - Display: 06:20:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:20:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:20:21 INFO - Display Serial Number: 1600x1200 60 06:20:21 INFO - Main Display: Yes 06:20:21 INFO - Mirror: Off 06:20:21 INFO - Online: Yes 06:20:21 INFO - Rotation: Supported 06:20:21 INFO - Return code: 0 06:20:21 INFO - Running post-action listener: _resource_record_post_action 06:20:21 INFO - [mozharness: 2016-10-14 13:20:21.670106Z] Finished run-tests step (success) 06:20:21 INFO - Running post-run listener: _resource_record_post_run 06:20:21 INFO - Total resource usage - Wall time: 1215s; CPU: 54.0%; Read bytes: 37336064; Write bytes: 708132864; Read time: 242; Write time: 1979 06:20:21 INFO - TinderboxPrint: CPU usage
53.6% 06:20:21 INFO - TinderboxPrint: I/O read bytes / time
37,336,064 / 242 06:20:21 INFO - TinderboxPrint: I/O write bytes / time
708,132,864 / 1,979 06:20:21 INFO - TinderboxPrint: CPU idle
2,257.2 (46.4%) 06:20:21 INFO - TinderboxPrint: CPU system
974.4 (20.0%) 06:20:21 INFO - TinderboxPrint: CPU user
1,630.1 (33.5%) 06:20:21 INFO - TinderboxPrint: Swap in / out
445,292,544 / 0 06:20:21 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 392727040; Write bytes: 385353728; Read time: 29298; Write time: 814 06:20:21 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 36381696; Write bytes: 296937472; Read time: 230; Write time: 1100 06:20:22 INFO - Running post-run listener: _upload_blobber_files 06:20:22 INFO - Blob upload gear active. 06:20:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:20:22 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 06:20:22 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:20:22 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:20:22 INFO - (blobuploader) - INFO - Open directory for files ... 06:20:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:20:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:20:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:20:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:20:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:20:23 INFO - (blobuploader) - INFO - Done attempting. 06:20:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:20:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:20:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:20:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:20:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:20:24 INFO - (blobuploader) - INFO - Done attempting. 06:20:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:20:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:20:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:20:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:20:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:20:26 INFO - (blobuploader) - INFO - Done attempting. 06:20:26 INFO - (blobuploader) - INFO - Iteration through files over. 06:20:26 INFO - Return code: 0 06:20:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:20:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:20:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fec1412a7cce9d2e9df2d0d4aee5a2b79616996b31fd080466c552caa30f054e50213120c9e6c5b584aaf230899a8bc54a8586101dbbcda8a462318e8058f952", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7ea4e4c6dd6e8e1b65ae15d61a4d9fccbdda33b401a0bb29d23c98454b39d4229ed6c1f84022ee0bfebbd20849c8d20615b72aa115b16d3bec0ba376b5d96554", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9c9a7b0bc93925931c192a8d787beb3b4f4508915b56712e4eb984106218864dfafff0c9c523d770a41b87e6e1ed42df83f87be680fc368c1b7d3c8669bbb0ad"} 06:20:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:20:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:20:26 INFO - Contents: 06:20:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fec1412a7cce9d2e9df2d0d4aee5a2b79616996b31fd080466c552caa30f054e50213120c9e6c5b584aaf230899a8bc54a8586101dbbcda8a462318e8058f952", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7ea4e4c6dd6e8e1b65ae15d61a4d9fccbdda33b401a0bb29d23c98454b39d4229ed6c1f84022ee0bfebbd20849c8d20615b72aa115b16d3bec0ba376b5d96554", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9c9a7b0bc93925931c192a8d787beb3b4f4508915b56712e4eb984106218864dfafff0c9c523d770a41b87e6e1ed42df83f87be680fc368c1b7d3c8669bbb0ad"} 06:20:26 INFO - Running post-run listener: copy_logs_to_upload_dir 06:20:26 INFO - Copying logs to upload dir... 06:20:26 INFO - mkdir: /builds/slave/test/build/upload/logs 06:20:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.739213 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-10-14 06:20:26.957686) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-14 06:20:26.961306) ========= 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.v9QLUcJFXG/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.ZpualKetCg/Listeners TMPDIR=/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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/Fx-Team/sha512/fec1412a7cce9d2e9df2d0d4aee5a2b79616996b31fd080466c552caa30f054e50213120c9e6c5b584aaf230899a8bc54a8586101dbbcda8a462318e8058f952", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7ea4e4c6dd6e8e1b65ae15d61a4d9fccbdda33b401a0bb29d23c98454b39d4229ed6c1f84022ee0bfebbd20849c8d20615b72aa115b16d3bec0ba376b5d96554", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9c9a7b0bc93925931c192a8d787beb3b4f4508915b56712e4eb984106218864dfafff0c9c523d770a41b87e6e1ed42df83f87be680fc368c1b7d3c8669bbb0ad"} build_url:https://queue.taskcluster.net/v1/task/Ls7i0ARgScyPMt4Yl5O_DA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011324 build_url: 'https://queue.taskcluster.net/v1/task/Ls7i0ARgScyPMt4Yl5O_DA/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fec1412a7cce9d2e9df2d0d4aee5a2b79616996b31fd080466c552caa30f054e50213120c9e6c5b584aaf230899a8bc54a8586101dbbcda8a462318e8058f952", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7ea4e4c6dd6e8e1b65ae15d61a4d9fccbdda33b401a0bb29d23c98454b39d4229ed6c1f84022ee0bfebbd20849c8d20615b72aa115b16d3bec0ba376b5d96554", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9c9a7b0bc93925931c192a8d787beb3b4f4508915b56712e4eb984106218864dfafff0c9c523d770a41b87e6e1ed42df83f87be680fc368c1b7d3c8669bbb0ad"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-14 06:20:26.993817) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 06:20:26.994124) ========= 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.v9QLUcJFXG/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.ZpualKetCg/Listeners TMPDIR=/var/folders/4j/mnc9qf551_bgstm4l6ck0qh400000w/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.005182 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 06:20:27.043051) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-14 06:20:27.043386) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-14 06:20:27.043752) ========= ========= Total master_lag: 0.70 =========