builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0034 starttime: 1470756586.66 results: success (0) buildid: 20160809064320 builduid: 4d73ae11f4f44869bd5fe66d6e0c21c8 revision: 643a67968688ec383af3d008ef081a6939a0cb53 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 08:29:46.662605) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 08:29:46.663019) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 08:29:46.663289) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 08:29:46.693258) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 08:29:46.693619) ========= 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.RBWOKyL2IP/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.OvwRdOdxFL/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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-08-09 08:29:46-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 27.5M=0s 2016-08-09 08:29:46 (27.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.121848 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 08:29:46.841192) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 08:29:46.841528) ========= 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.RBWOKyL2IP/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.OvwRdOdxFL/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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.027378 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 08:29:46.892289) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-09 08:29:46.892625) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 643a67968688ec383af3d008ef081a6939a0cb53 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 643a67968688ec383af3d008ef081a6939a0cb53 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RBWOKyL2IP/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.OvwRdOdxFL/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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-08-09 08:29:46,976 truncating revision to first 12 chars 2016-08-09 08:29:46,977 Setting DEBUG logging. 2016-08-09 08:29:46,977 attempt 1/10 2016-08-09 08:29:46,977 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/643a67968688?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-09 08:29:48,117 unpacking tar archive at: mozilla-central-643a67968688/testing/mozharness/ program finished with exit code 0 elapsedTime=1.406118 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-09 08:29:48.322043) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 08:29:48.322407) ========= script_repo_revision: 643a67968688ec383af3d008ef081a6939a0cb53 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 08:29:48.326881) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 08:29:48.327195) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 08:29:48.348377) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-08-09 08:29:48.348836) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RBWOKyL2IP/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.OvwRdOdxFL/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 08:29:48 INFO - MultiFileLogger online at 20160809 08:29:48 in /builds/slave/test 08:29:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:29:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:29:48 INFO - {'append_to_log': False, 08:29:48 INFO - 'base_work_dir': '/builds/slave/test', 08:29:48 INFO - 'blob_upload_branch': 'Firefox', 08:29:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:29:48 INFO - 'branch': 'Firefox', 08:29:48 INFO - 'buildbot_json_path': 'buildprops.json', 08:29:48 INFO - 'config_files': ('talos/mac_config.py',), 08:29:48 INFO - 'default_actions': ('clobber', 08:29:48 INFO - 'read-buildbot-config', 08:29:48 INFO - 'download-and-extract', 08:29:48 INFO - 'populate-webroot', 08:29:48 INFO - 'create-virtualenv', 08:29:48 INFO - 'install', 08:29:48 INFO - 'run-tests'), 08:29:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:29:48 INFO - 'download_minidump_stackwalk': True, 08:29:48 INFO - 'download_symbols': 'ondemand', 08:29:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:29:48 INFO - 'tooltool.py': '/tools/tooltool.py', 08:29:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:29:48 INFO - '/tools/misc-python/virtualenv.py')}, 08:29:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:29:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:29:48 INFO - 'installer_path': 'installer.exe', 08:29:48 INFO - 'log_level': 'info', 08:29:48 INFO - 'log_name': 'talos', 08:29:48 INFO - 'log_to_console': True, 08:29:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:29:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:29:48 INFO - 'opt_config_files': (), 08:29:48 INFO - 'pip_index': False, 08:29:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:29:48 INFO - 'cmd': ('bash', 08:29:48 INFO - '-c', 08:29:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:29:48 INFO - 'enabled': True, 08:29:48 INFO - 'halt_on_failure': False, 08:29:48 INFO - 'name': 'check_screen_resolution'},), 08:29:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:29:48 INFO - 'cmd': ('bash', 08:29:48 INFO - '-c', 08:29:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:29:48 INFO - 'enabled': True, 08:29:48 INFO - 'halt_on_failure': False, 08:29:48 INFO - 'name': 'check_screen_resolution'},), 08:29:48 INFO - 'run_cmd_checks_enabled': True, 08:29:48 INFO - 'sps_profile': False, 08:29:48 INFO - 'sps_profile_interval': 0, 08:29:48 INFO - 'suite': 'dromaeojs-e10s', 08:29:48 INFO - 'system_bits': '32', 08:29:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:29:48 INFO - 'title': 't-yosemite-r7-0034', 08:29:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:29:48 INFO - 'use_talos_json': True, 08:29:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:29:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:29:48 INFO - 'work_dir': 'build'} 08:29:48 INFO - [mozharness: 2016-08-09 15:29:48.560238Z] Running clobber step. 08:29:48 INFO - Running pre-action listener: _resource_record_pre_action 08:29:48 INFO - Running main action method: clobber 08:29:48 INFO - rmtree: /builds/slave/test/build 08:29:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:29:50 INFO - Running post-action listener: _resource_record_post_action 08:29:50 INFO - [mozharness: 2016-08-09 15:29:50.859751Z] Finished clobber step (success) 08:29:50 INFO - [mozharness: 2016-08-09 15:29:50.859965Z] Running read-buildbot-config step. 08:29:50 INFO - Running pre-action listener: _resource_record_pre_action 08:29:50 INFO - Running main action method: read_buildbot_config 08:29:50 INFO - Using buildbot properties: 08:29:50 INFO - { 08:29:50 INFO - "project": "", 08:29:50 INFO - "product": "firefox", 08:29:50 INFO - "script_repo_revision": "production", 08:29:50 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:29:50 INFO - "repository": "", 08:29:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:29:50 INFO - "buildid": "20160809064320", 08:29:50 INFO - "pgo_build": "False", 08:29:50 INFO - "basedir": "/builds/slave/test", 08:29:50 INFO - "buildnumber": 13, 08:29:50 INFO - "slavename": "t-yosemite-r7-0034", 08:29:50 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 08:29:50 INFO - "platform": "yosemite_r7", 08:29:50 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:29:50 INFO - "branch": "mozilla-central", 08:29:50 INFO - "repo_path": "mozilla-central", 08:29:50 INFO - "revision": "643a67968688ec383af3d008ef081a6939a0cb53", 08:29:50 INFO - "stage_platform": "macosx64", 08:29:50 INFO - "builduid": "4d73ae11f4f44869bd5fe66d6e0c21c8", 08:29:50 INFO - "slavebuilddir": "test" 08:29:50 INFO - } 08:29:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 08:29:50 INFO - Running post-action listener: _resource_record_post_action 08:29:50 INFO - [mozharness: 2016-08-09 15:29:50.863352Z] Finished read-buildbot-config step (success) 08:29:50 INFO - [mozharness: 2016-08-09 15:29:50.863496Z] Running download-and-extract step. 08:29:50 INFO - Running pre-action listener: _resource_record_pre_action 08:29:50 INFO - Running main action method: download_and_extract 08:29:50 INFO - mkdir: /builds/slave/test/build/tests 08:29:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:29:50 INFO - trying https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 08:29:50 INFO - Downloading https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 08:29:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 08:29:52 INFO - Downloaded 1199 bytes. 08:29:52 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 08:29:52 INFO - Using the following test package requirements: 08:29:52 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 08:29:52 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:29:52 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 08:29:52 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:29:52 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 08:29:52 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:29:52 INFO - u'jsshell-mac.zip'], 08:29:52 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:29:52 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 08:29:52 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 08:29:52 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:29:52 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 08:29:52 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:29:52 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 08:29:52 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:29:52 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 08:29:52 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:29:52 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 08:29:52 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:29:52 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')]} 08:29:52 INFO - trying https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 08:29:52 INFO - Downloading https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 08:29:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:29:53 INFO - Downloaded 16324531 bytes. 08:29:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:29:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 08:29:56 INFO - Return code: 0 08:29:56 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:29:56 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')]} 08:29:56 INFO - trying https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 08:29:56 INFO - Downloading https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 08:29:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:29:57 INFO - Downloaded 16324531 bytes. 08:29:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:29:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 08:30:00 INFO - Return code: 0 08:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:00 INFO - trying https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 08:30:00 INFO - Downloading https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 08:30:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:30:01 INFO - Downloaded 14413723 bytes. 08:30:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:30:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 08:30:01 INFO - Return code: 0 08:30:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:01 INFO - trying https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:30:01 INFO - Downloading https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 08:30:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 08:30:05 INFO - Downloaded 111951282 bytes. 08:30:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:30:05 INFO - mkdir: /builds/slave/test/properties 08:30:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:30:05 INFO - Writing to file /builds/slave/test/properties/build_url 08:30:05 INFO - Contents: 08:30:05 INFO - build_url:https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:30:06 INFO - Running post-action listener: _resource_record_post_action 08:30:06 INFO - Running post-action listener: set_extra_try_arguments 08:30:06 INFO - [mozharness: 2016-08-09 15:30:06.032102Z] Finished download-and-extract step (success) 08:30:06 INFO - [mozharness: 2016-08-09 15:30:06.032297Z] Running populate-webroot step. 08:30:06 INFO - Running pre-action listener: _resource_record_pre_action 08:30:06 INFO - Running main action method: populate_webroot 08:30:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:30:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:30:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:30:06 INFO - '--tppagecycles', 08:30:06 INFO - '7'], 08:30:06 INFO - 'tests': ['tsvgm']}}, 08:30:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:30:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:30:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:30:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:30:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:30:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:30:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:06 INFO - 'talos_options': ['--disable-e10s'], 08:30:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:30:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:30:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:06 INFO - 'talos_options': ['--disable-e10s'], 08:30:06 INFO - 'tests': ['damp', 'tps']}, 08:30:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:06 INFO - 'tests': ['damp', 'tps']}, 08:30:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:30:06 INFO - 'tests': ['dromaeo_dom']}, 08:30:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:30:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:30:06 INFO - 'tests': ['basic_compositor_video']}, 08:30:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:30:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:30:06 INFO - 'tests': ['a11yr', 08:30:06 INFO - 'ts_paint', 08:30:06 INFO - 'tpaint', 08:30:06 INFO - 'sessionrestore', 08:30:06 INFO - 'sessionrestore_no_auto_restore', 08:30:06 INFO - 'tabpaint']}, 08:30:06 INFO - 'other-e10s': {'tests': ['a11yr', 08:30:06 INFO - 'ts_paint', 08:30:06 INFO - 'tpaint', 08:30:06 INFO - 'sessionrestore', 08:30:06 INFO - 'sessionrestore_no_auto_restore', 08:30:06 INFO - 'tabpaint']}, 08:30:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:30:06 INFO - 'ts_paint', 08:30:06 INFO - 'tpaint', 08:30:06 INFO - 'sessionrestore', 08:30:06 INFO - 'sessionrestore_no_auto_restore', 08:30:06 INFO - 'tabpaint']}, 08:30:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:30:06 INFO - 'ts_paint', 08:30:06 INFO - 'tpaint', 08:30:06 INFO - 'sessionrestore', 08:30:06 INFO - 'sessionrestore_no_auto_restore', 08:30:06 INFO - 'tabpaint']}, 08:30:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:30:06 INFO - 'tests': ['a11yr', 08:30:06 INFO - 'ts_paint', 08:30:06 INFO - 'tpaint', 08:30:06 INFO - 'sessionrestore', 08:30:06 INFO - 'sessionrestore_no_auto_restore', 08:30:06 INFO - 'tabpaint']}, 08:30:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:30:06 INFO - 'tests': ['a11yr', 08:30:06 INFO - 'ts_paint', 08:30:06 INFO - 'tpaint', 08:30:06 INFO - 'sessionrestore', 08:30:06 INFO - 'sessionrestore_no_auto_restore', 08:30:06 INFO - 'tabpaint']}, 08:30:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:30:06 INFO - 'tests': ['tsvgx', 08:30:06 INFO - 'tsvgr_opacity', 08:30:06 INFO - 'tart', 08:30:06 INFO - 'tscrollx', 08:30:06 INFO - 'cart']}, 08:30:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:30:06 INFO - 'tsvgr_opacity', 08:30:06 INFO - 'tart', 08:30:06 INFO - 'tscrollx', 08:30:06 INFO - 'cart']}, 08:30:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:06 INFO - 'talos_options': ['--disable-e10s'], 08:30:06 INFO - 'tests': ['tp5o']}, 08:30:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:06 INFO - 'tests': ['tp5o']}, 08:30:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:06 INFO - 'talos_options': ['--disable-e10s', 08:30:06 INFO - '--xperf_path', 08:30:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:30:06 INFO - 'tests': ['tp5n']}, 08:30:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:30:06 INFO - 'talos_options': ['--xperf_path', 08:30:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:30:06 INFO - 'tests': ['tp5n']}}, 08:30:06 INFO - 'talos.zip': {'path': '', 08:30:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:30:06 INFO - Running post-action listener: _resource_record_post_action 08:30:06 INFO - [mozharness: 2016-08-09 15:30:06.052150Z] Finished populate-webroot step (success) 08:30:06 INFO - [mozharness: 2016-08-09 15:30:06.052276Z] Running create-virtualenv step. 08:30:06 INFO - Running pre-action listener: _resource_record_pre_action 08:30:06 INFO - Running main action method: create_virtualenv 08:30:06 INFO - Creating virtualenv /builds/slave/test/build/venv 08:30:06 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 08:30:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:30:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:30:06 INFO - Using real prefix '/tools/python27' 08:30:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:30:07 INFO - Installing distribute.............................................................................................................................................................................................done. 08:30:10 INFO - Installing pip.................done. 08:30:10 INFO - Return code: 0 08:30:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:30:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10293ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94a3589a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028a0d30>, '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 0x102605db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102898b20>, '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.RBWOKyL2IP/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.OvwRdOdxFL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 08:30:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:30:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:30:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RBWOKyL2IP/Render', 08:30:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:10 INFO - 'HOME': '/Users/cltbld', 08:30:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:10 INFO - 'LOGNAME': 'cltbld', 08:30:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:10 INFO - 'MOZ_NO_REMOTE': '1', 08:30:10 INFO - 'NO_EM_RESTART': '1', 08:30:10 INFO - 'PAGER': '/bin/cat', 08:30:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:10 INFO - 'PWD': '/builds/slave/test', 08:30:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:10 INFO - 'SHELL': '/bin/bash', 08:30:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OvwRdOdxFL/Listeners', 08:30:10 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 08:30:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:10 INFO - 'USER': 'cltbld', 08:30:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:10 INFO - 'XPC_FLAGS': '0x0', 08:30:10 INFO - 'XPC_SERVICE_NAME': '0', 08:30:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:10 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 08:30:10 INFO - Cleaning up... 08:30:10 INFO - Return code: 0 08:30:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:30:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10293ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94a3589a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028a0d30>, '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 0x102605db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102898b20>, '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.RBWOKyL2IP/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.OvwRdOdxFL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 08:30:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:30:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:30:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RBWOKyL2IP/Render', 08:30:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:10 INFO - 'HOME': '/Users/cltbld', 08:30:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:10 INFO - 'LOGNAME': 'cltbld', 08:30:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:10 INFO - 'MOZ_NO_REMOTE': '1', 08:30:10 INFO - 'NO_EM_RESTART': '1', 08:30:10 INFO - 'PAGER': '/bin/cat', 08:30:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:10 INFO - 'PWD': '/builds/slave/test', 08:30:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:10 INFO - 'SHELL': '/bin/bash', 08:30:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OvwRdOdxFL/Listeners', 08:30:10 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 08:30:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:10 INFO - 'USER': 'cltbld', 08:30:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:10 INFO - 'XPC_FLAGS': '0x0', 08:30:10 INFO - 'XPC_SERVICE_NAME': '0', 08:30:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:10 INFO - Downloading/unpacking psutil>=3.1.1 08:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:30:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:30:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:30:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:30:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:30:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:30:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:30:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:30:14 INFO - Installing collected packages: psutil 08:30:14 INFO - Running setup.py install for psutil 08:30:14 INFO - building 'psutil._psutil_osx' extension 08:30:14 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 08:30:15 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 08:30:15 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 08:30:15 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 08:30:15 INFO - building 'psutil._psutil_posix' extension 08:30:15 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 08:30:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:30:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:30:15 INFO - ^ 08:30:15 INFO - 1 warning generated. 08:30:15 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 08:30:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:30:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:30:15 INFO - Successfully installed psutil 08:30:15 INFO - Cleaning up... 08:30:15 INFO - Return code: 0 08:30:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:30:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10293ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94a3589a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028a0d30>, '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 0x102605db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102898b20>, '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.RBWOKyL2IP/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.OvwRdOdxFL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 08:30:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:30:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:30:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RBWOKyL2IP/Render', 08:30:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:15 INFO - 'HOME': '/Users/cltbld', 08:30:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:15 INFO - 'LOGNAME': 'cltbld', 08:30:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:15 INFO - 'MOZ_NO_REMOTE': '1', 08:30:15 INFO - 'NO_EM_RESTART': '1', 08:30:15 INFO - 'PAGER': '/bin/cat', 08:30:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:15 INFO - 'PWD': '/builds/slave/test', 08:30:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:15 INFO - 'SHELL': '/bin/bash', 08:30:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OvwRdOdxFL/Listeners', 08:30:15 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 08:30:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:15 INFO - 'USER': 'cltbld', 08:30:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:15 INFO - 'XPC_FLAGS': '0x0', 08:30:15 INFO - 'XPC_SERVICE_NAME': '0', 08:30:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:30:15 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 08:30:15 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 08:30:15 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 08:30:15 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 08:30:15 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 08:30:15 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 08:30:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:30:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:30:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:30:18 INFO - Installing collected packages: mozsystemmonitor 08:30:18 INFO - Running setup.py install for mozsystemmonitor 08:30:18 INFO - Successfully installed mozsystemmonitor 08:30:18 INFO - Cleaning up... 08:30:18 INFO - Return code: 0 08:30:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:30:18 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')]} 08:30:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:18 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')]} 08:30:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:18 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 0x10293ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94a3589a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028a0d30>, '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 0x102605db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102898b20>, '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.RBWOKyL2IP/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.OvwRdOdxFL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 08:30:18 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 08:30:18 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 08:30:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RBWOKyL2IP/Render', 08:30:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:18 INFO - 'HOME': '/Users/cltbld', 08:30:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:18 INFO - 'LOGNAME': 'cltbld', 08:30:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:18 INFO - 'MOZ_NO_REMOTE': '1', 08:30:18 INFO - 'NO_EM_RESTART': '1', 08:30:18 INFO - 'PAGER': '/bin/cat', 08:30:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:18 INFO - 'PWD': '/builds/slave/test', 08:30:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:18 INFO - 'SHELL': '/bin/bash', 08:30:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OvwRdOdxFL/Listeners', 08:30:18 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 08:30:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:18 INFO - 'USER': 'cltbld', 08:30:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:18 INFO - 'XPC_FLAGS': '0x0', 08:30:18 INFO - 'XPC_SERVICE_NAME': '0', 08:30:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:18 INFO - Downloading/unpacking jsonschema==2.5.1 08:30:18 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 08:30:18 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 08:30:18 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 08:30:18 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 08:30:18 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 08:30:18 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 08:30:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:30:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:30:21 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 08:30:21 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 08:30:21 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 08:30:21 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 08:30:21 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 08:30:21 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 08:30:21 INFO - Downloading functools32-3.2.3-2.tar.gz 08:30:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:30:21 INFO - warning: no files found matching '*.txt' 08:30:21 INFO - no previously-included directories found matching 'build' 08:30:21 INFO - no previously-included directories found matching 'dist' 08:30:21 INFO - no previously-included directories found matching '.git*' 08:30:21 INFO - Installing collected packages: jsonschema, functools32 08:30:22 INFO - Running setup.py install for functools32 08:30:22 INFO - warning: no files found matching '*.txt' 08:30:22 INFO - no previously-included directories found matching 'build' 08:30:22 INFO - no previously-included directories found matching 'dist' 08:30:22 INFO - no previously-included directories found matching '.git*' 08:30:22 INFO - Successfully installed jsonschema functools32 08:30:22 INFO - Cleaning up... 08:30:22 INFO - Return code: 0 08:30:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:30:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:22 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 0x10293ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94a3589a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028a0d30>, '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 0x102605db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102898b20>, '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.RBWOKyL2IP/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.OvwRdOdxFL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 08:30:22 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 08:30:22 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 08:30:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RBWOKyL2IP/Render', 08:30:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:22 INFO - 'HOME': '/Users/cltbld', 08:30:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:22 INFO - 'LOGNAME': 'cltbld', 08:30:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:22 INFO - 'MOZ_NO_REMOTE': '1', 08:30:22 INFO - 'NO_EM_RESTART': '1', 08:30:22 INFO - 'PAGER': '/bin/cat', 08:30:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:22 INFO - 'PWD': '/builds/slave/test', 08:30:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:22 INFO - 'SHELL': '/bin/bash', 08:30:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OvwRdOdxFL/Listeners', 08:30:22 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 08:30:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:22 INFO - 'USER': 'cltbld', 08:30:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:22 INFO - 'XPC_FLAGS': '0x0', 08:30:22 INFO - 'XPC_SERVICE_NAME': '0', 08:30:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:30:22 INFO - Cleaning up... 08:30:22 INFO - Return code: 0 08:30:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:30:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:22 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 0x10293ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94a3589a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028a0d30>, '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 0x102605db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102898b20>, '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.RBWOKyL2IP/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.OvwRdOdxFL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 08:30:22 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 08:30:22 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 08:30:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RBWOKyL2IP/Render', 08:30:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:22 INFO - 'HOME': '/Users/cltbld', 08:30:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:22 INFO - 'LOGNAME': 'cltbld', 08:30:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:22 INFO - 'MOZ_NO_REMOTE': '1', 08:30:22 INFO - 'NO_EM_RESTART': '1', 08:30:22 INFO - 'PAGER': '/bin/cat', 08:30:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:22 INFO - 'PWD': '/builds/slave/test', 08:30:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:22 INFO - 'SHELL': '/bin/bash', 08:30:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OvwRdOdxFL/Listeners', 08:30:22 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 08:30:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:22 INFO - 'USER': 'cltbld', 08:30:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:22 INFO - 'XPC_FLAGS': '0x0', 08:30:22 INFO - 'XPC_SERVICE_NAME': '0', 08:30:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:22 INFO - Downloading/unpacking blobuploader==1.2.4 08:30:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:30:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:30:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:30:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:30:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:30:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:30:25 INFO - Downloading blobuploader-1.2.4.tar.gz 08:30:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:30:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:30:25 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 08:30:25 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 08:30:25 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 08:30:25 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 08:30:25 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 08:30:25 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 08:30:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:30:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:30:26 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 08:30:26 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 08:30:26 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 08:30:26 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 08:30:26 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 08:30:26 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 08:30:26 INFO - Downloading docopt-0.6.1.tar.gz 08:30:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:30:26 INFO - Installing collected packages: blobuploader, requests, docopt 08:30:26 INFO - Running setup.py install for blobuploader 08:30:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:30:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:30:26 INFO - Running setup.py install for requests 08:30:27 INFO - Running setup.py install for docopt 08:30:27 INFO - Successfully installed blobuploader requests docopt 08:30:27 INFO - Cleaning up... 08:30:27 INFO - Return code: 0 08:30:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:30:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:27 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 0x10293ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94a3589a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028a0d30>, '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 0x102605db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102898b20>, '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.RBWOKyL2IP/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.OvwRdOdxFL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 08:30:27 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 08:30:27 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 08:30:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RBWOKyL2IP/Render', 08:30:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:27 INFO - 'HOME': '/Users/cltbld', 08:30:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:27 INFO - 'LOGNAME': 'cltbld', 08:30:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:27 INFO - 'MOZ_NO_REMOTE': '1', 08:30:27 INFO - 'NO_EM_RESTART': '1', 08:30:27 INFO - 'PAGER': '/bin/cat', 08:30:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:27 INFO - 'PWD': '/builds/slave/test', 08:30:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:27 INFO - 'SHELL': '/bin/bash', 08:30:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OvwRdOdxFL/Listeners', 08:30:27 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 08:30:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:27 INFO - 'USER': 'cltbld', 08:30:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:27 INFO - 'XPC_FLAGS': '0x0', 08:30:27 INFO - 'XPC_SERVICE_NAME': '0', 08:30:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:30:27 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-u96LML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:30:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:30:27 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-JZOPZx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:30:28 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-elR7j4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:30:28 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-sw0pXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:30:28 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-MXTG6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:30:28 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-7_3q4O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:30:28 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-UGv3nq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:30:28 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-_piyu0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:30:28 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-LFJuXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:30:28 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-IR0AP1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:30:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:30:28 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-RT2k5g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:30:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:30:29 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-YImsTM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:30:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:30:29 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-__s48v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:30:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:30:29 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-6RDi_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:30:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:30:29 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-lweyH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:30:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:30:29 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-o5yG9V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:30:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:30:29 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-Od63N8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:30:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:30:29 INFO - Running setup.py install for manifestparser 08:30:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:30:29 INFO - Running setup.py install for mozcrash 08:30:30 INFO - Running setup.py install for mozdebug 08:30:30 INFO - Running setup.py install for mozdevice 08:30:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:30:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:30:30 INFO - Running setup.py install for mozfile 08:30:30 INFO - Running setup.py install for mozhttpd 08:30:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:30:30 INFO - Running setup.py install for mozinfo 08:30:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:30:30 INFO - Running setup.py install for mozInstall 08:30:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:30:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:30:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:30:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:30:30 INFO - Running setup.py install for mozleak 08:30:31 INFO - Running setup.py install for mozlog 08:30:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:30:31 INFO - Running setup.py install for moznetwork 08:30:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:30:31 INFO - Running setup.py install for mozprocess 08:30:31 INFO - Running setup.py install for mozprofile 08:30:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:30:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:30:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:30:31 INFO - Running setup.py install for mozrunner 08:30:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:30:32 INFO - Running setup.py install for mozscreenshot 08:30:32 INFO - Running setup.py install for moztest 08:30:32 INFO - Running setup.py install for mozversion 08:30:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:30:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:30:32 INFO - Cleaning up... 08:30:32 INFO - Return code: 0 08:30:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:30:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:30:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:32 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 0x10293ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94a3589a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028a0d30>, '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 0x102605db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102898b20>, '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.RBWOKyL2IP/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.OvwRdOdxFL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 08:30:32 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 08:30:32 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 08:30:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RBWOKyL2IP/Render', 08:30:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:32 INFO - 'HOME': '/Users/cltbld', 08:30:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:32 INFO - 'LOGNAME': 'cltbld', 08:30:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:32 INFO - 'MOZ_NO_REMOTE': '1', 08:30:32 INFO - 'NO_EM_RESTART': '1', 08:30:32 INFO - 'PAGER': '/bin/cat', 08:30:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:32 INFO - 'PWD': '/builds/slave/test', 08:30:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:32 INFO - 'SHELL': '/bin/bash', 08:30:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OvwRdOdxFL/Listeners', 08:30:32 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 08:30:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:32 INFO - 'USER': 'cltbld', 08:30:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:32 INFO - 'XPC_FLAGS': '0x0', 08:30:32 INFO - 'XPC_SERVICE_NAME': '0', 08:30:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:30:32 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-lCtq86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:30:32 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)) 08:30:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:30:32 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-QGJDfO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:30:33 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)) 08:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:30:33 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-AuIeKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:30:33 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)) 08:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:30:33 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-oXp_kr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:30:33 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)) 08:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:30:33 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-4AC9z9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:30:33 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)) 08:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:30:33 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-W5uUFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:30:33 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)) 08:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:30:33 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-qWc9IG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:30:33 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)) 08:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:30:33 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-NoWmBt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:30:33 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)) 08:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:30:33 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-yTvxYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:30:33 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)) 08:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:30:33 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-hcrQLx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:30:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 08:30:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:30:33 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-Bn_HPF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:30:34 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)) 08:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:30:34 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-SCDoHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:30:34 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)) 08:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:30:34 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-f8UJkR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:30:34 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)) 08:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:30:34 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-GR2sL5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:30:34 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)) 08:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:30:34 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-UMILwR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:30:34 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)) 08:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:30:34 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-Bz4hTC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:30:34 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)) 08:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:30:34 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-6S7n8e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:30:34 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)) 08:30:34 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)) 08:30:34 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)) 08:30:34 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)) 08:30:34 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)) 08:30:34 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)) 08:30:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:30:34 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 08:30:34 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 08:30:34 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 08:30:34 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 08:30:34 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 08:30:34 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 08:30:37 INFO - Downloading blessings-1.6.tar.gz 08:30:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:30:37 INFO - Installing collected packages: blessings 08:30:37 INFO - Running setup.py install for blessings 08:30:37 INFO - Successfully installed blessings 08:30:37 INFO - Cleaning up... 08:30:37 INFO - Return code: 0 08:30:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:30:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:30:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:30:37 INFO - Reading from file tmpfile_stdout 08:30:37 INFO - Current package versions: 08:30:37 INFO - blessings == 1.6 08:30:37 INFO - blobuploader == 1.2.4 08:30:37 INFO - docopt == 0.6.1 08:30:37 INFO - functools32 == 3.2.3-2 08:30:37 INFO - jsonschema == 2.5.1 08:30:37 INFO - manifestparser == 1.1 08:30:37 INFO - mozInstall == 1.12 08:30:37 INFO - mozcrash == 0.17 08:30:37 INFO - mozdebug == 0.1 08:30:37 INFO - mozdevice == 0.48 08:30:37 INFO - mozfile == 1.2 08:30:37 INFO - mozhttpd == 0.7 08:30:37 INFO - mozinfo == 0.9 08:30:37 INFO - mozleak == 0.1 08:30:37 INFO - mozlog == 3.3 08:30:37 INFO - moznetwork == 0.27 08:30:37 INFO - mozprocess == 0.23 08:30:37 INFO - mozprofile == 0.28 08:30:37 INFO - mozrunner == 6.12 08:30:37 INFO - mozscreenshot == 0.1 08:30:37 INFO - mozsystemmonitor == 0.3 08:30:37 INFO - moztest == 0.7 08:30:37 INFO - mozversion == 1.4 08:30:37 INFO - psutil == 3.1.1 08:30:37 INFO - requests == 1.2.3 08:30:37 INFO - wsgiref == 0.1.2 08:30:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:30:37 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')]} 08:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:38 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')]} 08:30:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:38 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 0x10293ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94a3589a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028a0d30>, '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 0x102605db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102898b20>, '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.RBWOKyL2IP/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.OvwRdOdxFL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 08:30:38 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 08:30:38 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 08:30:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RBWOKyL2IP/Render', 08:30:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:38 INFO - 'HOME': '/Users/cltbld', 08:30:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:38 INFO - 'LOGNAME': 'cltbld', 08:30:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:38 INFO - 'MOZ_NO_REMOTE': '1', 08:30:38 INFO - 'NO_EM_RESTART': '1', 08:30:38 INFO - 'PAGER': '/bin/cat', 08:30:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:38 INFO - 'PWD': '/builds/slave/test', 08:30:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:38 INFO - 'SHELL': '/bin/bash', 08:30:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OvwRdOdxFL/Listeners', 08:30:38 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 08:30:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:38 INFO - 'USER': 'cltbld', 08:30:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:38 INFO - 'XPC_FLAGS': '0x0', 08:30:38 INFO - 'XPC_SERVICE_NAME': '0', 08:30:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:38 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)) 08:30:38 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)) 08:30:38 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)) 08:30:38 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)) 08:30:38 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)) 08:30:38 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)) 08:30:38 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)) 08:30:38 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)) 08:30:38 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)) 08:30:38 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)) 08:30:38 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)) 08:30:38 INFO - Cleaning up... 08:30:38 INFO - Return code: 0 08:30:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:30:38 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')]} 08:30:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:30:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:38 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')]} 08:30:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:30:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:30:38 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 0x10293ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1029d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94a3589a60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028a0d30>, '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 0x102605db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102898b20>, '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.RBWOKyL2IP/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.OvwRdOdxFL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 08:30:38 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 08:30:38 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 08:30:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RBWOKyL2IP/Render', 08:30:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:30:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:30:38 INFO - 'HOME': '/Users/cltbld', 08:30:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:30:38 INFO - 'LOGNAME': 'cltbld', 08:30:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:30:38 INFO - 'MOZ_NO_REMOTE': '1', 08:30:38 INFO - 'NO_EM_RESTART': '1', 08:30:38 INFO - 'PAGER': '/bin/cat', 08:30:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:30:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:30:38 INFO - 'PWD': '/builds/slave/test', 08:30:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:30:38 INFO - 'SHELL': '/bin/bash', 08:30:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OvwRdOdxFL/Listeners', 08:30:38 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 08:30:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:30:38 INFO - 'USER': 'cltbld', 08:30:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:30:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:30:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:30:38 INFO - 'XPC_FLAGS': '0x0', 08:30:38 INFO - 'XPC_SERVICE_NAME': '0', 08:30:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:30:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:30:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:30:39 INFO - Cleaning up... 08:30:39 INFO - Return code: 0 08:30:39 INFO - Running post-action listener: _resource_record_post_action 08:30:39 INFO - Running post-action listener: _start_resource_monitoring 08:30:39 INFO - Starting resource monitoring. 08:30:39 INFO - [mozharness: 2016-08-09 15:30:39.175760Z] Finished create-virtualenv step (success) 08:30:39 INFO - [mozharness: 2016-08-09 15:30:39.176773Z] Running install step. 08:30:39 INFO - Running pre-action listener: _resource_record_pre_action 08:30:39 INFO - Running main action method: install 08:30:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:30:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:30:39 INFO - Reading from file tmpfile_stdout 08:30:39 INFO - Detecting whether we're running mozinstall >=1.0... 08:30:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:30:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:30:39 INFO - Reading from file tmpfile_stdout 08:30:39 INFO - Output received: 08:30:39 INFO - Usage: mozinstall [options] installer 08:30:39 INFO - Options: 08:30:39 INFO - -h, --help show this help message and exit 08:30:39 INFO - -d DEST, --destination=DEST 08:30:39 INFO - Directory to install application into. [default: 08:30:39 INFO - "/builds/slave/test"] 08:30:39 INFO - --app=APP Application being installed. [default: firefox] 08:30:39 INFO - mkdir: /builds/slave/test/build/application 08:30:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:30:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:31:16 INFO - Reading from file tmpfile_stdout 08:31:16 INFO - Output received: 08:31:16 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:31:16 INFO - Running post-action listener: _resource_record_post_action 08:31:16 INFO - [mozharness: 2016-08-09 15:31:16.124516Z] Finished install step (success) 08:31:16 INFO - [mozharness: 2016-08-09 15:31:16.124696Z] Running run-tests step. 08:31:16 INFO - Running pre-action listener: _resource_record_pre_action 08:31:16 INFO - Running main action method: run_tests 08:31:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:31:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:31:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:31:16 INFO - 2016-08-09 08:31:16.139 screenresolution[1643:7661] starting screenresolution argv=screenresolution get 08:31:16 INFO - 2016-08-09 08:31:16.142 screenresolution[1643:7661] Display 0: 1600x1200x32@60 08:31:16 INFO - 2016-08-09 08:31:16.148 screenresolution[1644:7663] starting screenresolution argv=screenresolution list 08:31:16 INFO - Available Modes on Display 0 08:31:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:31:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:31:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:31:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:31:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:31:16 INFO - Intel Iris: 08:31:16 INFO - Chipset Model: Intel Iris 08:31:16 INFO - Type: GPU 08:31:16 INFO - Bus: Built-In 08:31:16 INFO - VRAM (Dynamic, Max): 1536 MB 08:31:16 INFO - Vendor: Intel (0x8086) 08:31:16 INFO - Device ID: 0x0a2e 08:31:16 INFO - Revision ID: 0x0009 08:31:16 INFO - Displays: 08:31:16 INFO - Display: 08:31:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:31:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:31:16 INFO - Display Serial Number: 1600x1200 60 08:31:16 INFO - Main Display: Yes 08:31:16 INFO - Mirror: Off 08:31:16 INFO - Online: Yes 08:31:16 INFO - Rotation: Supported 08:31:16 INFO - Return code: 0 08:31:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:31:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:31:16 INFO - Python 2.7.3 08:31:16 INFO - Return code: 0 08:31:16 INFO - grabbing minidump binary from tooltool 08:31:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:31:16 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 0x1028a0d30>, '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 0x102605db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102898b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:31:16 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 08:31:16 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 08:31:16 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 08:31:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:31:16 INFO - Return code: 0 08:31:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:31:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:31:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:31:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:31:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:31:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0034', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 08:31:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0034 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 08:31:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RBWOKyL2IP/Render', 08:31:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:31:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:31:16 INFO - 'HOME': '/Users/cltbld', 08:31:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:31:16 INFO - 'LOGNAME': 'cltbld', 08:31:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:31:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:31:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:31:16 INFO - 'MOZ_NO_REMOTE': '1', 08:31:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:31:16 INFO - 'NO_EM_RESTART': '1', 08:31:16 INFO - 'PAGER': '/bin/cat', 08:31:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:31:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:31:16 INFO - 'PWD': '/builds/slave/test', 08:31:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:31:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:31:16 INFO - 'SHELL': '/bin/bash', 08:31:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.OvwRdOdxFL/Listeners', 08:31:16 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 08:31:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:31:16 INFO - 'USER': 'cltbld', 08:31:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:31:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:31:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:31:16 INFO - 'XPC_FLAGS': '0x0', 08:31:16 INFO - 'XPC_SERVICE_NAME': '0', 08:31:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:31:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0034', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 08:31:16 INFO - mozversion application_buildid: 20160809064320 08:31:16 INFO - mozversion application_changeset: 643a67968688ec383af3d008ef081a6939a0cb53 08:31:16 INFO - mozversion application_display_name: Nightly 08:31:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:31:16 INFO - mozversion application_name: Firefox 08:31:16 INFO - mozversion application_remotingname: firefox 08:31:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:31:16 INFO - mozversion application_vendor: Mozilla 08:31:16 INFO - mozversion application_version: 51.0a1 08:31:16 INFO - mozversion platform_buildid: 20160809064320 08:31:16 INFO - mozversion platform_changeset: 643a67968688ec383af3d008ef081a6939a0cb53 08:31:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:31:16 INFO - mozversion platform_version: 51.0a1 08:31:16 INFO - using testdate: 1470756676 08:31:16 INFO - actual date: 1470756676 08:31:16 INFO - starting webserver on 'localhost:49271' 08:31:16 INFO - SUITE-START | Running 2 tests 08:31:16 INFO - TEST-START | dromaeo_css 08:31:16 INFO - operating with platform_type : mac_ 08:31:16 INFO - Initialising browser for dromaeo_css test... 08:31:16 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpRlHi0P/profile http://localhost:49271/getInfo.html) 08:31:20 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 08:31:20 INFO - PROCESS | 1654 | colorDepth:24 08:31:20 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 08:31:20 INFO - PROCESS | 1654 | __metrics 08:31:20 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 08:31:20 INFO - TEST-INFO | 1654: exit 0 08:31:20 INFO - Browser initialized. 08:31:20 INFO - Running cycle 1/1 for dromaeo_css test... 08:31:20 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpRlHi0P/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:31:32 INFO - PROCESS | 1657 | RSS: Main: 254578688 08:31:32 INFO - PROCESS | 1657 | 08:32:22 INFO - PROCESS | 1657 | 1470756742656 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] 08:34:33 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 08:34:35 INFO - PROCESS | 1657 | RSS: Main: 267431936 08:34:35 INFO - PROCESS | 1657 | 08:37:37 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 08:37:38 INFO - PROCESS | 1657 | RSS: Main: 280498176 08:37:38 INFO - PROCESS | 1657 | 08:40:40 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 08:40:40 INFO - PROCESS | 1657 | RSS: Main: 287420416 08:40:40 INFO - PROCESS | 1657 | 08:43:42 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 08:43:43 INFO - PROCESS | 1657 | RSS: Main: 286715904 08:43:43 INFO - PROCESS | 1657 | 08:46:45 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 08:46:46 INFO - PROCESS | 1657 | RSS: Main: 291225600 08:46:46 INFO - PROCESS | 1657 | 08:49:49 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 08:49:49 INFO - PROCESS | 1657 | RSS: Main: 291291136 08:49:49 INFO - PROCESS | 1657 | 08:49:49 INFO - PROCESS | 1657 | __start_tp_report 08:49:49 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 08:49:49 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 08:49:49 INFO - PROCESS | 1657 | |i|pagename|runs| 08:49:49 INFO - PROCESS | 1657 | |0;dojo.html;2763.212484441932;2981.9999999999995;3015.0351887396027;3031.5604237385564;3033.256852071243;4243.915121697564;4376.059147283333;4778.880527986802;4902.852914412569;4957.801687932488;4776.331313616088;4835.323054772326;4879.487653796354;4898.828541001066;4974.4030716314;4348.608625223737;4469.798859051334;4498.055408364247;4587.678862479628;4610.700304480198;4539;4540.387047748544;4559.361689363475;4591.104734576759;4638.281066434708;4409.559044095581;4441.022974945507;4469.888252793674;4513.074819661962;4578.771061446931;4529.8641040768825;4530.0939812037395;4559.430071241095;4560.566746159109;4579.267317229227;4405.713628614129;4538.818447262105;4568.634509239253;4579.129965306581;4579.152856721517;4460.375547423363;4783;4837.782299796482;4863.221884498464;4888.193448081062;5450.427705090948;5499.285092937933;5551.139573366135;5556.111022236423;5567.749451274703;5598.692071936042;5610.270664813589;5621.016322143609;5622.746976386073;5640.182173584807;4982.576480999083;5092.00705862357;5147.356580427447;5166.3025491558155;5223.947760522384;5693.430656934356;5702.914456283159;5713.8285851424525;5736.053551164011;5750.30996280449;5368.463153684678;5407.297051383295;5416.91874621881;5423.213634023124;5431.565474762009;5346.385165705916;5430.02259593277;5441.945580544223;5458.372287186945;5629.915551266734;5319.654222475604;5415.350157980989;5439.728013599304;5544.306961629796;5592.412796656374;5497.697626630494;5572.58205634579;5597;5614.747336369873;5626.240457538262;4157.97921010399;4380.78096095194;4517;4528.977355113269;4557.863264102082;3925.5878132796215;3928.175083232496;3975;3997.480327557399;4069.2471892699946;4706.1999460091865;4949.257611358325;4957.752112394366;4958.603311735124;4991.3511243538105;5329.946700532945;5576.275084239023;5629.634073785191;5666.235058267163;5701.515371193412;4926.778294976734;4939.407271127488;4971.751412429364;5019.774110164978;5081.7967281309075;4828.420589529279;4842.878928026827;4876.366072410564;4885.926711099265;4930.580900623487;5508.697021663686;5734.197212390269;5738.397468265772;5757.769689212385;5764.221830052973;4455.0644364682485;5008.549230569266;5538.252335934759;5553.444655534414;5690.800821971211;5779.682117483579;5826.650400975955;5828.533717302521;5840.036393994943;5858.882822343444;4511.977440112779;4883.658143929891;4936.333594964633;5524.75138618755;5529.281193444826;4031.415444760552;4370.672199585108;5419.539339156058;5532.944670553404;5597.944020559742;5102.387713474258;5270.815521456884;5292.682439053669;5308.522232999048;5352.839414817562;5159.432462429055;5331.733413329395;5335.839924802107;5381.73091345439;5400.513953744182;4208.368744688199;5018.4730603286125;5105.132127538253;5107.565856902205;5145.948540514547;4899.779509921991;5295.417504074471;5297.390800057889;5322.467753224647;5328.733563321896;5051.74741262928;5201.947980520146;5206.271122042841;5207.921881171861;5223.634345595772 08:49:49 INFO - PROCESS | 1657 | |1;ext.html;436.6419535980497;472.9668923175378;479.99280010799845;480.0902290160147;484.1672323603401;32224.194395140134;41034.58965410345;41146.794266028664;41179;41212.17575648489;35892;42076.368854467204;42106.947326316855;42296.73109806703;42370.57629423705;9404.764880877981;9571.186449151795;9600.471974041426;9601.087896649808;9601.231901447867;9479.573419196118;9559.713208603718;9576.61693532258;9587.089226523503;9588.95205523975;9592.472414017262;9610.855837162448;9618.855717164248;9640.325177237597;9642.421454712705;8185.631646575918;9534.952325238364;9565.186959108483;9594.42433453995;9601.327907046545;9373.906260937372;9443.15011648948;9448.811023779555;9454.85817712735;9458.621655133786;39303;39446.80276598599;39475.407868881986;39506.80246598777;39615.81152565399;2803.2991752061985;3098.51972944194;3169.8573564189437;3274.2141885947203;3337.4159522083537;29964.550531742043;29965;30027.249318766986;31022.844885775427;31053.8447307762;3021.3504096619113;3035.3170536629304;3053.129857990462;3077.615298087739;3158.936821263562;404508;406296;407101;407387;407576.9242307569;32119.357612847612;32148.03555893327;32197;32205;32334;30299.848500757354;32918.506222406686;33090.8345458276;34818.955431336595;35976;16245.756313655305;16293.837061629232;16385;16413.83586164147;16474.670506589802;12368.44342004612;12423.503059877685;12534.185277956904;12541.623751287476;12583.245005299712;14822.62943426423;19267.325643602686;19284.325048623232;19388.612227755366;19419.805801942082;7705.190954949761;7834.764957051298;7837.882431763528;7879.133295337623;7887.369010479148;778.8091917480141;863.9524826134498;896.6368620708623;965.2663975378622;1004.3672486333563;30364;30427;30463.69536304653;30661.540076898866;31621.841890790398;765.682241869629;988.8417853143462;1028.4651980969854;1044.2690116918188;1079.854219680333;15416.46042388511;15456.613584660248;15628.765568516481;15688.450904218525;15701.057936523845;597942;614120;614691;615032;615337;58926.410735892096;59146.408535914954;59635.70182149061;59731.70134149301;59732.70133649304;1213.769383817072;1256.5162412471082;1267.0940277701488;1296.3777386854174;1299.2853930338088;26253.081142159932;28739.994100206393;28749.425011499236;29277.707222927496;29297.41405171927;50933;50982;51402.48597513977;51559.48440515547;51580.22629660483;549314;553279;553790;556114;556151;26786.464270714867;27047.594286085332;27051.1884643461;27303;27315.590266145628;46847.53152468568;47236;47536.7623161882;48993.265101024226;49095.01809963709;39836.40245396263;40155.799221003705;40216.1956760869;40438.79780601078;41670.37494437525;32286;40526.39210411787;41308.65269027992;41455.1708965825;41481.755547332425;33096.834515827264;38625.2274954505;38819.6118038816;39018.414723778595;39061.41407878827;28094.85952570224;32523;34733.65266347405;35512;35714;25700.971961122093;25785.355366115997;25974.35064123412;26115.738842611332;26424 08:49:49 INFO - PROCESS | 1657 | |2;jquery.html;336.68688120048347;339.7808413573245;348.84650753668376;364.5789113573823;368.169777152521;18545.35091271806;19220.519487012814;20215;20328.390148295537;20445.488862778402;17496.475105746817;19845.305414310504;20600;20850;21023.894880525575;5611.410801865796;5768.884622307552;5791.478766910977;5834.620749651281;5836.124581312794;5366.436524164965;5437.972810135944;5767.33675627304;5810.389909059551;5838.591298609078;5667.319921609413;5770.538356931456;5811.0702287633985;5889.9705501472645;5953.732082056296;4648.395708557866;5909.704514774266;5942.168096466498;5944.494717948979;5949.107633854913;5716.971415142939;5718.370979192286;5764.308283006066;5777.075667893159;5787.160861675034;16511.917440412843;24693.135740249185;25134.85814273216;25359.746402535922;25817.225483235532;2085.8539902206853;2238.0935721033006;2259.7740225977273;2320.9419764505856;2330.650402439631;8072;9788.21694264457;9868.506574671237;9906.702798916043;9981.800363992752;1586.643005323793;1713.6487020160962;1773.8403543681002;1787.5352408373788;1846.1138653446287;100863.49568252185;125939;127873.36063319717;128018.35990819987;129561.35219323938;8777.51723655205;9248;11179.60871369498;11193.944030279796;11247.268927519684;12761.361931903368;14344.135262415819;14344.641383965485;14350.569482915529;14406.927965360106;7649.732259370895;8331.583420828934;8388.706395276258;8527.317814574943;8541.914580854113;5969;8285.047219569826;8306.875396869053;8397.496150231005;8462.19609137131;3801.1447424329303;4347.891287721611;4356.324769660708;4423.424954755861;4495.25828238337;1578.9131597762234;1827.8446332061658;1884.387574038443;1888.8583356248062;1890.2533499267988;581.8370856160282;810.6797814863096;816.2735165702529;843.3927572148043;853.8633818588996;17775.377861774778;19284.325048623512;20969.951502424818;21150.682739758915;21151.471213219793;857.1685465098844;875.5009644502572;881.5504092912532;884.1070518775954;894.7360528644066;8757.386982911135;10523.947380263202;11035.83446248307;11053.668389948314;11106.278091924;504413;525239.3738031285;592728;612542;612702;94524;110780;119242.40378798051;126198;127333;394.8539040555013;842.6376658037103;896.5069211933411;899.3749344205845;902.6163880350824;8709.608067637098;10526;10961.671149865517;10994.945025274823;11417.0866330695;50854.23718644297;51782;52287.47712522826;52480.21279680884;53181.734091329294;53605.73197133989;75859.86210206962;77602;79527;79995.60002199952;6389.584676995981;7542.717737984446;7818.374530037469;7942.523448592871;8188.181181881764;10591.5233814477;12836.935815320863;12900.935495322463;13123.54067607629;13151.473941042252;8995.640174392951;11011;11225.494852731483;11334.716632084264;11401.885981140082;9397.765055873659;11019.669409917715;11185.440727963407;11277.492512837107;11539.07687385024;11061.336319820837;11224.831627525431;11435.771284574095;11469.139814514112;11489.195756296978;7687.308142266999;7981.553566558309;7986.845896297189;8302.086770455124;8367.790805229917;8122.228388303101;9881.604735810488;10955.780884382428;11178.0498657615;11347.659570212907 08:49:49 INFO - PROCESS | 1657 | |3;mootools.html;1429.99285003575;1439.9784003239954;1548.8606025457707;1591.9442819501319;1623.4399132299368;7624.6187690615525;8115.675372985081;8230.135835737252;8757.430767000149;8757.868631970518;8354.582270886462;8579.871301930476;8585.18440748128;8596.097409771977;8598.828023439528;2203.0967303405605;2244.6408574628063;2248.4154119928853;2252.5945329840624;2253.076238742116;2207.415035015722;2237.9104835806547;2267.7732226777357;2271.3526644906206;2280.7149106361703;2246.809021233197;2247.932562023134;2259.4464356232666;2264.116994372196;2273.6362182050875;2238.9888050559643;2251.7635648257024;2252.358077947777;2263.5472905418983;2263.7436222896285;2179.3788770200417;2226.1540614566525;2230.921917732888;2244.696965909614;2249.3139592424304;8533.871991920127;8583.570821458965;8650.480971141751;8653.956730216372;8680.479171249683;5011.5489605935645;5173.198154286091;5181.5077567631015;5191.0656081905245;5220.738963051832;3295.8516866741056;3393.711534519569;3489.45913383426;3530.329237444903;3548.769329993568;1031.705963800321;1036.9325993810378;1045.8512259315735;1058.2063452410691;1063.29822317271;11091.33451992883;11130.499127539199;11250.831237531444;11270.267432616936;11287.984081432629;4382.255016647177;4430.62339701129;4585.380973568593;4593.356930029799;4612.285095810088;9498.857517137249;9656.95171524138;9669.758256043515;9683.90316096844;9704.27217958656;4435.889102772457;5010.649254552146;5125.8718532036255;5148.433672296044;5157.303763991813;4239.703220774578;4548.5906268436;4644.883877903012;4662.067586482717;4701.200795864711;3787.791671458096;3795.7094587840265;3888.183481468923;3923.097687531884;3942.9211415772097;1029.8249297619423;1212.2060050666828;1216.9291023899088;1222.614876313976;1225.6874497003207;817.0807841178674;838.2455789789117;839.202757380491;861.3582880753753;863.8617821148586;5581.10702287632;5792.420757924258;5867.090600956859;5997.670128142994;6056.3338032816355;840.8318336332758;841.865301551761;911.2345629671108;952.2334520710756;952.7237101240563;3353.6752982571747;3758.1168425420005;3947.822347994347;3974.3436038844334;4012.7592344459426;10016.998300169924;10354.068133867988;10394.22043346752;10410.947945260225;10413.791724165474;10339.689809305733;10344.844827327595;10360.481975901175;10364.326318789255;10414.791704165875;7435.8141046474275;7768.961155194301;7771.883421748678;7852.921470785219;7901.617216987004;5709.885802283848;5754.827355179351;5786.363500015079;5823.650580965155;5978.760849565969;9456.243500519804;9743.756406089904;9747.415155090717;9752.756181095529;9757.170640495637;9512.762180945532;9849.212063034796;9984.151347135576;10025.248106391904;10049.246306527186;6779.288174741581;7084.397826184832;7189.173245076883;7296.562206267641;7415.5550666958;8245.711400100967;8283.710070147516;8306.293965013041;8314.916850831414;8321.459105158145;7287.672054757442;7380.298871607189;7420.998165247623;7441.218672039358;7520.135184453858;6976.476764242803;7131.714731410893;7174.354308112295;7241.239669834592;7312.341889229995;5939.970300148471;6747.932520674731;6809.182898052265;6840.008198811244;6898.655067246718;4846.95153048465;4890.706557606555;4903.479921224432;4908.85273441783;4999;4833.936533962663;4901.681390709592;4903.779329930233;4908.870959679365;5016.849494515024 08:49:49 INFO - PROCESS | 1657 | |4;prototype.html;270.9742574455427;276.96537932758406;286.0104040021526;287.7927891917818;288.9812162209456;4155.792210389476;4358.474533913137;4409.250427427337;4471.221817564161;4471.50813410525;4224.387463817745;4401.87752123209;4442.48911375192;4444.82220711172;4447.688661793668;3532.6643968822987;3546.060294022086;3548;3549.3433714762723;3584.2473080652962;2730.84980326082;2899.6230490036264;3535.9469607955903;3551;3572.964270357289;3369.882054128106;3425.2978139481347;3472.635373285794;3505.228849653085;3515.912102197453;2832.6175966244705;2970.5395663672166;3482.982585087071;3494.6854783069393;3505.8247087645514;2319.675245465636;2914.309700373778;3069.5856059432135;3073.078076577018;3085.922851928697;3291.1113999220315;3959;4077.8581997040874;4154.563770804052;4175.352820312826;3887.961120388788;3888.358420860555;3892.8442862285474;3910.687145028391;3912.3544615138467;4104.363823607346;4273.358996150571;4620.3762492063815;4651.3255577941345;4671.0657868426115;2742.80800343976;3049.1157564306545;3178.6026746656667;3297.505374193866;3318.78427902188;4099.877003689894;4765.928511072302;4852.708837469728;4875;4897.804087836482;2805.719428057178;3371.8819841305435;3387;3399.0312760863035;3468.618451970281;3252.154439845657;3445.620981692012;3447.9482807757904;3457.75795694304;3460.2906404187083;3493.371193185251;3714.6812881426704;3811.874263104228;3892.357760969465;3914.9642186063656;3866.941995870064;4487.394201782718;4647.395838540968;4711.882202944886;4774.809007639725;995.1242906242605;1096.276457538021;1142.5772464188137;1180.8228765685217;1181.8640856301636;665.6971078159522;809.5952023988006;811.2455416462628;812.6383759227206;823.7816978500703;3788.962110378916;4209.684273679486;4249.957500424956;4299.6775241856985;4300.978495107504;4210.305299625527;4469.647764176867;4701.717896926195;4776.59398951093;4780.832670856504;3578.7137029038136;3721.832517536717;3806.4670946067017;3966.5438474575794;4271.497372154865;2480.888360023803;2486.401710212832;2490.5392502387117;2497.8126640502037;2519.3449703076967;4916.631252656065;4923.606111511071;4924.803007879645;4948.812285051634;4956.826511072095;4582.289745089516;4889.99510200816;4891.33966914462;4912.38595175603;4936.407631084293;4210;4888.682235654671;4916.631252655993;4925.079219104624;4925.901481970341;4153.979230103831;4335.002949321737;4759.881002974953;4762.357081794052;4802.543758342952;4517.435320584927;4691.50739172382;4718.292256161603;4748.31149483316;4796.784144713426;4643.373144625432;4849.005953779537;4867.29424233477;4872.537108974641;4891.877703057452;3085.92285192872;3333.433316336277;3343.933121337608;3352.245744707421;3375.5822554526667;4320.006398528419;4648.721601559598;4759.6668233223345;4760.809567617256;4793.616510679068;3731.3022574728975;4341.305391137403;4597.712640460676;4603.263477843529;4627.745473998963;3780.2215371702328;4411.669124815716;4606.088473283684;4621.653375996746;4647.581717645428;4546.272596384562;4555.997680510282;4560.156371071363;4573.862784116482;4587.128445595326;3972.015121023213;4595.678302518925;4598.747068911242;4658.93011604833;4662.673612847068;4346.760928148856;4433.844815431574;4594.081183763194;4597.33354654293;4604.907901841877 08:49:49 INFO - PROCESS | 1657 | |5;yui.html;572.5419664268584;664.2195420381051;688.2498077095964;713.4506430048863;748.0126233371949;14711.852881471183;15145.469908553203;15416.152111633855;15417.3833046678;15467.149306788122;14012.929935350308;14108.929455352734;14147.009709320351;14156.433742650283;14165.079269847452;658.6278752504819;818.8116733151379;823.7075838077471;837.857564214082;860.3375400941271;834.995825020874;844.1221130024798;847.2417186617973;850.0097386544699;850.4472093139447;889.4129874282975;908.2733812949647;909.440693973205;911.6080085563239;914.7758799094231;898.8561830107152;900.4417261297972;902.2105657549644;904.4076130134772;906.5376657904483;660.1088530483856;667.6561570791047;669.6584741781678;669.7856685860527;671.0873212431089;14447.277636118151;14540.927295363561;14605.05067170641;14625;14653.41386344545;14251.429942802379;14312.85687143136;14367.640808979755;14447.711045779026;14506.782398264035;11473.5984240552;11602.767944641144;11667.358295293756;11908.106891983136;12189.878101219052;3355.5302257683943;3663.5420572428447;3717.3308804415196;3890.727649064567;3909.9609003910164;104091.99999999924;105356.47321763342;105588.94411055945;106769;106789;10758.602853775197;10923.617673381552;10958.958898904591;11107.722306942229;11512.079033677286;22482.66276005861;22600.208992685177;22649.867506624603;22658.093676253095;22735;7868.842623147505;7941.523508589503;8024.398170137263;8066.354691624656;8169.060558035783;4354.172707185625;4418.5802348776815;4421.2262854001065;4435.6229720473475;4585.701929374647;3565.750397472204;3674.1733110050027;3680.2087551176623;3681.042928838521;3695.2424752925585;676.4317972902785;700.5131433653562;717.8277213468834;727.5743689941444;739.2570466681059;507.0036086727423;523.6543881038497;577.1400613086471;605.4006533531859;618.0821480102045;7876.527408355517;9240.815183696423;9344.392614480037;9429.715355656059;9435.433873967446;558.4917724870348;558.8658721906794;582.9417058294136;602.4156568128908;611.8436155665795;4528.207563676344;4568.588827005586;4647.442306923191;4731.335236916218;4841.201201801734;90789.54605226932;112438;129312.35343823409;132410;137589;93476.53261733647;117691;120345;130155.34922325327;131864.3627127452;97637;109559.83939749058;110234;111180.99999999838;111778.44110779394;6752.662366881538;6832.0435139081565;8733.170348816851;8864;8910.955445222733;52637;55945.52536896235;59156;60580;62949.68525157345;86335.56832215798;92462.52762306573;93037;93785.5310723442;95107;11586.826197607212;11641.6507504775;11675.182737208313;11794.410279486163;11984;13025.739485210432;13089.869101309247;13178.472861085449;13204.801927970895;13568.796468053184;12043.277403355476;12123.454444550192;12213.75572488563;12277.631671049883;12761.298128603015;12258.816117758419;12347.3826308686;12435.264241866775;12446.937765311115;12665.986721062109;12119.45462454174;12162.939185304016;12166.817497737364;12205.877941220475;12293.446794894073;9672.274579406714;10001.24978128839;10087.193024557871;10490.160787137163;10597.9470102649;10313.896861031593;10410.635627752992;10544.894551054698;10570.365778053341;10596.735081623021 08:49:49 INFO - PROCESS | 1657 | __end_tp_report 08:49:49 INFO - PROCESS | 1657 | __start_cc_report 08:49:49 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,434 08:49:49 INFO - PROCESS | 1657 | __end_cc_report 08:49:49 INFO - PROCESS | 1657 | __startTimestamp1470757789699__endTimestamp 08:49:49 INFO - PROCESS | 1657 | 08:49:49 INFO - PROCESS | 1657 | ------- Summary: start ------- 08:49:49 INFO - PROCESS | 1657 | Number of tests: 6 08:49:49 INFO - PROCESS | 1657 | 08:49:49 INFO - PROCESS | 1657 | [#0] dojo.html Cycles:165 Average:5009.67 Median:5092.01 stddev:605.24 (11.9%) stddev-sans-first:580.88 08:49:49 INFO - PROCESS | 1657 | Values: 2763.2 2982.0 3015.0 3031.6 3033.3 4243.9 4376.1 4778.9 4902.9 4957.8 4776.3 4835.3 4879.5 4898.8 4974.4 4348.6 4469.8 4498.1 4587.7 4610.7 4539.0 4540.4 4559.4 4591.1 4638.3 4409.6 4441.0 4469.9 4513.1 4578.8 4529.9 4530.1 4559.4 4560.6 4579.3 4405.7 4538.8 4568.6 4579.1 4579.2 4460.4 4783.0 4837.8 4863.2 4888.2 5450.4 5499.3 5551.1 5556.1 5567.7 5598.7 5610.3 5621.0 5622.7 5640.2 4982.6 5092.0 5147.4 5166.3 5223.9 5693.4 5702.9 5713.8 5736.1 5750.3 5368.5 5407.3 5416.9 5423.2 5431.6 5346.4 5430.0 5441.9 5458.4 5629.9 5319.7 5415.4 5439.7 5544.3 5592.4 5497.7 5572.6 5597.0 5614.7 5626.2 4158.0 4380.8 4517.0 4529.0 4557.9 3925.6 3928.2 3975.0 3997.5 4069.2 4706.2 4949.3 4957.8 4958.6 4991.4 5329.9 5576.3 5629.6 5666.2 5701.5 4926.8 4939.4 4971.8 5019.8 5081.8 4828.4 4842.9 4876.4 4885.9 4930.6 5508.7 5734.2 5738.4 5757.8 5764.2 4455.1 5008.5 5538.3 5553.4 5690.8 5779.7 5826.7 5828.5 5840.0 5858.9 4512.0 4883.7 4936.3 5524.8 5529.3 4031.4 4370.7 5419.5 5532.9 5597.9 5102.4 5270.8 5292.7 5308.5 5352.8 5159.4 5331.7 5335.8 5381.7 5400.5 4208.4 5018.5 5105.1 5107.6 5145.9 4899.8 5295.4 5297.4 5322.5 5328.7 5051.7 5201.9 5206.3 5207.9 5223.6 08:49:49 INFO - PROCESS | 1657 | 08:49:49 INFO - PROCESS | 1657 | [#1] ext.html Cycles:180 Average:64927.18 Median:39561.31 stddev:141900.57 (358.7%) stddev-sans-first:142216.03 08:49:49 INFO - PROCESS | 1657 | Values: 436.6 473.0 480.0 480.1 484.2 32224.2 41034.6 41146.8 41179.0 41212.2 35892.0 42076.4 42106.9 42296.7 42370.6 9404.8 9571.2 9600.5 9601.1 9601.2 9479.6 9559.7 9576.6 9587.1 9589.0 9592.5 9610.9 9618.9 9640.3 9642.4 8185.6 9535.0 9565.2 9594.4 9601.3 9373.9 9443.2 9448.8 9454.9 9458.6 39303.0 39446.8 39475.4 39506.8 39615.8 2803.3 3098.5 3169.9 3274.2 3337.4 29964.6 29965.0 30027.2 31022.8 31053.8 3021.4 3035.3 3053.1 3077.6 3158.9 404508.0 406296.0 407101.0 407387.0 407576.9 32119.4 32148.0 32197.0 32205.0 32334.0 30299.8 32918.5 33090.8 34819.0 35976.0 16245.8 16293.8 16385.0 16413.8 16474.7 12368.4 12423.5 12534.2 12541.6 12583.2 14822.6 19267.3 19284.3 19388.6 19419.8 7705.2 7834.8 7837.9 7879.1 7887.4 778.8 864.0 896.6 965.3 1004.4 30364.0 30427.0 30463.7 30661.5 31621.8 765.7 988.8 1028.5 1044.3 1079.9 15416.5 15456.6 15628.8 15688.5 15701.1 597942.0 614120.0 614691.0 615032.0 615337.0 58926.4 59146.4 59635.7 59731.7 59732.7 1213.8 1256.5 1267.1 1296.4 1299.3 26253.1 28740.0 28749.4 29277.7 29297.4 50933.0 50982.0 51402.5 51559.5 51580.2 549314.0 553279.0 553790.0 556114.0 556151.0 26786.5 27047.6 27051.2 27303.0 27315.6 46847.5 47236.0 47536.8 48993.3 49095.0 39836.4 40155.8 40216.2 40438.8 41670.4 32286.0 40526.4 41308.7 41455.2 41481.8 33096.8 38625.2 38819.6 39018.4 39061.4 28094.9 32523.0 34733.7 35512.0 35714.0 25701.0 25785.4 25974.4 26115.7 26424.0 08:49:49 INFO - PROCESS | 1657 | 08:49:49 INFO - PROCESS | 1657 | [#2] jquery.html Cycles:180 Average:33344.88 Median:4459.34 stddev:95528.42 (2142.2%) stddev-sans-first:95764.25 08:49:49 INFO - PROCESS | 1657 | Values: 336.7 339.8 348.8 364.6 368.2 18545.4 19220.5 20215.0 20328.4 20445.5 17496.5 19845.3 20600.0 20850.0 21023.9 5611.4 5768.9 5791.5 5834.6 5836.1 5366.4 5438.0 5767.3 5810.4 5838.6 5667.3 5770.5 5811.1 5890.0 5953.7 4648.4 5909.7 5942.2 5944.5 5949.1 5717.0 5718.4 5764.3 5777.1 5787.2 16511.9 24693.1 25134.9 25359.7 25817.2 2085.9 2238.1 2259.8 2320.9 2330.7 8072.0 9788.2 9868.5 9906.7 9981.8 1586.6 1713.6 1773.8 1787.5 1846.1 100863.5 125939.0 127873.4 128018.4 129561.4 8777.5 9248.0 11179.6 11193.9 11247.3 12761.4 14344.1 14344.6 14350.6 14406.9 7649.7 8331.6 8388.7 8527.3 8541.9 5969.0 8285.0 8306.9 8397.5 8462.2 3801.1 4347.9 4356.3 4423.4 4495.3 1578.9 1827.8 1884.4 1888.9 1890.3 581.8 810.7 816.3 843.4 853.9 17775.4 19284.3 20970.0 21150.7 21151.5 857.2 875.5 881.6 884.1 894.7 8757.4 10523.9 11035.8 11053.7 11106.3 504413.0 525239.4 592728.0 612542.0 612702.0 94524.0 110780.0 119242.4 126198.0 127333.0 394.9 842.6 896.5 899.4 902.6 8709.6 10526.0 10961.7 10994.9 11417.1 50854.2 51782.0 52287.5 52480.2 53181.7 53605.7 75859.9 77602.0 79527.0 79995.6 6389.6 7542.7 7818.4 7942.5 8188.2 10591.5 12836.9 12900.9 13123.5 13151.5 8995.6 11011.0 11225.5 11334.7 11401.9 9397.8 11019.7 11185.4 11277.5 11539.1 11061.3 11224.8 11435.8 11469.1 11489.2 7687.3 7981.6 7986.8 8302.1 8367.8 8122.2 9881.6 10955.8 11178.0 11347.7 08:49:49 INFO - PROCESS | 1657 | 08:49:49 INFO - PROCESS | 1657 | [#3] mootools.html Cycles:180 Average:5543.04 Median:4953.94 stddev:3113.94 (62.9%) stddev-sans-first:3107.34 08:49:49 INFO - PROCESS | 1657 | Values: 1430.0 1440.0 1548.9 1591.9 1623.4 7624.6 8115.7 8230.1 8757.4 8757.9 8354.6 8579.9 8585.2 8596.1 8598.8 2203.1 2244.6 2248.4 2252.6 2253.1 2207.4 2237.9 2267.8 2271.4 2280.7 2246.8 2247.9 2259.4 2264.1 2273.6 2239.0 2251.8 2252.4 2263.5 2263.7 2179.4 2226.2 2230.9 2244.7 2249.3 8533.9 8583.6 8650.5 8654.0 8680.5 5011.5 5173.2 5181.5 5191.1 5220.7 3295.9 3393.7 3489.5 3530.3 3548.8 1031.7 1036.9 1045.9 1058.2 1063.3 11091.3 11130.5 11250.8 11270.3 11288.0 4382.3 4430.6 4585.4 4593.4 4612.3 9498.9 9657.0 9669.8 9683.9 9704.3 4435.9 5010.6 5125.9 5148.4 5157.3 4239.7 4548.6 4644.9 4662.1 4701.2 3787.8 3795.7 3888.2 3923.1 3942.9 1029.8 1212.2 1216.9 1222.6 1225.7 817.1 838.2 839.2 861.4 863.9 5581.1 5792.4 5867.1 5997.7 6056.3 840.8 841.9 911.2 952.2 952.7 3353.7 3758.1 3947.8 3974.3 4012.8 10017.0 10354.1 10394.2 10410.9 10413.8 10339.7 10344.8 10360.5 10364.3 10414.8 7435.8 7769.0 7771.9 7852.9 7901.6 5709.9 5754.8 5786.4 5823.7 5978.8 9456.2 9743.8 9747.4 9752.8 9757.2 9512.8 9849.2 9984.2 10025.2 10049.2 6779.3 7084.4 7189.2 7296.6 7415.6 8245.7 8283.7 8306.3 8314.9 8321.5 7287.7 7380.3 7421.0 7441.2 7520.1 6976.5 7131.7 7174.4 7241.2 7312.3 5940.0 6747.9 6809.2 6840.0 6898.7 4847.0 4890.7 4903.5 4908.9 4999.0 4833.9 4901.7 4903.8 4908.9 5016.8 08:49:49 INFO - PROCESS | 1657 | 08:49:49 INFO - PROCESS | 1657 | [#4] prototype.html Cycles:180 Average:3792.26 Median:4237.17 stddev:1140.57 (26.9%) stddev-sans-first:1112.72 08:49:49 INFO - PROCESS | 1657 | Values: 271.0 277.0 286.0 287.8 289.0 4155.8 4358.5 4409.3 4471.2 4471.5 4224.4 4401.9 4442.5 4444.8 4447.7 3532.7 3546.1 3548.0 3549.3 3584.2 2730.8 2899.6 3535.9 3551.0 3573.0 3369.9 3425.3 3472.6 3505.2 3515.9 2832.6 2970.5 3483.0 3494.7 3505.8 2319.7 2914.3 3069.6 3073.1 3085.9 3291.1 3959.0 4077.9 4154.6 4175.4 3888.0 3888.4 3892.8 3910.7 3912.4 4104.4 4273.4 4620.4 4651.3 4671.1 2742.8 3049.1 3178.6 3297.5 3318.8 4099.9 4765.9 4852.7 4875.0 4897.8 2805.7 3371.9 3387.0 3399.0 3468.6 3252.2 3445.6 3447.9 3457.8 3460.3 3493.4 3714.7 3811.9 3892.4 3915.0 3866.9 4487.4 4647.4 4711.9 4774.8 995.1 1096.3 1142.6 1180.8 1181.9 665.7 809.6 811.2 812.6 823.8 3789.0 4209.7 4250.0 4299.7 4301.0 4210.3 4469.6 4701.7 4776.6 4780.8 3578.7 3721.8 3806.5 3966.5 4271.5 2480.9 2486.4 2490.5 2497.8 2519.3 4916.6 4923.6 4924.8 4948.8 4956.8 4582.3 4890.0 4891.3 4912.4 4936.4 4210.0 4888.7 4916.6 4925.1 4925.9 4154.0 4335.0 4759.9 4762.4 4802.5 4517.4 4691.5 4718.3 4748.3 4796.8 4643.4 4849.0 4867.3 4872.5 4891.9 3085.9 3333.4 3343.9 3352.2 3375.6 4320.0 4648.7 4759.7 4760.8 4793.6 3731.3 4341.3 4597.7 4603.3 4627.7 3780.2 4411.7 4606.1 4621.7 4647.6 4546.3 4556.0 4560.2 4573.9 4587.1 3972.0 4595.7 4598.7 4658.9 4662.7 4346.8 4433.8 4594.1 4597.3 4604.9 08:49:49 INFO - PROCESS | 1657 | 08:49:49 INFO - PROCESS | 1657 | [#5] yui.html Cycles:180 Average:23275.03 Median:3680.63 stddev:36338.64 (987.3%) stddev-sans-first:36400.59 08:49:49 INFO - PROCESS | 1657 | Values: 572.5 664.2 688.2 713.5 748.0 14711.9 15145.5 15416.2 15417.4 15467.1 14012.9 14108.9 14147.0 14156.4 14165.1 658.6 818.8 823.7 837.9 860.3 835.0 844.1 847.2 850.0 850.4 889.4 908.3 909.4 911.6 914.8 898.9 900.4 902.2 904.4 906.5 660.1 667.7 669.7 669.8 671.1 14447.3 14540.9 14605.1 14625.0 14653.4 14251.4 14312.9 14367.6 14447.7 14506.8 11473.6 11602.8 11667.4 11908.1 12189.9 3355.5 3663.5 3717.3 3890.7 3910.0 104092.0 105356.5 105588.9 106769.0 106789.0 10758.6 10923.6 10959.0 11107.7 11512.1 22482.7 22600.2 22649.9 22658.1 22735.0 7868.8 7941.5 8024.4 8066.4 8169.1 4354.2 4418.6 4421.2 4435.6 4585.7 3565.8 3674.2 3680.2 3681.0 3695.2 676.4 700.5 717.8 727.6 739.3 507.0 523.7 577.1 605.4 618.1 7876.5 9240.8 9344.4 9429.7 9435.4 558.5 558.9 582.9 602.4 611.8 4528.2 4568.6 4647.4 4731.3 4841.2 90789.5 112438.0 129312.4 132410.0 137589.0 93476.5 117691.0 120345.0 130155.3 131864.4 97637.0 109559.8 110234.0 111181.0 111778.4 6752.7 6832.0 8733.2 8864.0 8911.0 52637.0 55945.5 59156.0 60580.0 62949.7 86335.6 92462.5 93037.0 93785.5 95107.0 11586.8 11641.7 11675.2 11794.4 11984.0 13025.7 13089.9 13178.5 13204.8 13568.8 12043.3 12123.5 12213.8 12277.6 12761.3 12258.8 12347.4 12435.3 12446.9 12666.0 12119.5 12162.9 12166.8 12205.9 12293.4 9672.3 10001.2 10087.2 10490.2 10597.9 10313.9 10410.6 10544.9 10570.4 10596.7 08:49:49 INFO - PROCESS | 1657 | -------- Summary: end -------- 08:49:49 INFO - PROCESS | 1657 | 08:49:50 INFO - TEST-INFO | 1657: exit 0 08:49:50 INFO - TEST-OK | dromaeo_css | took 1113628ms 08:49:50 INFO - TEST-START | kraken 08:49:50 INFO - operating with platform_type : mac_ 08:49:50 INFO - Initialising browser for kraken test... 08:49:50 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpF67N2B/profile http://localhost:49271/getInfo.html) 08:49:53 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 08:49:53 INFO - PROCESS | 1666 | colorDepth:24 08:49:53 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 08:49:53 INFO - PROCESS | 1666 | __metrics 08:49:53 ERROR - PROCESS | 1666 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 08:49:54 INFO - TEST-INFO | 1666: exit 0 08:49:54 INFO - Browser initialized. 08:49:54 INFO - Running cycle 1/1 for kraken test... 08:49:54 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpF67N2B/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:50:06 INFO - PROCESS | 1668 | RSS: Main: 254500864 08:50:06 INFO - PROCESS | 1668 | 08:50:09 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 08:50:10 INFO - PROCESS | 1668 | RSS: Main: 267329536 08:50:10 INFO - PROCESS | 1668 | 08:50:13 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 08:50:13 INFO - PROCESS | 1668 | RSS: Main: 276611072 08:50:13 INFO - PROCESS | 1668 | 08:50:17 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 08:50:18 INFO - PROCESS | 1668 | RSS: Main: 279408640 08:50:18 INFO - PROCESS | 1668 | 08:50:20 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 08:50:21 INFO - PROCESS | 1668 | RSS: Main: 280461312 08:50:21 INFO - PROCESS | 1668 | 08:50:23 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:50:24 INFO - PROCESS | 1668 | RSS: Main: 280870912 08:50:24 INFO - PROCESS | 1668 | 08:50:27 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 08:50:27 INFO - PROCESS | 1668 | RSS: Main: 282705920 08:50:27 INFO - PROCESS | 1668 | 08:50:30 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 08:50:31 INFO - PROCESS | 1668 | RSS: Main: 283418624 08:50:31 INFO - PROCESS | 1668 | 08:50:34 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 08:50:35 INFO - PROCESS | 1668 | RSS: Main: 283029504 08:50:35 INFO - PROCESS | 1668 | 08:50:37 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:50:37 INFO - PROCESS | 1668 | RSS: Main: 282595328 08:50:37 INFO - PROCESS | 1668 | 08:50:39 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:50:40 INFO - PROCESS | 1668 | RSS: Main: 282542080 08:50:40 INFO - PROCESS | 1668 | 08:50:42 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:50:43 INFO - PROCESS | 1668 | RSS: Main: 282906624 08:50:43 INFO - PROCESS | 1668 | 08:50:46 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:50:47 INFO - PROCESS | 1668 | RSS: Main: 283099136 08:50:47 INFO - PROCESS | 1668 | 08:50:50 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:50:51 INFO - PROCESS | 1668 | RSS: Main: 283049984 08:50:51 INFO - PROCESS | 1668 | 08:50:52 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 08:50:53 INFO - PROCESS | 1668 | RSS: Main: 285159424 08:50:53 INFO - PROCESS | 1668 | 08:50:53 INFO - PROCESS | 1668 | __start_tp_report 08:50:53 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 08:50:53 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 08:50:53 INFO - PROCESS | 1668 | |i|pagename|runs| 08:50:53 INFO - PROCESS | 1668 | |0;ai-astar;108;121;112;124;145;138;117;121;121;146 08:50:53 INFO - PROCESS | 1668 | |1;audio-beat-detection;149;139;173;162;141;147;155;111;199;151 08:50:53 INFO - PROCESS | 1668 | |2;audio-dft;256;315;316;230;230;232;230;232;269;261 08:50:53 INFO - PROCESS | 1668 | |3;audio-fft;74;85;75;169;63;63;62;67;141;88 08:50:53 INFO - PROCESS | 1668 | |4;audio-oscillator;75;72;119;78;90;82;119;74;76;123 08:50:53 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;140;121;92;91;94;89;95;91;97;136 08:50:53 INFO - PROCESS | 1668 | |6;imaging-darkroom;109;121;100;109;150;100;131;152;100;105 08:50:53 INFO - PROCESS | 1668 | |7;imaging-desaturate;107;110;161;167;107;166;109;107;108;106 08:50:53 INFO - PROCESS | 1668 | |8;json-parse-financial;58;57;58;56;60;61;58;58;59;58 08:50:53 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;70;74;72;73;97;120;89;81;76;77 08:50:53 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;87;107;113;110;83;92;91;88;93;86 08:50:53 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;148;149;141;173;169;157;162;173;176;174 08:50:53 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;177;181;175;174;178;178;210;152;235;245 08:50:53 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;65;64;66;94;94;62;79;93;90;68 08:50:53 INFO - PROCESS | 1668 | __end_tp_report 08:50:53 INFO - PROCESS | 1668 | __start_cc_report 08:50:53 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,1094 08:50:53 INFO - PROCESS | 1668 | __end_cc_report 08:50:53 INFO - PROCESS | 1668 | __startTimestamp1470757853180__endTimestamp 08:50:53 INFO - PROCESS | 1668 | 08:50:53 INFO - PROCESS | 1668 | ------- Summary: start ------- 08:50:53 INFO - PROCESS | 1668 | Number of tests: 14 08:50:53 INFO - PROCESS | 1668 | 08:50:53 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:125.30 Median:122.50 stddev:13.25 (10.8%) stddev-sans-first:12.49 08:50:53 INFO - PROCESS | 1668 | Values: 108.0 121.0 112.0 124.0 145.0 138.0 117.0 121.0 121.0 146.0 08:50:53 INFO - PROCESS | 1668 | 08:50:53 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:152.70 Median:153.00 stddev:23.00 (15.0%) stddev-sans-first:24.35 08:50:53 INFO - PROCESS | 1668 | Values: 149.0 139.0 173.0 162.0 141.0 147.0 155.0 111.0 199.0 151.0 08:50:53 INFO - PROCESS | 1668 | 08:50:53 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:257.10 Median:258.50 stddev:34.06 (13.2%) stddev-sans-first:36.13 08:50:53 INFO - PROCESS | 1668 | Values: 256.0 315.0 316.0 230.0 230.0 232.0 230.0 232.0 269.0 261.0 08:50:53 INFO - PROCESS | 1668 | 08:50:53 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:88.70 Median:70.50 stddev:36.68 (52.0%) stddev-sans-first:38.51 08:50:53 INFO - PROCESS | 1668 | Values: 74.0 85.0 75.0 169.0 63.0 63.0 62.0 67.0 141.0 88.0 08:50:53 INFO - PROCESS | 1668 | 08:50:53 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:90.80 Median:75.50 stddev:21.01 (27.8%) stddev-sans-first:21.49 08:50:53 INFO - PROCESS | 1668 | Values: 75.0 72.0 119.0 78.0 90.0 82.0 119.0 74.0 76.0 123.0 08:50:53 INFO - PROCESS | 1668 | 08:50:53 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:104.60 Median:91.50 stddev:19.84 (21.7%) stddev-sans-first:16.39 08:50:53 INFO - PROCESS | 1668 | Values: 140.0 121.0 92.0 91.0 94.0 89.0 95.0 91.0 97.0 136.0 08:50:53 INFO - PROCESS | 1668 | 08:50:53 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:117.70 Median:115.00 stddev:20.17 (17.5%) stddev-sans-first:21.14 08:50:53 INFO - PROCESS | 1668 | Values: 109.0 121.0 100.0 109.0 150.0 100.0 131.0 152.0 100.0 105.0 08:50:53 INFO - PROCESS | 1668 | 08:50:53 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:124.80 Median:109.50 stddev:27.58 (25.2%) stddev-sans-first:28.49 08:50:53 INFO - PROCESS | 1668 | Values: 107.0 110.0 161.0 167.0 107.0 166.0 109.0 107.0 108.0 106.0 08:50:53 INFO - PROCESS | 1668 | 08:50:53 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:58.30 Median:58.00 stddev:1.42 (2.4%) stddev-sans-first:1.50 08:50:53 INFO - PROCESS | 1668 | Values: 58.0 57.0 58.0 56.0 60.0 61.0 58.0 58.0 59.0 58.0 08:50:53 INFO - PROCESS | 1668 | 08:50:53 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:82.90 Median:76.50 stddev:15.50 (20.3%) stddev-sans-first:15.72 08:50:53 INFO - PROCESS | 1668 | Values: 70.0 74.0 72.0 73.0 97.0 120.0 89.0 81.0 76.0 77.0 08:50:53 INFO - PROCESS | 1668 | 08:50:53 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:95.00 Median:87.50 stddev:10.85 (12.4%) stddev-sans-first:11.12 08:50:53 INFO - PROCESS | 1668 | Values: 87.0 107.0 113.0 110.0 83.0 92.0 91.0 88.0 93.0 86.0 08:50:53 INFO - PROCESS | 1668 | 08:50:53 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:162.20 Median:171.00 stddev:12.74 (7.5%) stddev-sans-first:12.44 08:50:53 INFO - PROCESS | 1668 | Values: 148.0 149.0 141.0 173.0 169.0 157.0 162.0 173.0 176.0 174.0 08:50:53 INFO - PROCESS | 1668 | 08:50:53 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.50 Median:179.50 stddev:29.65 (16.5%) stddev-sans-first:31.04 08:50:53 INFO - PROCESS | 1668 | Values: 177.0 181.0 175.0 174.0 178.0 178.0 210.0 152.0 235.0 245.0 08:50:53 INFO - PROCESS | 1668 | 08:50:53 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.50 Median:84.50 stddev:13.92 (16.5%) stddev-sans-first:14.01 08:50:53 INFO - PROCESS | 1668 | Values: 65.0 64.0 66.0 94.0 94.0 62.0 79.0 93.0 90.0 68.0 08:50:53 INFO - PROCESS | 1668 | -------- Summary: end -------- 08:50:53 INFO - PROCESS | 1668 | 08:50:54 INFO - TEST-INFO | 1668: exit 0 08:50:54 INFO - TEST-OK | kraken | took 63694ms 08:50:54 INFO - SUITE-END | took 1177s 08:50:54 INFO - Completed test suite (00:19:37) 08:50:54 INFO - javascript benchmark 08:50:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6779.3658335443615, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2763.212484441932, 2981.9999999999995, 3015.0351887396027, 3031.5604237385564, 3033.256852071243, 4243.915121697564, 4376.059147283333, 4778.880527986802, 4902.852914412569, 4957.801687932488, 4776.331313616088, 4835.323054772326, 4879.487653796354, 4898.828541001066, 4974.4030716314, 4348.608625223737, 4469.798859051334, 4498.055408364247, 4587.678862479628, 4610.700304480198, 4539.0, 4540.387047748544, 4559.361689363475, 4591.104734576759, 4638.281066434708, 4409.559044095581, 4441.022974945507, 4469.888252793674, 4513.074819661962, 4578.771061446931, 4529.8641040768825, 4530.0939812037395, 4559.430071241095, 4560.566746159109, 4579.267317229227, 4405.713628614129, 4538.818447262105, 4568.634509239253, 4579.129965306581, 4579.152856721517, 4460.375547423363, 4783.0, 4837.782299796482, 4863.221884498464, 4888.193448081062, 5450.427705090948, 5499.285092937933, 5551.139573366135, 5556.111022236423, 5567.749451274703, 5598.692071936042, 5610.270664813589, 5621.016322143609, 5622.746976386073, 5640.182173584807, 4982.576480999083, 5092.00705862357, 5147.356580427447, 5166.3025491558155, 5223.947760522384, 5693.430656934356, 5702.914456283159, 5713.8285851424525, 5736.053551164011, 5750.30996280449, 5368.463153684678, 5407.297051383295, 5416.91874621881, 5423.213634023124, 5431.565474762009, 5346.385165705916, 5430.02259593277, 5441.945580544223, 5458.372287186945, 5629.915551266734, 5319.654222475604, 5415.350157980989, 5439.728013599304, 5544.306961629796, 5592.412796656374, 5497.697626630494, 5572.58205634579, 5597.0, 5614.747336369873, 5626.240457538262, 4157.97921010399, 4380.78096095194, 4517.0, 4528.977355113269, 4557.863264102082, 3925.5878132796215, 3928.175083232496, 3975.0, 3997.480327557399, 4069.2471892699946, 4706.1999460091865, 4949.257611358325, 4957.752112394366, 4958.603311735124, 4991.3511243538105, 5329.946700532945, 5576.275084239023, 5629.634073785191, 5666.235058267163, 5701.515371193412, 4926.778294976734, 4939.407271127488, 4971.751412429364, 5019.774110164978, 5081.7967281309075, 4828.420589529279, 4842.878928026827, 4876.366072410564, 4885.926711099265, 4930.580900623487, 5508.697021663686, 5734.197212390269, 5738.397468265772, 5757.769689212385, 5764.221830052973, 4455.0644364682485, 5008.549230569266, 5538.252335934759, 5553.444655534414, 5690.800821971211, 5779.682117483579, 5826.650400975955, 5828.533717302521, 5840.036393994943, 5858.882822343444, 4511.977440112779, 4883.658143929891, 4936.333594964633, 5524.75138618755, 5529.281193444826, 4031.415444760552, 4370.672199585108, 5419.539339156058, 5532.944670553404, 5597.944020559742, 5102.387713474258, 5270.815521456884, 5292.682439053669, 5308.522232999048, 5352.839414817562, 5159.432462429055, 5331.733413329395, 5335.839924802107, 5381.73091345439, 5400.513953744182, 4208.368744688199, 5018.4730603286125, 5105.132127538253, 5107.565856902205, 5145.948540514547, 4899.779509921991, 5295.417504074471, 5297.390800057889, 5322.467753224647, 5328.733563321896, 5051.74741262928, 5201.947980520146, 5206.271122042841, 5207.921881171861, 5223.634345595772], "value": 4972.082623748407, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [436.6419535980497, 472.9668923175378, 479.99280010799845, 480.0902290160147, 484.1672323603401, 32224.194395140134, 41034.58965410345, 41146.794266028664, 41179.0, 41212.17575648489, 35892.0, 42076.368854467204, 42106.947326316855, 42296.73109806703, 42370.57629423705, 9404.764880877981, 9571.186449151795, 9600.471974041426, 9601.087896649808, 9601.231901447867, 9479.573419196118, 9559.713208603718, 9576.61693532258, 9587.089226523503, 9588.95205523975, 9592.472414017262, 9610.855837162448, 9618.855717164248, 9640.325177237597, 9642.421454712705, 8185.631646575918, 9534.952325238364, 9565.186959108483, 9594.42433453995, 9601.327907046545, 9373.906260937372, 9443.15011648948, 9448.811023779555, 9454.85817712735, 9458.621655133786, 39303.0, 39446.80276598599, 39475.407868881986, 39506.80246598777, 39615.81152565399, 2803.2991752061985, 3098.51972944194, 3169.8573564189437, 3274.2141885947203, 3337.4159522083537, 29964.550531742043, 29965.0, 30027.249318766986, 31022.844885775427, 31053.8447307762, 3021.3504096619113, 3035.3170536629304, 3053.129857990462, 3077.615298087739, 3158.936821263562, 404508.0, 406296.0, 407101.0, 407387.0, 407576.9242307569, 32119.357612847612, 32148.03555893327, 32197.0, 32205.0, 32334.0, 30299.848500757354, 32918.506222406686, 33090.8345458276, 34818.955431336595, 35976.0, 16245.756313655305, 16293.837061629232, 16385.0, 16413.83586164147, 16474.670506589802, 12368.44342004612, 12423.503059877685, 12534.185277956904, 12541.623751287476, 12583.245005299712, 14822.62943426423, 19267.325643602686, 19284.325048623232, 19388.612227755366, 19419.805801942082, 7705.190954949761, 7834.764957051298, 7837.882431763528, 7879.133295337623, 7887.369010479148, 778.8091917480141, 863.9524826134498, 896.6368620708623, 965.2663975378622, 1004.3672486333563, 30364.0, 30427.0, 30463.69536304653, 30661.540076898866, 31621.841890790398, 765.682241869629, 988.8417853143462, 1028.4651980969854, 1044.2690116918188, 1079.854219680333, 15416.46042388511, 15456.613584660248, 15628.765568516481, 15688.450904218525, 15701.057936523845, 597942.0, 614120.0, 614691.0, 615032.0, 615337.0, 58926.410735892096, 59146.408535914954, 59635.70182149061, 59731.70134149301, 59732.70133649304, 1213.769383817072, 1256.5162412471082, 1267.0940277701488, 1296.3777386854174, 1299.2853930338088, 26253.081142159932, 28739.994100206393, 28749.425011499236, 29277.707222927496, 29297.41405171927, 50933.0, 50982.0, 51402.48597513977, 51559.48440515547, 51580.22629660483, 549314.0, 553279.0, 553790.0, 556114.0, 556151.0, 26786.464270714867, 27047.594286085332, 27051.1884643461, 27303.0, 27315.590266145628, 46847.53152468568, 47236.0, 47536.7623161882, 48993.265101024226, 49095.01809963709, 39836.40245396263, 40155.799221003705, 40216.1956760869, 40438.79780601078, 41670.37494437525, 32286.0, 40526.39210411787, 41308.65269027992, 41455.1708965825, 41481.755547332425, 33096.834515827264, 38625.2274954505, 38819.6118038816, 39018.414723778595, 39061.41407878827, 28094.85952570224, 32523.0, 34733.65266347405, 35512.0, 35714.0, 25700.971961122093, 25785.355366115997, 25974.35064123412, 26115.738842611332, 26424.0], "value": 18922.07778838431, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.68688120048347, 339.7808413573245, 348.84650753668376, 364.5789113573823, 368.169777152521, 18545.35091271806, 19220.519487012814, 20215.0, 20328.390148295537, 20445.488862778402, 17496.475105746817, 19845.305414310504, 20600.0, 20850.0, 21023.894880525575, 5611.410801865796, 5768.884622307552, 5791.478766910977, 5834.620749651281, 5836.124581312794, 5366.436524164965, 5437.972810135944, 5767.33675627304, 5810.389909059551, 5838.591298609078, 5667.319921609413, 5770.538356931456, 5811.0702287633985, 5889.9705501472645, 5953.732082056296, 4648.395708557866, 5909.704514774266, 5942.168096466498, 5944.494717948979, 5949.107633854913, 5716.971415142939, 5718.370979192286, 5764.308283006066, 5777.075667893159, 5787.160861675034, 16511.917440412843, 24693.135740249185, 25134.85814273216, 25359.746402535922, 25817.225483235532, 2085.8539902206853, 2238.0935721033006, 2259.7740225977273, 2320.9419764505856, 2330.650402439631, 8072.0, 9788.21694264457, 9868.506574671237, 9906.702798916043, 9981.800363992752, 1586.643005323793, 1713.6487020160962, 1773.8403543681002, 1787.5352408373788, 1846.1138653446287, 100863.49568252185, 125939.0, 127873.36063319717, 128018.35990819987, 129561.35219323938, 8777.51723655205, 9248.0, 11179.60871369498, 11193.944030279796, 11247.268927519684, 12761.361931903368, 14344.135262415819, 14344.641383965485, 14350.569482915529, 14406.927965360106, 7649.732259370895, 8331.583420828934, 8388.706395276258, 8527.317814574943, 8541.914580854113, 5969.0, 8285.047219569826, 8306.875396869053, 8397.496150231005, 8462.19609137131, 3801.1447424329303, 4347.891287721611, 4356.324769660708, 4423.424954755861, 4495.25828238337, 1578.9131597762234, 1827.8446332061658, 1884.387574038443, 1888.8583356248062, 1890.2533499267988, 581.8370856160282, 810.6797814863096, 816.2735165702529, 843.3927572148043, 853.8633818588996, 17775.377861774778, 19284.325048623512, 20969.951502424818, 21150.682739758915, 21151.471213219793, 857.1685465098844, 875.5009644502572, 881.5504092912532, 884.1070518775954, 894.7360528644066, 8757.386982911135, 10523.947380263202, 11035.83446248307, 11053.668389948314, 11106.278091924, 504413.0, 525239.3738031285, 592728.0, 612542.0, 612702.0, 94524.0, 110780.0, 119242.40378798051, 126198.0, 127333.0, 394.8539040555013, 842.6376658037103, 896.5069211933411, 899.3749344205845, 902.6163880350824, 8709.608067637098, 10526.0, 10961.671149865517, 10994.945025274823, 11417.0866330695, 50854.23718644297, 51782.0, 52287.47712522826, 52480.21279680884, 53181.734091329294, 53605.73197133989, 75859.86210206962, 77602.0, 79527.0, 79995.60002199952, 6389.584676995981, 7542.717737984446, 7818.374530037469, 7942.523448592871, 8188.181181881764, 10591.5233814477, 12836.935815320863, 12900.935495322463, 13123.54067607629, 13151.473941042252, 8995.640174392951, 11011.0, 11225.494852731483, 11334.716632084264, 11401.885981140082, 9397.765055873659, 11019.669409917715, 11185.440727963407, 11277.492512837107, 11539.07687385024, 11061.336319820837, 11224.831627525431, 11435.771284574095, 11469.139814514112, 11489.195756296978, 7687.308142266999, 7981.553566558309, 7986.845896297189, 8302.086770455124, 8367.790805229917, 8122.228388303101, 9881.604735810488, 10955.780884382428, 11178.0498657615, 11347.659570212907], "value": 8989.18383591387, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1429.99285003575, 1439.9784003239954, 1548.8606025457707, 1591.9442819501319, 1623.4399132299368, 7624.6187690615525, 8115.675372985081, 8230.135835737252, 8757.430767000149, 8757.868631970518, 8354.582270886462, 8579.871301930476, 8585.18440748128, 8596.097409771977, 8598.828023439528, 2203.0967303405605, 2244.6408574628063, 2248.4154119928853, 2252.5945329840624, 2253.076238742116, 2207.415035015722, 2237.9104835806547, 2267.7732226777357, 2271.3526644906206, 2280.7149106361703, 2246.809021233197, 2247.932562023134, 2259.4464356232666, 2264.116994372196, 2273.6362182050875, 2238.9888050559643, 2251.7635648257024, 2252.358077947777, 2263.5472905418983, 2263.7436222896285, 2179.3788770200417, 2226.1540614566525, 2230.921917732888, 2244.696965909614, 2249.3139592424304, 8533.871991920127, 8583.570821458965, 8650.480971141751, 8653.956730216372, 8680.479171249683, 5011.5489605935645, 5173.198154286091, 5181.5077567631015, 5191.0656081905245, 5220.738963051832, 3295.8516866741056, 3393.711534519569, 3489.45913383426, 3530.329237444903, 3548.769329993568, 1031.705963800321, 1036.9325993810378, 1045.8512259315735, 1058.2063452410691, 1063.29822317271, 11091.33451992883, 11130.499127539199, 11250.831237531444, 11270.267432616936, 11287.984081432629, 4382.255016647177, 4430.62339701129, 4585.380973568593, 4593.356930029799, 4612.285095810088, 9498.857517137249, 9656.95171524138, 9669.758256043515, 9683.90316096844, 9704.27217958656, 4435.889102772457, 5010.649254552146, 5125.8718532036255, 5148.433672296044, 5157.303763991813, 4239.703220774578, 4548.5906268436, 4644.883877903012, 4662.067586482717, 4701.200795864711, 3787.791671458096, 3795.7094587840265, 3888.183481468923, 3923.097687531884, 3942.9211415772097, 1029.8249297619423, 1212.2060050666828, 1216.9291023899088, 1222.614876313976, 1225.6874497003207, 817.0807841178674, 838.2455789789117, 839.202757380491, 861.3582880753753, 863.8617821148586, 5581.10702287632, 5792.420757924258, 5867.090600956859, 5997.670128142994, 6056.3338032816355, 840.8318336332758, 841.865301551761, 911.2345629671108, 952.2334520710756, 952.7237101240563, 3353.6752982571747, 3758.1168425420005, 3947.822347994347, 3974.3436038844334, 4012.7592344459426, 10016.998300169924, 10354.068133867988, 10394.22043346752, 10410.947945260225, 10413.791724165474, 10339.689809305733, 10344.844827327595, 10360.481975901175, 10364.326318789255, 10414.791704165875, 7435.8141046474275, 7768.961155194301, 7771.883421748678, 7852.921470785219, 7901.617216987004, 5709.885802283848, 5754.827355179351, 5786.363500015079, 5823.650580965155, 5978.760849565969, 9456.243500519804, 9743.756406089904, 9747.415155090717, 9752.756181095529, 9757.170640495637, 9512.762180945532, 9849.212063034796, 9984.151347135576, 10025.248106391904, 10049.246306527186, 6779.288174741581, 7084.397826184832, 7189.173245076883, 7296.562206267641, 7415.5550666958, 8245.711400100967, 8283.710070147516, 8306.293965013041, 8314.916850831414, 8321.459105158145, 7287.672054757442, 7380.298871607189, 7420.998165247623, 7441.218672039358, 7520.135184453858, 6976.476764242803, 7131.714731410893, 7174.354308112295, 7241.239669834592, 7312.341889229995, 5939.970300148471, 6747.932520674731, 6809.182898052265, 6840.008198811244, 6898.655067246718, 4846.95153048465, 4890.706557606555, 4903.479921224432, 4908.85273441783, 4999.0, 4833.936533962663, 4901.681390709592, 4903.779329930233, 4908.870959679365, 5016.849494515024], "value": 4438.29730749592, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [270.9742574455427, 276.96537932758406, 286.0104040021526, 287.7927891917818, 288.9812162209456, 4155.792210389476, 4358.474533913137, 4409.250427427337, 4471.221817564161, 4471.50813410525, 4224.387463817745, 4401.87752123209, 4442.48911375192, 4444.82220711172, 4447.688661793668, 3532.6643968822987, 3546.060294022086, 3548.0, 3549.3433714762723, 3584.2473080652962, 2730.84980326082, 2899.6230490036264, 3535.9469607955903, 3551.0, 3572.964270357289, 3369.882054128106, 3425.2978139481347, 3472.635373285794, 3505.228849653085, 3515.912102197453, 2832.6175966244705, 2970.5395663672166, 3482.982585087071, 3494.6854783069393, 3505.8247087645514, 2319.675245465636, 2914.309700373778, 3069.5856059432135, 3073.078076577018, 3085.922851928697, 3291.1113999220315, 3959.0, 4077.8581997040874, 4154.563770804052, 4175.352820312826, 3887.961120388788, 3888.358420860555, 3892.8442862285474, 3910.687145028391, 3912.3544615138467, 4104.363823607346, 4273.358996150571, 4620.3762492063815, 4651.3255577941345, 4671.0657868426115, 2742.80800343976, 3049.1157564306545, 3178.6026746656667, 3297.505374193866, 3318.78427902188, 4099.877003689894, 4765.928511072302, 4852.708837469728, 4875.0, 4897.804087836482, 2805.719428057178, 3371.8819841305435, 3387.0, 3399.0312760863035, 3468.618451970281, 3252.154439845657, 3445.620981692012, 3447.9482807757904, 3457.75795694304, 3460.2906404187083, 3493.371193185251, 3714.6812881426704, 3811.874263104228, 3892.357760969465, 3914.9642186063656, 3866.941995870064, 4487.394201782718, 4647.395838540968, 4711.882202944886, 4774.809007639725, 995.1242906242605, 1096.276457538021, 1142.5772464188137, 1180.8228765685217, 1181.8640856301636, 665.6971078159522, 809.5952023988006, 811.2455416462628, 812.6383759227206, 823.7816978500703, 3788.962110378916, 4209.684273679486, 4249.957500424956, 4299.6775241856985, 4300.978495107504, 4210.305299625527, 4469.647764176867, 4701.717896926195, 4776.59398951093, 4780.832670856504, 3578.7137029038136, 3721.832517536717, 3806.4670946067017, 3966.5438474575794, 4271.497372154865, 2480.888360023803, 2486.401710212832, 2490.5392502387117, 2497.8126640502037, 2519.3449703076967, 4916.631252656065, 4923.606111511071, 4924.803007879645, 4948.812285051634, 4956.826511072095, 4582.289745089516, 4889.99510200816, 4891.33966914462, 4912.38595175603, 4936.407631084293, 4210.0, 4888.682235654671, 4916.631252655993, 4925.079219104624, 4925.901481970341, 4153.979230103831, 4335.002949321737, 4759.881002974953, 4762.357081794052, 4802.543758342952, 4517.435320584927, 4691.50739172382, 4718.292256161603, 4748.31149483316, 4796.784144713426, 4643.373144625432, 4849.005953779537, 4867.29424233477, 4872.537108974641, 4891.877703057452, 3085.92285192872, 3333.433316336277, 3343.933121337608, 3352.245744707421, 3375.5822554526667, 4320.006398528419, 4648.721601559598, 4759.6668233223345, 4760.809567617256, 4793.616510679068, 3731.3022574728975, 4341.305391137403, 4597.712640460676, 4603.263477843529, 4627.745473998963, 3780.2215371702328, 4411.669124815716, 4606.088473283684, 4621.653375996746, 4647.581717645428, 4546.272596384562, 4555.997680510282, 4560.156371071363, 4573.862784116482, 4587.128445595326, 3972.015121023213, 4595.678302518925, 4598.747068911242, 4658.93011604833, 4662.673612847068, 4346.760928148856, 4433.844815431574, 4594.081183763194, 4597.33354654293, 4604.907901841877], "value": 3439.2189604597006, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [572.5419664268584, 664.2195420381051, 688.2498077095964, 713.4506430048863, 748.0126233371949, 14711.852881471183, 15145.469908553203, 15416.152111633855, 15417.3833046678, 15467.149306788122, 14012.929935350308, 14108.929455352734, 14147.009709320351, 14156.433742650283, 14165.079269847452, 658.6278752504819, 818.8116733151379, 823.7075838077471, 837.857564214082, 860.3375400941271, 834.995825020874, 844.1221130024798, 847.2417186617973, 850.0097386544699, 850.4472093139447, 889.4129874282975, 908.2733812949647, 909.440693973205, 911.6080085563239, 914.7758799094231, 898.8561830107152, 900.4417261297972, 902.2105657549644, 904.4076130134772, 906.5376657904483, 660.1088530483856, 667.6561570791047, 669.6584741781678, 669.7856685860527, 671.0873212431089, 14447.277636118151, 14540.927295363561, 14605.05067170641, 14625.0, 14653.41386344545, 14251.429942802379, 14312.85687143136, 14367.640808979755, 14447.711045779026, 14506.782398264035, 11473.5984240552, 11602.767944641144, 11667.358295293756, 11908.106891983136, 12189.878101219052, 3355.5302257683943, 3663.5420572428447, 3717.3308804415196, 3890.727649064567, 3909.9609003910164, 104091.99999999924, 105356.47321763342, 105588.94411055945, 106769.0, 106789.0, 10758.602853775197, 10923.617673381552, 10958.958898904591, 11107.722306942229, 11512.079033677286, 22482.66276005861, 22600.208992685177, 22649.867506624603, 22658.093676253095, 22735.0, 7868.842623147505, 7941.523508589503, 8024.398170137263, 8066.354691624656, 8169.060558035783, 4354.172707185625, 4418.5802348776815, 4421.2262854001065, 4435.6229720473475, 4585.701929374647, 3565.750397472204, 3674.1733110050027, 3680.2087551176623, 3681.042928838521, 3695.2424752925585, 676.4317972902785, 700.5131433653562, 717.8277213468834, 727.5743689941444, 739.2570466681059, 507.0036086727423, 523.6543881038497, 577.1400613086471, 605.4006533531859, 618.0821480102045, 7876.527408355517, 9240.815183696423, 9344.392614480037, 9429.715355656059, 9435.433873967446, 558.4917724870348, 558.8658721906794, 582.9417058294136, 602.4156568128908, 611.8436155665795, 4528.207563676344, 4568.588827005586, 4647.442306923191, 4731.335236916218, 4841.201201801734, 90789.54605226932, 112438.0, 129312.35343823409, 132410.0, 137589.0, 93476.53261733647, 117691.0, 120345.0, 130155.34922325327, 131864.3627127452, 97637.0, 109559.83939749058, 110234.0, 111180.99999999838, 111778.44110779394, 6752.662366881538, 6832.0435139081565, 8733.170348816851, 8864.0, 8910.955445222733, 52637.0, 55945.52536896235, 59156.0, 60580.0, 62949.68525157345, 86335.56832215798, 92462.52762306573, 93037.0, 93785.5310723442, 95107.0, 11586.826197607212, 11641.6507504775, 11675.182737208313, 11794.410279486163, 11984.0, 13025.739485210432, 13089.869101309247, 13178.472861085449, 13204.801927970895, 13568.796468053184, 12043.277403355476, 12123.454444550192, 12213.75572488563, 12277.631671049883, 12761.298128603015, 12258.816117758419, 12347.3826308686, 12435.264241866775, 12446.937765311115, 12665.986721062109, 12119.45462454174, 12162.939185304016, 12166.817497737364, 12205.877941220475, 12293.446794894073, 9672.274579406714, 10001.24978128839, 10087.193024557871, 10490.160787137163, 10597.9470102649, 10313.896861031593, 10410.635627752992, 10544.894551054698, 10570.365778053341, 10596.735081623021], "value": 7519.252492381503, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1728.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 121.0, 112.0, 124.0, 145.0, 138.0, 117.0, 121.0, 121.0, 146.0], "value": 125.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 139.0, 173.0, 162.0, 141.0, 147.0, 155.0, 111.0, 199.0, 151.0], "value": 152.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [256.0, 315.0, 316.0, 230.0, 230.0, 232.0, 230.0, 232.0, 269.0, 261.0], "value": 257.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 85.0, 75.0, 169.0, 63.0, 63.0, 62.0, 67.0, 141.0, 88.0], "value": 88.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 72.0, 119.0, 78.0, 90.0, 82.0, 119.0, 74.0, 76.0, 123.0], "value": 90.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 121.0, 92.0, 91.0, 94.0, 89.0, 95.0, 91.0, 97.0, 136.0], "value": 104.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 121.0, 100.0, 109.0, 150.0, 100.0, 131.0, 152.0, 100.0, 105.0], "value": 117.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 110.0, 161.0, 167.0, 107.0, 166.0, 109.0, 107.0, 108.0, 106.0], "value": 124.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 58.0, 56.0, 60.0, 61.0, 58.0, 58.0, 59.0, 58.0], "value": 58.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 74.0, 72.0, 73.0, 97.0, 120.0, 89.0, 81.0, 76.0, 77.0], "value": 82.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 107.0, 113.0, 110.0, 83.0, 92.0, 91.0, 88.0, 93.0, 86.0], "value": 95.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 141.0, 173.0, 169.0, 157.0, 162.0, 173.0, 176.0, 174.0], "value": 162.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 181.0, 175.0, 174.0, 178.0, 178.0, 210.0, 152.0, 235.0, 245.0], "value": 190.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 66.0, 94.0, 94.0, 62.0, 79.0, 93.0, 90.0, 68.0], "value": 77.5, "unit": "score"}]}]} 08:50:54 INFO - Return code: 0 08:50:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:50:54 ERROR - # TBPL SUCCESS # 08:50:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:50:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:50:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:50:54 INFO - 2016-08-09 08:50:54.488 screenresolution[1673:10518] starting screenresolution argv=screenresolution get 08:50:54 INFO - 2016-08-09 08:50:54.492 screenresolution[1673:10518] Display 0: 1600x1200x32@60 08:50:54 INFO - 2016-08-09 08:50:54.498 screenresolution[1674:10521] starting screenresolution argv=screenresolution list 08:50:54 INFO - Available Modes on Display 0 08:50:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:50:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:50:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:50:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:50:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:50:54 INFO - Intel Iris: 08:50:54 INFO - Chipset Model: Intel Iris 08:50:54 INFO - Type: GPU 08:50:54 INFO - Bus: Built-In 08:50:54 INFO - VRAM (Dynamic, Max): 1536 MB 08:50:54 INFO - Vendor: Intel (0x8086) 08:50:54 INFO - Device ID: 0x0a2e 08:50:54 INFO - Revision ID: 0x0009 08:50:54 INFO - Displays: 08:50:54 INFO - Display: 08:50:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:50:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:50:54 INFO - Display Serial Number: 1600x1200 60 08:50:54 INFO - Main Display: Yes 08:50:54 INFO - Mirror: Off 08:50:54 INFO - Online: Yes 08:50:54 INFO - Rotation: Supported 08:50:54 INFO - Return code: 0 08:50:54 INFO - Running post-action listener: _resource_record_post_action 08:50:54 INFO - [mozharness: 2016-08-09 15:50:54.749372Z] Finished run-tests step (success) 08:50:54 INFO - Running post-run listener: _resource_record_post_run 08:50:54 INFO - Total resource usage - Wall time: 1215s; CPU: 54.0%; Read bytes: 37397504; Write bytes: 706714112; Read time: 389; Write time: 1856 08:50:54 INFO - TinderboxPrint: CPU usage
54.5% 08:50:54 INFO - TinderboxPrint: I/O read bytes / time
37,397,504 / 389 08:50:54 INFO - TinderboxPrint: I/O write bytes / time
706,714,112 / 1,856 08:50:54 INFO - TinderboxPrint: CPU idle
2,214.2 (45.5%) 08:50:54 INFO - TinderboxPrint: CPU system
985.4 (20.3%) 08:50:54 INFO - TinderboxPrint: CPU user
1,662.1 (34.2%) 08:50:54 INFO - TinderboxPrint: Swap in / out
443,650,048 / 0 08:50:54 INFO - install - Wall time: 37s; CPU: 55.0%; Read bytes: 393359872; Write bytes: 381416960; Read time: 29941; Write time: 650 08:50:54 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 31198208; Write bytes: 297878528; Read time: 305; Write time: 1152 08:50:55 INFO - Running post-run listener: _upload_blobber_files 08:50:55 INFO - Blob upload gear active. 08:50:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:50:55 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 08:50:55 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:50:55 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:50:55 INFO - (blobuploader) - INFO - Open directory for files ... 08:50:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:50:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:50:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:50:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:50:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:50:56 INFO - (blobuploader) - INFO - Done attempting. 08:50:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:50:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:50:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:50:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:50:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:50:57 INFO - (blobuploader) - INFO - Done attempting. 08:50:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:50:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:50:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:50:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:50:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:50:59 INFO - (blobuploader) - INFO - Done attempting. 08:50:59 INFO - (blobuploader) - INFO - Iteration through files over. 08:50:59 INFO - Return code: 0 08:50:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:50:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:50:59 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15f61f3316f55a3d3db4b6f630bd612cae26133625cdd0cce63156cde870c008e8ac7bd5219fd2a03dc08b91bbd1bc653c3e6672f6d000c4ca18927d53d7ed37", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f129c729058d2e61c005da8c13bf4a0e64c649882be2c0e5362ba373555b278821f9eb0f0a708c3fc3c9c96a95662adec80eaa9ba515508db304802c620b83d7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/141183cde7b0bc78569b714d88cb1c0d1561aa30a5d7fd37e37a6ca88a4f16229a33b7df4f16ad4ad6a633e37c51f147d0d9d12bb220d17c836a1b90e95a9cf2"} 08:50:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:50:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:50:59 INFO - Contents: 08:50:59 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15f61f3316f55a3d3db4b6f630bd612cae26133625cdd0cce63156cde870c008e8ac7bd5219fd2a03dc08b91bbd1bc653c3e6672f6d000c4ca18927d53d7ed37", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f129c729058d2e61c005da8c13bf4a0e64c649882be2c0e5362ba373555b278821f9eb0f0a708c3fc3c9c96a95662adec80eaa9ba515508db304802c620b83d7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/141183cde7b0bc78569b714d88cb1c0d1561aa30a5d7fd37e37a6ca88a4f16229a33b7df4f16ad4ad6a633e37c51f147d0d9d12bb220d17c836a1b90e95a9cf2"} 08:50:59 INFO - Running post-run listener: copy_logs_to_upload_dir 08:50:59 INFO - Copying logs to upload dir... 08:50:59 INFO - mkdir: /builds/slave/test/build/upload/logs 08:50:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.576653 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-08-09 08:50:59.963781) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 08:50:59.967182) ========= 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.RBWOKyL2IP/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.OvwRdOdxFL/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15f61f3316f55a3d3db4b6f630bd612cae26133625cdd0cce63156cde870c008e8ac7bd5219fd2a03dc08b91bbd1bc653c3e6672f6d000c4ca18927d53d7ed37", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f129c729058d2e61c005da8c13bf4a0e64c649882be2c0e5362ba373555b278821f9eb0f0a708c3fc3c9c96a95662adec80eaa9ba515508db304802c620b83d7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/141183cde7b0bc78569b714d88cb1c0d1561aa30a5d7fd37e37a6ca88a4f16229a33b7df4f16ad4ad6a633e37c51f147d0d9d12bb220d17c836a1b90e95a9cf2"} build_url:https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011874 build_url: 'https://queue.taskcluster.net/v1/task/BRDKRL34RP6Ouz6Kywjr1w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/15f61f3316f55a3d3db4b6f630bd612cae26133625cdd0cce63156cde870c008e8ac7bd5219fd2a03dc08b91bbd1bc653c3e6672f6d000c4ca18927d53d7ed37", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f129c729058d2e61c005da8c13bf4a0e64c649882be2c0e5362ba373555b278821f9eb0f0a708c3fc3c9c96a95662adec80eaa9ba515508db304802c620b83d7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/141183cde7b0bc78569b714d88cb1c0d1561aa30a5d7fd37e37a6ca88a4f16229a33b7df4f16ad4ad6a633e37c51f147d0d9d12bb220d17c836a1b90e95a9cf2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 08:51:00.002158) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 08:51:00.002543) ========= 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.RBWOKyL2IP/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.OvwRdOdxFL/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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.005279 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 08:51:00.054058) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 08:51:00.054661) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 08:51:00.055215) ========= ========= Total master_lag: 0.18 =========