builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0311 starttime: 1470757816.37 results: success (0) buildid: 20160809064620 builduid: df3f0aad8c01402a91b882437a2037c2 revision: 6cf0089510fad8deb866136f5b92bbced9498447 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 08:50:16.365693) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 08:50:16.366166) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 08:50:16.366475) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 08:50:16.394669) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 08:50:16.395085) ========= 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.jOh2RWEDtn/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.8AxWr1r4KF/Listeners TMPDIR=/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/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:50:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 150M=0s 2016-08-09 08:50:16 (150 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.149250 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 08:50:16.566589) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 08:50:16.569979) ========= 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.jOh2RWEDtn/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.8AxWr1r4KF/Listeners TMPDIR=/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/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.026788 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 08:50:16.618691) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-09 08:50:16.619052) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6cf0089510fad8deb866136f5b92bbced9498447 --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 6cf0089510fad8deb866136f5b92bbced9498447 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jOh2RWEDtn/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.8AxWr1r4KF/Listeners TMPDIR=/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/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:50:16,707 truncating revision to first 12 chars 2016-08-09 08:50:16,707 Setting DEBUG logging. 2016-08-09 08:50:16,707 attempt 1/10 2016-08-09 08:50:16,707 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6cf0089510fa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-09 08:50:17,722 unpacking tar archive at: mozilla-central-6cf0089510fa/testing/mozharness/ program finished with exit code 0 elapsedTime=1.292223 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-09 08:50:17.941230) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 08:50:17.941780) ========= script_repo_revision: 6cf0089510fad8deb866136f5b92bbced9498447 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 08:50:17.942450) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 08:50:17.943220) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 08:50:17.962403) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-08-09 08:50:17.962840) ========= /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.jOh2RWEDtn/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.8AxWr1r4KF/Listeners TMPDIR=/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/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:50:18 INFO - MultiFileLogger online at 20160809 08:50:18 in /builds/slave/test 08:50:18 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:50:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:50:18 INFO - {'append_to_log': False, 08:50:18 INFO - 'base_work_dir': '/builds/slave/test', 08:50:18 INFO - 'blob_upload_branch': 'Firefox', 08:50:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:50:18 INFO - 'branch': 'Firefox', 08:50:18 INFO - 'buildbot_json_path': 'buildprops.json', 08:50:18 INFO - 'config_files': ('talos/mac_config.py',), 08:50:18 INFO - 'default_actions': ('clobber', 08:50:18 INFO - 'read-buildbot-config', 08:50:18 INFO - 'download-and-extract', 08:50:18 INFO - 'populate-webroot', 08:50:18 INFO - 'create-virtualenv', 08:50:18 INFO - 'install', 08:50:18 INFO - 'run-tests'), 08:50:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:50:18 INFO - 'download_minidump_stackwalk': True, 08:50:18 INFO - 'download_symbols': 'ondemand', 08:50:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:50:18 INFO - 'tooltool.py': '/tools/tooltool.py', 08:50:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:50:18 INFO - '/tools/misc-python/virtualenv.py')}, 08:50:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:50:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:50:18 INFO - 'installer_path': 'installer.exe', 08:50:18 INFO - 'log_level': 'info', 08:50:18 INFO - 'log_name': 'talos', 08:50:18 INFO - 'log_to_console': True, 08:50:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:50:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:50:18 INFO - 'opt_config_files': (), 08:50:18 INFO - 'pip_index': False, 08:50:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:50:18 INFO - 'cmd': ('bash', 08:50:18 INFO - '-c', 08:50:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:50:18 INFO - 'enabled': True, 08:50:18 INFO - 'halt_on_failure': False, 08:50:18 INFO - 'name': 'check_screen_resolution'},), 08:50:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:50:18 INFO - 'cmd': ('bash', 08:50:18 INFO - '-c', 08:50:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:50:18 INFO - 'enabled': True, 08:50:18 INFO - 'halt_on_failure': False, 08:50:18 INFO - 'name': 'check_screen_resolution'},), 08:50:18 INFO - 'run_cmd_checks_enabled': True, 08:50:18 INFO - 'sps_profile': False, 08:50:18 INFO - 'sps_profile_interval': 0, 08:50:18 INFO - 'suite': 'dromaeojs-e10s', 08:50:18 INFO - 'system_bits': '32', 08:50:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:50:18 INFO - 'title': 't-yosemite-r7-0311', 08:50:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:50:18 INFO - 'use_talos_json': True, 08:50:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:50:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:50:18 INFO - 'work_dir': 'build'} 08:50:18 INFO - [mozharness: 2016-08-09 15:50:18.174297Z] Running clobber step. 08:50:18 INFO - Running pre-action listener: _resource_record_pre_action 08:50:18 INFO - Running main action method: clobber 08:50:18 INFO - rmtree: /builds/slave/test/build 08:50:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:50:20 INFO - Running post-action listener: _resource_record_post_action 08:50:20 INFO - [mozharness: 2016-08-09 15:50:20.059905Z] Finished clobber step (success) 08:50:20 INFO - [mozharness: 2016-08-09 15:50:20.060018Z] Running read-buildbot-config step. 08:50:20 INFO - Running pre-action listener: _resource_record_pre_action 08:50:20 INFO - Running main action method: read_buildbot_config 08:50:20 INFO - Using buildbot properties: 08:50:20 INFO - { 08:50:20 INFO - "project": "", 08:50:20 INFO - "product": "firefox", 08:50:20 INFO - "script_repo_revision": "production", 08:50:20 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:50:20 INFO - "repository": "", 08:50:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:50:20 INFO - "buildid": "20160809064620", 08:50:20 INFO - "pgo_build": "False", 08:50:20 INFO - "basedir": "/builds/slave/test", 08:50:20 INFO - "buildnumber": 35, 08:50:20 INFO - "slavename": "t-yosemite-r7-0311", 08:50:20 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 08:50:20 INFO - "platform": "yosemite_r7", 08:50:20 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:50:20 INFO - "branch": "mozilla-central", 08:50:20 INFO - "repo_path": "mozilla-central", 08:50:20 INFO - "revision": "6cf0089510fad8deb866136f5b92bbced9498447", 08:50:20 INFO - "stage_platform": "macosx64", 08:50:20 INFO - "builduid": "df3f0aad8c01402a91b882437a2037c2", 08:50:20 INFO - "slavebuilddir": "test" 08:50:20 INFO - } 08:50:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/PW1bgKdlTYmwig-HuUkgBQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 08:50:20 INFO - Running post-action listener: _resource_record_post_action 08:50:20 INFO - [mozharness: 2016-08-09 15:50:20.063054Z] Finished read-buildbot-config step (success) 08:50:20 INFO - [mozharness: 2016-08-09 15:50:20.063155Z] Running download-and-extract step. 08:50:20 INFO - Running pre-action listener: _resource_record_pre_action 08:50:20 INFO - Running main action method: download_and_extract 08:50:20 INFO - mkdir: /builds/slave/test/build/tests 08:50:20 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:50:20 INFO - trying https://queue.taskcluster.net/v1/task/PW1bgKdlTYmwig-HuUkgBQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 08:50:20 INFO - Downloading https://queue.taskcluster.net/v1/task/PW1bgKdlTYmwig-HuUkgBQ/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:50:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PW1bgKdlTYmwig-HuUkgBQ/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:50:21 INFO - Downloaded 1199 bytes. 08:50:21 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 08:50:21 INFO - Using the following test package requirements: 08:50:21 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 08:50:21 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:50:21 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 08:50:21 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:50:21 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 08:50:21 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:50:21 INFO - u'jsshell-mac.zip'], 08:50:21 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:50:21 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 08:50:21 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 08:50:21 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:50:21 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 08:50:21 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:50:21 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 08:50:21 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:50:21 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 08:50:21 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 08:50:21 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 08:50:21 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:50:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:21 INFO - trying https://queue.taskcluster.net/v1/task/PW1bgKdlTYmwig-HuUkgBQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 08:50:21 INFO - Downloading https://queue.taskcluster.net/v1/task/PW1bgKdlTYmwig-HuUkgBQ/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:50:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PW1bgKdlTYmwig-HuUkgBQ/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:50:21 INFO - Downloaded 16325921 bytes. 08:50:21 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:50:21 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:50:24 INFO - Return code: 0 08:50:24 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:50:24 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:50:24 INFO - trying https://queue.taskcluster.net/v1/task/PW1bgKdlTYmwig-HuUkgBQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 08:50:24 INFO - Downloading https://queue.taskcluster.net/v1/task/PW1bgKdlTYmwig-HuUkgBQ/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:50:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PW1bgKdlTYmwig-HuUkgBQ/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:50:25 INFO - Downloaded 16325921 bytes. 08:50:25 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:50:25 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:50:29 INFO - Return code: 0 08:50:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:29 INFO - trying https://queue.taskcluster.net/v1/task/PW1bgKdlTYmwig-HuUkgBQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 08:50:29 INFO - Downloading https://queue.taskcluster.net/v1/task/PW1bgKdlTYmwig-HuUkgBQ/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:50:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PW1bgKdlTYmwig-HuUkgBQ/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:50:30 INFO - Downloaded 14413723 bytes. 08:50:30 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:50:30 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:50:30 INFO - Return code: 0 08:50:30 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:50:30 INFO - trying https://queue.taskcluster.net/v1/task/PW1bgKdlTYmwig-HuUkgBQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:50:30 INFO - Downloading https://queue.taskcluster.net/v1/task/PW1bgKdlTYmwig-HuUkgBQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 08:50:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PW1bgKdlTYmwig-HuUkgBQ/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:50:33 INFO - Downloaded 110888271 bytes. 08:50:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PW1bgKdlTYmwig-HuUkgBQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:50:33 INFO - mkdir: /builds/slave/test/properties 08:50:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:50:33 INFO - Writing to file /builds/slave/test/properties/build_url 08:50:33 INFO - Contents: 08:50:33 INFO - build_url:https://queue.taskcluster.net/v1/task/PW1bgKdlTYmwig-HuUkgBQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 08:50:34 INFO - Running post-action listener: _resource_record_post_action 08:50:34 INFO - Running post-action listener: set_extra_try_arguments 08:50:34 INFO - [mozharness: 2016-08-09 15:50:34.515506Z] Finished download-and-extract step (success) 08:50:34 INFO - [mozharness: 2016-08-09 15:50:34.515650Z] Running populate-webroot step. 08:50:34 INFO - Running pre-action listener: _resource_record_pre_action 08:50:34 INFO - Running main action method: populate_webroot 08:50:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:50:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:50:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:50:34 INFO - '--tppagecycles', 08:50:34 INFO - '7'], 08:50:34 INFO - 'tests': ['tsvgm']}}, 08:50:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:50:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:50:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:50:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:50:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:50:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:50:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:50:34 INFO - 'talos_options': ['--disable-e10s'], 08:50:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:50:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:50:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:50:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:50:34 INFO - 'talos_options': ['--disable-e10s'], 08:50:34 INFO - 'tests': ['damp', 'tps']}, 08:50:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:50:34 INFO - 'tests': ['damp', 'tps']}, 08:50:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:50:34 INFO - 'tests': ['dromaeo_dom']}, 08:50:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:50:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:50:34 INFO - 'tests': ['basic_compositor_video']}, 08:50:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:50:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:50:34 INFO - 'tests': ['a11yr', 08:50:34 INFO - 'ts_paint', 08:50:34 INFO - 'tpaint', 08:50:34 INFO - 'sessionrestore', 08:50:34 INFO - 'sessionrestore_no_auto_restore', 08:50:34 INFO - 'tabpaint']}, 08:50:34 INFO - 'other-e10s': {'tests': ['a11yr', 08:50:34 INFO - 'ts_paint', 08:50:34 INFO - 'tpaint', 08:50:34 INFO - 'sessionrestore', 08:50:34 INFO - 'sessionrestore_no_auto_restore', 08:50:34 INFO - 'tabpaint']}, 08:50:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:50:34 INFO - 'ts_paint', 08:50:34 INFO - 'tpaint', 08:50:34 INFO - 'sessionrestore', 08:50:34 INFO - 'sessionrestore_no_auto_restore', 08:50:34 INFO - 'tabpaint']}, 08:50:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:50:34 INFO - 'ts_paint', 08:50:34 INFO - 'tpaint', 08:50:34 INFO - 'sessionrestore', 08:50:34 INFO - 'sessionrestore_no_auto_restore', 08:50:34 INFO - 'tabpaint']}, 08:50:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:50:34 INFO - 'tests': ['a11yr', 08:50:34 INFO - 'ts_paint', 08:50:34 INFO - 'tpaint', 08:50:34 INFO - 'sessionrestore', 08:50:34 INFO - 'sessionrestore_no_auto_restore', 08:50:34 INFO - 'tabpaint']}, 08:50:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:50:34 INFO - 'tests': ['a11yr', 08:50:34 INFO - 'ts_paint', 08:50:34 INFO - 'tpaint', 08:50:34 INFO - 'sessionrestore', 08:50:34 INFO - 'sessionrestore_no_auto_restore', 08:50:34 INFO - 'tabpaint']}, 08:50:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:50:34 INFO - 'tests': ['tsvgx', 08:50:34 INFO - 'tsvgr_opacity', 08:50:34 INFO - 'tart', 08:50:34 INFO - 'tscrollx', 08:50:34 INFO - 'cart']}, 08:50:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:50:34 INFO - 'tsvgr_opacity', 08:50:34 INFO - 'tart', 08:50:34 INFO - 'tscrollx', 08:50:34 INFO - 'cart']}, 08:50:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:50:34 INFO - 'talos_options': ['--disable-e10s'], 08:50:34 INFO - 'tests': ['tp5o']}, 08:50:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:50:34 INFO - 'tests': ['tp5o']}, 08:50:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:50:34 INFO - 'talos_options': ['--disable-e10s', 08:50:34 INFO - '--xperf_path', 08:50:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:50:34 INFO - 'tests': ['tp5n']}, 08:50:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:50:34 INFO - 'talos_options': ['--xperf_path', 08:50:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:50:34 INFO - 'tests': ['tp5n']}}, 08:50:34 INFO - 'talos.zip': {'path': '', 08:50:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:50:34 INFO - Running post-action listener: _resource_record_post_action 08:50:34 INFO - [mozharness: 2016-08-09 15:50:34.535095Z] Finished populate-webroot step (success) 08:50:34 INFO - [mozharness: 2016-08-09 15:50:34.535234Z] Running create-virtualenv step. 08:50:34 INFO - Running pre-action listener: _resource_record_pre_action 08:50:34 INFO - Running main action method: create_virtualenv 08:50:34 INFO - Creating virtualenv /builds/slave/test/build/venv 08:50:34 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:50:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:50:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:50:34 INFO - Using real prefix '/tools/python27' 08:50:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:50:35 INFO - Installing distribute.............................................................................................................................................................................................done. 08:50:38 INFO - Installing pip.................done. 08:50:38 INFO - Return code: 0 08:50:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:50: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:50:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50: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:50:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10255fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025f4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f922b48f4a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024c3d30>, '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 0x102228db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024bbb20>, '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.jOh2RWEDtn/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.8AxWr1r4KF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/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:50: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', 'pip>=1.5'] in /builds/slave/test/build 08:50: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 pip>=1.5 08:50:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jOh2RWEDtn/Render', 08:50:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:38 INFO - 'HOME': '/Users/cltbld', 08:50:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:38 INFO - 'LOGNAME': 'cltbld', 08:50:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:38 INFO - 'MOZ_NO_REMOTE': '1', 08:50:38 INFO - 'NO_EM_RESTART': '1', 08:50:38 INFO - 'PAGER': '/bin/cat', 08:50:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:38 INFO - 'PWD': '/builds/slave/test', 08:50:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:38 INFO - 'SHELL': '/bin/bash', 08:50:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AxWr1r4KF/Listeners', 08:50:38 INFO - 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/', 08:50:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:38 INFO - 'USER': 'cltbld', 08:50:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:38 INFO - 'XPC_FLAGS': '0x0', 08:50:38 INFO - 'XPC_SERVICE_NAME': '0', 08:50:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:38 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:50:38 INFO - Cleaning up... 08:50:38 INFO - Return code: 0 08:50:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:50: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:50:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50: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:50:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50: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', '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 0x10255fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025f4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f922b48f4a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024c3d30>, '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 0x102228db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024bbb20>, '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.jOh2RWEDtn/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.8AxWr1r4KF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/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:50: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', 'psutil>=3.1.1'] in /builds/slave/test/build 08:50: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 psutil>=3.1.1 08:50:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jOh2RWEDtn/Render', 08:50:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:38 INFO - 'HOME': '/Users/cltbld', 08:50:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:38 INFO - 'LOGNAME': 'cltbld', 08:50:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:38 INFO - 'MOZ_NO_REMOTE': '1', 08:50:38 INFO - 'NO_EM_RESTART': '1', 08:50:38 INFO - 'PAGER': '/bin/cat', 08:50:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:38 INFO - 'PWD': '/builds/slave/test', 08:50:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:38 INFO - 'SHELL': '/bin/bash', 08:50:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AxWr1r4KF/Listeners', 08:50:38 INFO - 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/', 08:50:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:38 INFO - 'USER': 'cltbld', 08:50:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:38 INFO - 'XPC_FLAGS': '0x0', 08:50:38 INFO - 'XPC_SERVICE_NAME': '0', 08:50:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:38 INFO - Downloading/unpacking psutil>=3.1.1 08:50:38 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:50:38 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:50:38 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:50:38 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:50:38 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:50:38 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:50:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:50:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:50:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:50:42 INFO - Installing collected packages: psutil 08:50:42 INFO - Running setup.py install for psutil 08:50:42 INFO - building 'psutil._psutil_osx' extension 08:50:42 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:50:42 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:50:42 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:50:42 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:50:42 INFO - building 'psutil._psutil_posix' extension 08:50:42 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:50:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:50:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:50:43 INFO - ^ 08:50:43 INFO - 1 warning generated. 08:50:43 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:50:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:50:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:50:43 INFO - Successfully installed psutil 08:50:43 INFO - Cleaning up... 08:50:43 INFO - Return code: 0 08:50:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:50:43 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:50:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:43 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:50:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:43 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 0x10255fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025f4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f922b48f4a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024c3d30>, '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 0x102228db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024bbb20>, '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.jOh2RWEDtn/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.8AxWr1r4KF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/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:50:43 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:50:43 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:50:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jOh2RWEDtn/Render', 08:50:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:43 INFO - 'HOME': '/Users/cltbld', 08:50:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:43 INFO - 'LOGNAME': 'cltbld', 08:50:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:43 INFO - 'MOZ_NO_REMOTE': '1', 08:50:43 INFO - 'NO_EM_RESTART': '1', 08:50:43 INFO - 'PAGER': '/bin/cat', 08:50:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:43 INFO - 'PWD': '/builds/slave/test', 08:50:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:43 INFO - 'SHELL': '/bin/bash', 08:50:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AxWr1r4KF/Listeners', 08:50:43 INFO - 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/', 08:50:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:43 INFO - 'USER': 'cltbld', 08:50:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:43 INFO - 'XPC_FLAGS': '0x0', 08:50:43 INFO - 'XPC_SERVICE_NAME': '0', 08:50:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:50:43 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:50:43 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:50:43 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:50:43 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:50:43 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:50:43 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:50:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:50:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:50:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:50:46 INFO - Installing collected packages: mozsystemmonitor 08:50:46 INFO - Running setup.py install for mozsystemmonitor 08:50:46 INFO - Successfully installed mozsystemmonitor 08:50:46 INFO - Cleaning up... 08:50:46 INFO - Return code: 0 08:50:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:50:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10255fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025f4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f922b48f4a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024c3d30>, '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 0x102228db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024bbb20>, '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.jOh2RWEDtn/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.8AxWr1r4KF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/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:50:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:50:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:50:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jOh2RWEDtn/Render', 08:50:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:46 INFO - 'HOME': '/Users/cltbld', 08:50:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:46 INFO - 'LOGNAME': 'cltbld', 08:50:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:46 INFO - 'MOZ_NO_REMOTE': '1', 08:50:46 INFO - 'NO_EM_RESTART': '1', 08:50:46 INFO - 'PAGER': '/bin/cat', 08:50:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:46 INFO - 'PWD': '/builds/slave/test', 08:50:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:46 INFO - 'SHELL': '/bin/bash', 08:50:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AxWr1r4KF/Listeners', 08:50:46 INFO - 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/', 08:50:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:46 INFO - 'USER': 'cltbld', 08:50:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:46 INFO - 'XPC_FLAGS': '0x0', 08:50:46 INFO - 'XPC_SERVICE_NAME': '0', 08:50:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:46 INFO - Downloading/unpacking jsonschema==2.5.1 08:50:46 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:50:46 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:50:46 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:50:46 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:50:46 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:50:46 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:50:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:50:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:50:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:49 INFO - Downloading functools32-3.2.3-2.tar.gz 08:50:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:50:49 INFO - warning: no files found matching '*.txt' 08:50:49 INFO - no previously-included directories found matching 'build' 08:50:49 INFO - no previously-included directories found matching 'dist' 08:50:49 INFO - no previously-included directories found matching '.git*' 08:50:49 INFO - Installing collected packages: jsonschema, functools32 08:50:49 INFO - Running setup.py install for functools32 08:50:49 INFO - warning: no files found matching '*.txt' 08:50:49 INFO - no previously-included directories found matching 'build' 08:50:49 INFO - no previously-included directories found matching 'dist' 08:50:49 INFO - no previously-included directories found matching '.git*' 08:50:49 INFO - Successfully installed jsonschema functools32 08:50:49 INFO - Cleaning up... 08:50:50 INFO - Return code: 0 08:50:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:50: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:50:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50: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:50:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:50 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 0x10255fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025f4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f922b48f4a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024c3d30>, '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 0x102228db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024bbb20>, '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.jOh2RWEDtn/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.8AxWr1r4KF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/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:50:50 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:50:50 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:50:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jOh2RWEDtn/Render', 08:50:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:50 INFO - 'HOME': '/Users/cltbld', 08:50:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:50 INFO - 'LOGNAME': 'cltbld', 08:50:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:50 INFO - 'MOZ_NO_REMOTE': '1', 08:50:50 INFO - 'NO_EM_RESTART': '1', 08:50:50 INFO - 'PAGER': '/bin/cat', 08:50:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:50 INFO - 'PWD': '/builds/slave/test', 08:50:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:50 INFO - 'SHELL': '/bin/bash', 08:50:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AxWr1r4KF/Listeners', 08:50:50 INFO - 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/', 08:50:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:50 INFO - 'USER': 'cltbld', 08:50:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:50 INFO - 'XPC_FLAGS': '0x0', 08:50:50 INFO - 'XPC_SERVICE_NAME': '0', 08:50:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:50:50 INFO - Cleaning up... 08:50:50 INFO - Return code: 0 08:50:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:50: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:50:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50: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:50:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:50 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 0x10255fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025f4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f922b48f4a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024c3d30>, '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 0x102228db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024bbb20>, '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.jOh2RWEDtn/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.8AxWr1r4KF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/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:50:50 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:50:50 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:50:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jOh2RWEDtn/Render', 08:50:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:50 INFO - 'HOME': '/Users/cltbld', 08:50:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:50 INFO - 'LOGNAME': 'cltbld', 08:50:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:50 INFO - 'MOZ_NO_REMOTE': '1', 08:50:50 INFO - 'NO_EM_RESTART': '1', 08:50:50 INFO - 'PAGER': '/bin/cat', 08:50:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:50 INFO - 'PWD': '/builds/slave/test', 08:50:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:50 INFO - 'SHELL': '/bin/bash', 08:50:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AxWr1r4KF/Listeners', 08:50:50 INFO - 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/', 08:50:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:50 INFO - 'USER': 'cltbld', 08:50:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:50 INFO - 'XPC_FLAGS': '0x0', 08:50:50 INFO - 'XPC_SERVICE_NAME': '0', 08:50:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:50 INFO - Downloading/unpacking blobuploader==1.2.4 08:50:50 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:50:50 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:50:50 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:50:50 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:50:50 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:50:50 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:50:52 INFO - Downloading blobuploader-1.2.4.tar.gz 08:50:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:50:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:50:53 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:50:53 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:50:53 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:50:53 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:50:53 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:50:53 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:50:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:50:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:50:53 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:50:53 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:50:53 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:50:53 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:50:53 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:50:53 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:50:54 INFO - Downloading docopt-0.6.1.tar.gz 08:50:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:50:54 INFO - Installing collected packages: blobuploader, requests, docopt 08:50:54 INFO - Running setup.py install for blobuploader 08:50:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:50:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:50:54 INFO - Running setup.py install for requests 08:50:54 INFO - Running setup.py install for docopt 08:50:55 INFO - Successfully installed blobuploader requests docopt 08:50:55 INFO - Cleaning up... 08:50:55 INFO - Return code: 0 08:50:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:50:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:55 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 0x10255fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025f4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f922b48f4a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024c3d30>, '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 0x102228db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024bbb20>, '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.jOh2RWEDtn/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.8AxWr1r4KF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/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:50:55 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:50:55 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:50:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jOh2RWEDtn/Render', 08:50:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:55 INFO - 'HOME': '/Users/cltbld', 08:50:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:55 INFO - 'LOGNAME': 'cltbld', 08:50:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:55 INFO - 'MOZ_NO_REMOTE': '1', 08:50:55 INFO - 'NO_EM_RESTART': '1', 08:50:55 INFO - 'PAGER': '/bin/cat', 08:50:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:55 INFO - 'PWD': '/builds/slave/test', 08:50:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:55 INFO - 'SHELL': '/bin/bash', 08:50:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AxWr1r4KF/Listeners', 08:50:55 INFO - 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/', 08:50:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:55 INFO - 'USER': 'cltbld', 08:50:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:55 INFO - 'XPC_FLAGS': '0x0', 08:50:55 INFO - 'XPC_SERVICE_NAME': '0', 08:50:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:50:55 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-pe16nZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:50:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:50:55 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-XGZrZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:50:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:50:55 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-oDXJFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:50:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:50:55 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-RpLOhP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:50:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:50:55 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-sy4BOZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:50:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:50:55 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-zrR4sO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:50:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:50:56 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-rDsq6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:50:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:50:56 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-Buzs9M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:50:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:50:56 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-ekhfg8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:50:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:50:56 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-qRlMUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:50:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:50:56 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-1c79kL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:50:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:50:56 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-WdcbCS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:50:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:50:56 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-HjgUQv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:50:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:50:56 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-SpFeQt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:50:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:50:56 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-d5bzUc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:50:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:50:56 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-k0J0uN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:50:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:50:57 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-5xhp8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:50:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:50:57 INFO - Running setup.py install for manifestparser 08:50:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:50:57 INFO - Running setup.py install for mozcrash 08:50:57 INFO - Running setup.py install for mozdebug 08:50:57 INFO - Running setup.py install for mozdevice 08:50:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:50:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:50:57 INFO - Running setup.py install for mozfile 08:50:57 INFO - Running setup.py install for mozhttpd 08:50:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:50:58 INFO - Running setup.py install for mozinfo 08:50:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:50:58 INFO - Running setup.py install for mozInstall 08:50:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:50:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:50:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:50:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:50:58 INFO - Running setup.py install for mozleak 08:50:58 INFO - Running setup.py install for mozlog 08:50:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:50:58 INFO - Running setup.py install for moznetwork 08:50:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:50:58 INFO - Running setup.py install for mozprocess 08:50:58 INFO - Running setup.py install for mozprofile 08:50:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:50:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:50:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:50:59 INFO - Running setup.py install for mozrunner 08:50:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:50:59 INFO - Running setup.py install for mozscreenshot 08:50:59 INFO - Running setup.py install for moztest 08:50:59 INFO - Running setup.py install for mozversion 08:50:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:50:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:50:59 INFO - Cleaning up... 08:50:59 INFO - Return code: 0 08:50:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:50:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:59 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 0x10255fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025f4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f922b48f4a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024c3d30>, '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 0x102228db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024bbb20>, '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.jOh2RWEDtn/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.8AxWr1r4KF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/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:50:59 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:50:59 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:50:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jOh2RWEDtn/Render', 08:50:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:59 INFO - 'HOME': '/Users/cltbld', 08:50:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:59 INFO - 'LOGNAME': 'cltbld', 08:50:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:59 INFO - 'MOZ_NO_REMOTE': '1', 08:50:59 INFO - 'NO_EM_RESTART': '1', 08:50:59 INFO - 'PAGER': '/bin/cat', 08:50:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:59 INFO - 'PWD': '/builds/slave/test', 08:50:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:59 INFO - 'SHELL': '/bin/bash', 08:50:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AxWr1r4KF/Listeners', 08:50:59 INFO - 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/', 08:50:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:59 INFO - 'USER': 'cltbld', 08:50:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:59 INFO - 'XPC_FLAGS': '0x0', 08:50:59 INFO - 'XPC_SERVICE_NAME': '0', 08:50:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:51:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:51:00 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-jyTWwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:51:00 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:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:51:00 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-1y7HDC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:51:00 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:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:51:00 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-kydneZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:51:00 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:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:51:00 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-dDH_Md-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:51:00 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:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:51:00 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-JnlYo5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:51:00 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:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:51:00 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-JH8kle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:51:00 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:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:51:00 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-nspQ0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:51:00 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:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:51:00 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-kQsTL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:51:00 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:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:51:00 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-2Jx0_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:51:00 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:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:51:01 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-0kuTJl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:51:01 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:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:51:01 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-xULxtA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:51:01 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:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:51:01 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-99OgqZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:51:01 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:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:51:01 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-mdRpxx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:51:01 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:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:51:01 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-jCLJWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:51:01 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:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:51:01 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-ccvSvw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:51:01 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:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:51:01 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-0CRmuU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:51:01 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:51:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:51:01 INFO - Running setup.py (path:/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/pip-XCJvpR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:51:01 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:51:01 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:51:01 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:51:01 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:51:01 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:51:01 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:51:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:51:01 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:51:01 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:51:01 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:51:01 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:51:01 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:51:01 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:51:04 INFO - Downloading blessings-1.6.tar.gz 08:51:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:51:04 INFO - Installing collected packages: blessings 08:51:04 INFO - Running setup.py install for blessings 08:51:04 INFO - Successfully installed blessings 08:51:04 INFO - Cleaning up... 08:51:04 INFO - Return code: 0 08:51:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:51:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:51:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:51:04 INFO - Reading from file tmpfile_stdout 08:51:04 INFO - Current package versions: 08:51:04 INFO - blessings == 1.6 08:51:04 INFO - blobuploader == 1.2.4 08:51:04 INFO - docopt == 0.6.1 08:51:04 INFO - functools32 == 3.2.3-2 08:51:04 INFO - jsonschema == 2.5.1 08:51:04 INFO - manifestparser == 1.1 08:51:04 INFO - mozInstall == 1.12 08:51:04 INFO - mozcrash == 0.17 08:51:04 INFO - mozdebug == 0.1 08:51:04 INFO - mozdevice == 0.48 08:51:04 INFO - mozfile == 1.2 08:51:04 INFO - mozhttpd == 0.7 08:51:04 INFO - mozinfo == 0.9 08:51:04 INFO - mozleak == 0.1 08:51:04 INFO - mozlog == 3.3 08:51:04 INFO - moznetwork == 0.27 08:51:04 INFO - mozprocess == 0.23 08:51:04 INFO - mozprofile == 0.28 08:51:04 INFO - mozrunner == 6.12 08:51:04 INFO - mozscreenshot == 0.1 08:51:04 INFO - mozsystemmonitor == 0.3 08:51:04 INFO - moztest == 0.7 08:51:04 INFO - mozversion == 1.4 08:51:04 INFO - psutil == 3.1.1 08:51:04 INFO - requests == 1.2.3 08:51:04 INFO - wsgiref == 0.1.2 08:51:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:51:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10255fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025f4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f922b48f4a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024c3d30>, '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 0x102228db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024bbb20>, '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.jOh2RWEDtn/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.8AxWr1r4KF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/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:51:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:51:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:51:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jOh2RWEDtn/Render', 08:51:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:51:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:51:04 INFO - 'HOME': '/Users/cltbld', 08:51:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:51:04 INFO - 'LOGNAME': 'cltbld', 08:51:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:04 INFO - 'MOZ_NO_REMOTE': '1', 08:51:04 INFO - 'NO_EM_RESTART': '1', 08:51:04 INFO - 'PAGER': '/bin/cat', 08:51:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:51:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:04 INFO - 'PWD': '/builds/slave/test', 08:51:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:51:04 INFO - 'SHELL': '/bin/bash', 08:51:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AxWr1r4KF/Listeners', 08:51:04 INFO - 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/', 08:51:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:51:04 INFO - 'USER': 'cltbld', 08:51:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:51:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:51:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:04 INFO - 'XPC_FLAGS': '0x0', 08:51:04 INFO - 'XPC_SERVICE_NAME': '0', 08:51:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:51:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:05 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:51:05 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:51:05 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:51:05 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:51:05 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:51:05 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:51:05 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:51:05 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:51:05 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:51:05 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:51:05 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:51:05 INFO - Cleaning up... 08:51:05 INFO - Return code: 0 08:51:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:51:05 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:51:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:05 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:51:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:05 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 0x10255fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025f4618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f922b48f4a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024c3d30>, '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 0x102228db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024bbb20>, '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.jOh2RWEDtn/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.8AxWr1r4KF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/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:51:05 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:51:05 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:51:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jOh2RWEDtn/Render', 08:51:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:51:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:51:05 INFO - 'HOME': '/Users/cltbld', 08:51:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:51:05 INFO - 'LOGNAME': 'cltbld', 08:51:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:05 INFO - 'MOZ_NO_REMOTE': '1', 08:51:05 INFO - 'NO_EM_RESTART': '1', 08:51:05 INFO - 'PAGER': '/bin/cat', 08:51:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:51:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:05 INFO - 'PWD': '/builds/slave/test', 08:51:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:51:05 INFO - 'SHELL': '/bin/bash', 08:51:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AxWr1r4KF/Listeners', 08:51:05 INFO - 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/', 08:51:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:51:05 INFO - 'USER': 'cltbld', 08:51:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:51:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:51:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:05 INFO - 'XPC_FLAGS': '0x0', 08:51:05 INFO - 'XPC_SERVICE_NAME': '0', 08:51:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:51:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:51:05 INFO - Cleaning up... 08:51:05 INFO - Return code: 0 08:51:05 INFO - Running post-action listener: _resource_record_post_action 08:51:05 INFO - Running post-action listener: _start_resource_monitoring 08:51:05 INFO - Starting resource monitoring. 08:51:05 INFO - [mozharness: 2016-08-09 15:51:05.552083Z] Finished create-virtualenv step (success) 08:51:05 INFO - [mozharness: 2016-08-09 15:51:05.553040Z] Running install step. 08:51:05 INFO - Running pre-action listener: _resource_record_pre_action 08:51:05 INFO - Running main action method: install 08:51:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:51:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:51:05 INFO - Reading from file tmpfile_stdout 08:51:05 INFO - Detecting whether we're running mozinstall >=1.0... 08:51:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:51:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:51:05 INFO - Reading from file tmpfile_stdout 08:51:05 INFO - Output received: 08:51:05 INFO - Usage: mozinstall [options] installer 08:51:05 INFO - Options: 08:51:05 INFO - -h, --help show this help message and exit 08:51:05 INFO - -d DEST, --destination=DEST 08:51:05 INFO - Directory to install application into. [default: 08:51:05 INFO - "/builds/slave/test"] 08:51:05 INFO - --app=APP Application being installed. [default: firefox] 08:51:05 INFO - mkdir: /builds/slave/test/build/application 08:51:05 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:51:05 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:51:39 INFO - Reading from file tmpfile_stdout 08:51:39 INFO - Output received: 08:51:39 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:51:39 INFO - Running post-action listener: _resource_record_post_action 08:51:39 INFO - [mozharness: 2016-08-09 15:51:39.985892Z] Finished install step (success) 08:51:39 INFO - [mozharness: 2016-08-09 15:51:39.986063Z] Running run-tests step. 08:51:39 INFO - Running pre-action listener: _resource_record_pre_action 08:51:39 INFO - Running main action method: run_tests 08:51:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:51:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:51:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:51:40 INFO - 2016-08-09 08:51:40.000 screenresolution[1644:7483] starting screenresolution argv=screenresolution get 08:51:40 INFO - 2016-08-09 08:51:40.003 screenresolution[1644:7483] Display 0: 1600x1200x32@60 08:51:40 INFO - 2016-08-09 08:51:40.009 screenresolution[1645:7485] starting screenresolution argv=screenresolution list 08:51:40 INFO - Available Modes on Display 0 08:51:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:51:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:51:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:51:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:51:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:51:40 INFO - Intel Iris: 08:51:40 INFO - Chipset Model: Intel Iris 08:51:40 INFO - Type: GPU 08:51:40 INFO - Bus: Built-In 08:51:40 INFO - VRAM (Dynamic, Max): 1536 MB 08:51:40 INFO - Vendor: Intel (0x8086) 08:51:40 INFO - Device ID: 0x0a2e 08:51:40 INFO - Revision ID: 0x0009 08:51:40 INFO - Displays: 08:51:40 INFO - Display: 08:51:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:51:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:51:40 INFO - Display Serial Number: 1600x1200 60 08:51:40 INFO - Main Display: Yes 08:51:40 INFO - Mirror: Off 08:51:40 INFO - Online: Yes 08:51:40 INFO - Rotation: Supported 08:51:40 INFO - Return code: 0 08:51:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:51:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:51:40 INFO - Python 2.7.3 08:51:40 INFO - Return code: 0 08:51:40 INFO - grabbing minidump binary from tooltool 08:51:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:40 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1024c3d30>, '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 0x102228db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1024bbb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:51:40 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:51:40 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 08:51:40 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:51:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:51:40 INFO - Return code: 0 08:51:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:51:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:51:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:51:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:51:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:51:40 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/PW1bgKdlTYmwig-HuUkgBQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0311', '--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:51:40 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/PW1bgKdlTYmwig-HuUkgBQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0311 --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:51:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jOh2RWEDtn/Render', 08:51:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:51:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:51:40 INFO - 'HOME': '/Users/cltbld', 08:51:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:51:40 INFO - 'LOGNAME': 'cltbld', 08:51:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:51:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:51:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:40 INFO - 'MOZ_NO_REMOTE': '1', 08:51:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:51:40 INFO - 'NO_EM_RESTART': '1', 08:51:40 INFO - 'PAGER': '/bin/cat', 08:51:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:51:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:40 INFO - 'PWD': '/builds/slave/test', 08:51:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:51:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:51:40 INFO - 'SHELL': '/bin/bash', 08:51:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.8AxWr1r4KF/Listeners', 08:51:40 INFO - 'TMPDIR': '/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/', 08:51:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:51:40 INFO - 'USER': 'cltbld', 08:51:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:51:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:51:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:40 INFO - 'XPC_FLAGS': '0x0', 08:51:40 INFO - 'XPC_SERVICE_NAME': '0', 08:51:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:51:40 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/PW1bgKdlTYmwig-HuUkgBQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0311', '--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:51:40 INFO - mozversion application_buildid: 20160809064620 08:51:40 INFO - mozversion application_changeset: 6cf0089510fad8deb866136f5b92bbced9498447 08:51:40 INFO - mozversion application_display_name: Nightly 08:51:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:51:40 INFO - mozversion application_name: Firefox 08:51:40 INFO - mozversion application_remotingname: firefox 08:51:40 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:51:40 INFO - mozversion application_vendor: Mozilla 08:51:40 INFO - mozversion application_version: 51.0a1 08:51:40 INFO - mozversion platform_buildid: 20160809064620 08:51:40 INFO - mozversion platform_changeset: 6cf0089510fad8deb866136f5b92bbced9498447 08:51:40 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:51:40 INFO - mozversion platform_version: 51.0a1 08:51:40 INFO - using testdate: 1470757900 08:51:40 INFO - actual date: 1470757900 08:51:40 INFO - starting webserver on 'localhost:49271' 08:51:40 INFO - SUITE-START | Running 2 tests 08:51:40 INFO - TEST-START | dromaeo_css 08:51:40 INFO - operating with platform_type : mac_ 08:51:40 INFO - Initialising browser for dromaeo_css test... 08:51:40 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/tmpyGIl4E/profile http://localhost:49271/getInfo.html) 08:51:43 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 08:51:43 INFO - PROCESS | 1655 | colorDepth:24 08:51:43 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 08:51:43 INFO - PROCESS | 1655 | __metrics 08:51:43 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 08:51:44 INFO - TEST-INFO | 1655: exit 0 08:51:44 INFO - Browser initialized. 08:51:44 INFO - Running cycle 1/1 for dromaeo_css test... 08:51:44 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/tmpyGIl4E/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:51:56 INFO - PROCESS | 1658 | RSS: Main: 254922752 08:51:56 INFO - PROCESS | 1658 | 08:52:46 INFO - PROCESS | 1658 | 1470757966061 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:54:59 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 08:55:00 INFO - PROCESS | 1658 | RSS: Main: 265965568 08:55:00 INFO - PROCESS | 1658 | 08:58:02 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 08:58:03 INFO - PROCESS | 1658 | RSS: Main: 279953408 08:58:03 INFO - PROCESS | 1658 | 09:01:05 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 09:01:06 INFO - PROCESS | 1658 | RSS: Main: 284766208 09:01:06 INFO - PROCESS | 1658 | 09:04:08 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 09:04:08 INFO - PROCESS | 1658 | RSS: Main: 289816576 09:04:08 INFO - PROCESS | 1658 | 09:07:11 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 09:07:12 INFO - PROCESS | 1658 | RSS: Main: 292073472 09:07:12 INFO - PROCESS | 1658 | 09:10:14 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 09:10:15 INFO - PROCESS | 1658 | RSS: Main: 291332096 09:10:15 INFO - PROCESS | 1658 | 09:10:15 INFO - PROCESS | 1658 | __start_tp_report 09:10:15 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 09:10:15 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 09:10:15 INFO - PROCESS | 1658 | |i|pagename|runs| 09:10:15 INFO - PROCESS | 1658 | |0;dojo.html;3388;3478.1652403423172;3509.6139424663284;3512.63117372676;3519.824008799561;5849.941500584993;5985.311689155748;6013.428724271198;6060.1212824140475;6066.969665151668;5851.3563508014195;5977;5991.910121348184;5997.970010149954;6110.480609148217;4334.631556317717;4435.401220835178;5184.351956005499;5325;5361.946380536184;4840.177169881111;5467.863303417426;5488.341399032121;5488.91766623501;5543.473370029841;4483.618892394151;5026.919212369322;5284.180951952415;5345.705986170759;5361.08861493547;4082;5194.091034069061;5298.602604804655;5299.682019078868;5328.8401347959625;4277.401163837066;5309.814156504503;5372.140457526777;5372.785088596452;5394.109971854678;4538.364628951949;5861.413858614147;5954.374790646963;5976.073708575178;6036.486898613623;5243.370795504526;6241.7191226394925;6258.374162583751;6280.403361680633;6293.716782744787;5588.608797384185;6158.507319414436;6397.616143031387;6398.360163983611;6415.67921603918;6017.849553761191;6053.969730151321;6092.847678808065;6145.139680444741;6145.692715364214;6459.450946669492;6477.125588045647;6485.1245081914285;6489.448396886318;6519.6414197218655;6121.331035925228;6300.810975670737;6318.241810982619;6322.399372059647;6329.113924050636;6217.564770466024;6221.968890155611;6240.282367527792;6247.843803904939;6249;6169.722362493699;6207.224096987877;6250.937490625127;6274.6235225885675;6279.529035322369;6234.937650623527;6272.780452684134;6275.309715931244;6279.4662453691035;6297.370262973666;5026.447090820006;5067.391912970467;5086.033653605803;5133.178691409356;5140.665856719302;4554.066416384633;4606;4620.122176786467;4642.9535704643195;4664.300354946718;5943.405659434108;6023.969880150659;6026.969865150734;6041.335453100243;6046.395360463918;6173.351798061229;6220.284667263322;6243;6257.968710156511;6330.177076979963;6034.547408944347;6044.788432404933;6050.485708714808;6059.757609695563;6094.481969032593;4850.296706977469;5571.303587051619;5591.217229587861;5602.85992850182;5621.18492818539;6271.686415679289;6375.075614036032;6397.424231819158;6421.8073457796345;6480.092787009728;5754.395788442153;6330.240371155491;6331.461825744863;6350.714217860115;6351.96824015877;5605.757696921264;5616.578756593353;5875.236219291465;6465.579737317059;6569.244536878129;6201.503879689704;6205.689715514297;6223.097650840693;6233.563650544418;6317.115603815561;6312.779052733133;6335.683215839282;6364.586301890362;6370.936290637034;6379.298277189599;6089.908651370144;6191.659458729633;6224.657643829632;6240.719167637558;6248.750049997949;6100.550895826025;6115.696722738907;6133.365267548864;6142.775043102407;6224.346443623354;5347.43851895561;6128.4790792783115;6181;6201.410865967726;6210.813675589739;6176.536759743127;6236.968815155895;6243.937560624518;6248.156498872594;6262.467690246198;6096.664683442454;6137.285055221507;6141.762184092961;6144.846378840565;6244.968775156095 09:10:15 INFO - PROCESS | 1658 | |1;ext.html;477.5534874891975;514.1413838889057;521.1219095823537;533.4292307231261;543.5841581190388;34259;41961.95095122624;42035;42083.36874946875;42090.947726306855;42800.35799463003;43380.6985790426;43413;43415;43578.346324805076;9705.951470242639;9874.90125098747;9910.256730745186;9932.503374831265;9957.203423726085;9787.510624468749;9800.558974846113;9889.307748457612;9900.158486528619;9908.851367229498;9892.950535247313;9895.851562226573;9895.851562226573;9920.851187232198;9922.355046921963;9822.165115965152;9827.262955278311;9877.456739879304;9925.602975880956;9934.205263578968;8493.575321233913;8496.83006339876;8540.103289154673;9362.859557106649;9705.466199359033;39121;41097.58902410998;41412.792936035126;41520.169596608204;41612;2998.4752668282963;3088.7528997680133;3352.2792599591203;3433.2275238071484;3554.1470047188745;30924.0722778317;32279.6772032279;33166.502502462245;34035.97892063242;36025.279494409966;2727.7408646178583;3247.675232476757;3265.6081270247487;3284.6715328467203;3405.6934875861043;525964;529471;530005;534158.3292083554;538288.999999996;67545.66227168833;68787;69040.6547967267;69296;70096.64951675209;35902.281954361286;37154.814225929236;37230.8138459306;71232.64383678048;71824.64087679528;14393.712125757425;16909;16947.576310592332;16952;16980.575485612957;12631.873681263254;12746.617601471971;12764.61706148817;12767.233965962072;12786.296753678636;15776.895617306678;20590.897045514677;20842.270520531707;21030.263940762;21104.577908441745;7881.921180788233;8854.210923559058;8873.378863479495;8918.24194943437;9084.8637270441;853.1377229395413;933.869258303838;1008.1581879130861;1016.3952448293254;1073.6027669762082;28103;30912.381752364825;31574.526382104286;31929.8403507981;32607.18482037968;1007.4459047523833;1021.7088129883097;1074.7689246811817;1076.5908954597203;1080.8811030786608;15107.244637768068;15755.842441575438;16068.75896861548;16138.193090345436;16272;696121;696477;703426;704947;707501;60270.39729602648;60371.69814150901;60753.088703669484;61025.69487152625;61113.69443152756;1366.9043166978415;1382.9446822127204;1413.371049882813;1425.3443416028742;1427.7215942891244;28105.22151898702;30231;30787.230319241302;31210.531842021934;31302.843485782425;46047.76976115098;46641.83395415006;47618;51128.74435627798;56634.86730265289;655164;656431;656573;656754;656836;23351.41621459477;27649.03228386997;28074.719252808027;28076.43847123087;28348.149555513366;48763.756181218865;49309.506904930495;50126.49873501218;50566.49433505618;51015.74492127515;40546.59453405428;40685.1862962745;41359.37960930528;42106.1578768429;42174.78912605417;39244.234009470216;40246.79876600598;41075;41294.69827115384;42101.578984209766;29715;35051.474227886094;37263.88208353754;39540.80229598834;39629.81110566688;34616.48075278923;34686.47970280498;36028.819855900554;36220.456693150154;36704.449433259055;24989.87505062463;25543.23370298894;26690.733092668826;26870.731292687604;27222.725539994055 09:10:15 INFO - PROCESS | 1658 | |2;jquery.html;340.3414393149256;366.20350737146697;376.664768356163;388.83280189518496;397.75538044102865;15630.843691563081;19760.505987350305;20768.273110441136;20890.582188356242;21092.261770837984;11338.376389298584;11867.940660296686;11998.100142489304;11999.160058795866;12005.759884802299;5026.421961474432;5303.814366497173;5347.46525347466;5432.293801805759;5436.293281873351;5333.093374126388;5359.919601205985;5390.8921821563545;5422.9728851355885;5430.266913966623;5362.249285100089;5407.621466497347;5414.972925135369;5425.511703946644;5617.269754931853;5945.346011938684;5989.700514974255;6078.574499785039;6084.543659225554;6123.142760013601;5300.549453296474;5319.840404787863;5341.679499230058;5394.811181608664;5394.946050539484;13278.269695166762;13444.395002224923;15667.451639192574;24957.62563561548;25756.356091097685;2570.768630823235;2764;2888.5378339465583;2969.346743716379;2998.7750918680968;9492.762680933032;10015.59937602495;10115.747106322327;10231.181505479544;16087.839121608751;1774.290283886443;1873.3443294846832;1894.336982056283;1924.846012319025;1945.727598136248;124121;129117;130297;130579;135112;10207.846882296772;11295.887041129483;11501.194916355775;11598.362090084958;11789.882101178879;14354.641133971525;14368.85631143696;14386;14394.992350535571;14436.783448248285;8132.430729848854;8336.208060234268;8382.748517544484;8461.280791132822;8472.703455379033;6877.2091209510545;8179.71371002024;8188.918110818935;8204.220599043081;8398.790030249293;3799.981000094982;4436.068425630589;4513.300438432048;4526.321051842184;4528.275475923836;1629.2831154292073;1733.3153404405189;1930.3340347580063;2008.357325655776;2041.8468614853648;892.5269607108243;958.6453012385323;974.0405700385115;1038.1746511523206;1047.889971552976;19622.215111395384;20088;21167.470813229793;21182.576348472943;21810.563788724456;918.3250311021394;918.5269586163131;961.9951900240594;963.5808423335872;1006.0081039541749;6239.656818875005;10609.726832780116;11288.604898828666;11314.434278286053;11550.364729939934;487813.3655990326;549282;593673.031634839;605014;615505;93776.13996349741;130747.34626326807;133786;135822.32088839493;139537;844.6241422567022;848.6690190825583;850.6427300533667;855.5935930433119;862.7239283429242;16234.431794887123;18458.35395761142;19134.808651913303;19346.613067738846;19413.61172776565;51790.96418071693;57416.71291643682;58135;58323.70838145782;58500.12249816171;83837;84498.57750711207;84689;86633;87616;7736.961315193388;7769.72805951789;7990.6004699765945;7990.920090799018;8211.876821847796;10846;12428.13003089775;12591.433385497492;12793.680157996125;12866.292353920624;7959.761207163794;11061.83407248908;11066.889331106908;11075.72310692239;11176.385298808318;9221.308401869752;10748.946255268986;10839.512221950188;11183.217174798012;11242.100631949588;9550.808983820423;10969.683637963495;10973.725656858642;11230.3261804292;11265.493052812806;7273.709051637875;7616.4767046592015;7967.6576265934045;7977.321927635984;8195.95902020486;10695.03744662984;11056.778864422506;11058.502367393326;11157.832632510357;11224.989750922456 09:10:15 INFO - PROCESS | 1658 | |3;mootools.html;1479.1864474539004;1527.5264667952931;1704.8891822031567;1802.9819701802978;1860.795312515623;7905.762827115182;8473.44922580032;8561.101084386144;8889.199972002518;8913.509756963364;8535.95732021339;8740.169683880036;8746.475211487299;8760.036395996436;8769.298456123512;2332;2337.848039877411;2370.20598099636;2376.1802178248563;2384.201292566992;2346.2609278077434;2351.294611616508;2363.822713296501;2381.166591692911;2391.2826152154285;2361.445060410802;2364.125273648752;2367.6566897799808;2383.1420688552107;2389.713234411873;2334.7665233476687;2350.15394457995;2350.7061617297836;2350.788429041377;2359.3983534198755;2334.8015418689433;2344.4256157241584;2357.2574638988835;2366.2901129661204;2366.503034362786;8682.86975695365;8708.477491350539;8714.738557843211;8845.115488451167;8858.379913406065;5175.844724658266;5261.053010458116;5337.11937530307;5338.973305133488;5352;3559.8220088995445;3584.354816133095;3622;3622.7826330420257;3637.272545490886;1086.315621158665;1092.0771947704177;1094.2285688589525;1103.5144536403957;1113.9164562657752;11365.374904380256;11411;11455.484503197293;11482.253653512578;11522.827157592643;3847.3459511883048;4467.659702089425;4757.762111894392;4759.4764575896625;4759.881002974953;9840.16358609512;9849.359791613666;9851;9877.160441362565;9885.45629990343;5234.947650523446;5252.737363131828;5290.232916227125;5291.232771248147;5345.1714984177515;4404.867853964322;4697.436307643036;4824.059308434821;4861.51384861511;4869.6591238612955;3833.5974722654278;3912.2762288976633;3923;3996.480457540501;4012.378081397388;1084.7776205877776;1269.3399432295155;1272.1476610670873;1275.6428200103985;1282.7691015617092;851.7785375802335;865.0138841720444;910.6630546697764;941.1952780372819;964.9083337082966;5898.085796701518;5980.372060933626;6046.63720176782;6140.815775526741;6157.753689852445;419.8908283846161;423.8749568877127;438.48916012845314;443.05407954018403;443.70715327884085;3456.187795867969;3921.3333733265413;4069.755814651071;4150.481189851269;4155.8753237402325;10080.798384032278;10565;10569.735756606146;10573.682789516326;10604.045635892806;10509.737256568495;10519.211059170593;10552.261341706007;10589.205809564313;10602.939706029336;7903.288704016666;8007.719729809429;8056.516609003478;8163.306118979954;8167.714130005421;5888.911666325094;5931;5969.104634304889;6009.098635204754;6030.366811484906;9710.805783884423;9786.902130978599;9905.55425005862;9907.900920990698;9964.451955142536;9538.28462865274;9933.304668673123;9944.900550994398;9960.601575936882;10043.397396155962;6982.1272340957385;7552.018237629073;7696.114946781192;7953.5227886327;8085.312748416215;8252.546109963769;8354.122817104117;8445.746627600936;8457.196566326189;8461.788455288666;7395.667194976347;7430.034095567542;7473.140588832354;7487.962560187164;7517.135529414188;7269.781906542812;7302.598357090411;7312.817179570553;7343.935129406313;7347.66935487915;6867.793966181022;7018.824529386806;7033.261507541634;7047.6123813188715;7048.929510704827;4924.409070911371;4975.601951843916;5004;5081.034603425485;5167.61242906791;4355;5052.888364559791;5126.949465068278;5270.446603106619;5287.4976877196605 09:10:15 INFO - PROCESS | 1658 | |4;prototype.html;268.52337101644576;301.7736697476892;307.5832247304902;310.3854368350665;314.12516142543024;4912.508749125086;5583.83248502545;5701.429857014302;5719.369979555828;5751.4248575142465;5430.185472179175;5568.60784803798;5591.245181900453;5608.298962629671;5623.971880140603;4016.7589944603346;4109.4246805447265;4171.916561668765;4185.644220241283;4203.48674477188;4175.498940127189;4191.517975432834;4222.698629247067;4228.894277643053;4242.724222925515;4171.186618609372;4176.227397931377;4198.349255865346;4212.705110642256;4237.152569486099;3935.8425662973445;4171.0396113826455;4212.241796476633;4219.11398606293;4247.745135291892;3211.373782112489;3391.86432542698;3669.5963444021136;3680.0799800049986;3687.4100143976834;5432.837014889559;5443.374011988632;5458.536024437927;5469.699166545838;5495.560355171577;4582.954170458286;4602.4477062752685;4896.338994235768;4951.876203094916;4964.156093464084;4593.63250939924;5248.950209958024;5884.440978107073;5901;5920.585559010871;3654.6710796028483;3951.0122469382654;4084.8570300039355;4100.528439229497;4133.2146892090705;5321.547668448186;5603.831885043455;6022.217111775485;6100.694965251675;6127.969360153215;3663.6519530644546;3839.1745774658584;3965.325894597925;3969.960300397017;4023.376376661622;4051.5340735815757;4058;4063.2076745034433;4067.349224124126;4080.4287399764057;4517.909641807211;4698.882527936829;4764.856434455705;4783.784729687172;4812.542808433193;4685.390899183084;4842.443119041285;5882.264716910387;5899.262592175978;6040.7885724000325;1266.8733126687441;1405.311397415259;1422.9715405692036;1449.3695242569306;1453.9563813085624;806.9596520173968;890.2210565754964;896.5158814240367;897.2687259883174;925.6204955968021;4474.373587697725;4592.88517787058;5381.5694744421135;5404.567634589223;5486.890262194734;6010.664081434758;6013.639181649115;6059.333893087028;6081.270247570232;6118.663473509;5236.92144617831;5486.1222204447895;5495.862603434946;5612.831615051555;5709.3434255060365;2563.8205325627027;2863.18399256211;2963.392504536565;3201.3437245364644;3228.5157226416227;5924.940750592526;6297.866384050915;6322.936770632326;6384;6389.6805159741825;5347.465253474621;5547.944520554743;6232.3144454110015;6283.96858015707;6294.874102518015;5645.435456454322;6375.585586936927;6406;6424.081310400746;6435.517336199804;5884.852878678067;6159.876802464015;6196.752129914933;6199.628022318675;6212.689365531795;6125.621735109565;6132.724027418688;6141.7409431065635;6160.44555989963;6180.320164781961;6021.849453763691;6317.6841157941;6384.585001974857;6449.806505804833;6473.867073262254;3395.3548825723033;3439.194422927883;3444.7588668792946;3966.662833659171;3996.080901392754;5112.309838171799;5444;5927.496162826208;5982.371850955587;6109.11417844419;5398.406175320633;5732.770689172553;5890.910181616415;6044.879102417839;6075.453209211016;5797.173890224566;5954.345022047484;6079.544034197364;6106.076585875435;6134.509239260759;4733.621310295237;4842.539958703917;5372.892542149213;5860.794872179624;5919.171316015847;5549.171071960334;5714.714264286852;5852.297724273115;5997.790077347272;6017.3681763414215;5613.971930140323;5838.241028666246;5847.239858818326;5871.7651293947765;5884.911726324194 09:10:15 INFO - PROCESS | 1658 | |5;yui.html;673.4982438083626;723.3020135569178;769.6459628570857;796.7330944133722;811.5333683132199;15436.922815385922;16027.35890564376;16075.75886361704;16256.83743162568;16371.75442368364;14670.853291467081;14751.77872331916;14770.778438323434;14778.482753103644;14809.777853332209;703.9331263529956;852.9530875801828;857.2499063319611;880.1990188928077;882.7263548300048;879.406400679542;895.171965931514;899.1053901368148;899.1323372945106;900.8694088918398;946.640276694855;950.9476978766165;951.6954574536152;951.7240000399876;953.2802733935869;943.2406912435487;945.9405465878226;946.4794363100302;946.7443790176683;950.3300173377752;710.6588837358046;711.2958171410319;711.7295427737479;711.9394851437634;713.3223437734173;15239.085654860633;15243.39026438941;15246.23768811555;15250.466233681767;15294.464693735667;14812.555623331318;14871.628209294746;15012.924935375362;15020.399184032625;15152.090874547452;12006.21959572625;12299;12495.75008499825;12513.624591262276;12648.114631975764;3904.2386734586767;3907.7069219808345;4103.9179216415505;4125.5874412558505;4126.133511962492;117416;121071.3946430271;122355;126109.36945315215;127183.364083179;11405.600803971822;11439.256448330832;11505.884941150649;11532.711682208012;12052.276863388224;23921.880390598282;24129.396765080663;24139.396515087265;24159.15442959488;24201.031958721454;8372.58137093143;8391.95804020976;8417.242448179693;8456.78858028542;8494.362922780816;3847.9807600962376;4644.674872758875;4646.326282688991;4658.557437043475;4823.107725070873;3650.379435495972;3745.981270093687;3754.6808521276;3757.1170774867883;3769.5288088988877;631.8388810853295;703.3634560722554;726.8552030551901;746.8356961468468;762.9809254768675;590.2091197794889;674.9460043196533;701.1411021498624;703.4653663215993;703.9225685174627;8531.402801803813;9228;9686;9804.65683701067;9993.700188994342;607.0893659510734;632.6994677528229;648.6691787188568;674.1606699658956;684.7603338831369;4170.541240463546;4379.824807007748;4651.511516314747;4788.587366726824;4873.390826146731;171616;174568;175568;177200;179368;71287.28712712911;128368;147210;148988;151151;126930.36534817451;130440.69559304476;130912.03631945528;131174;133828;9443.858342125011;9893.554790034323;10215.284930055122;10293.794124117327;10361;59656.40343596682;66958.66520667366;67282;67963.66018169878;68352.65823671049;78522;99704;101194;101421;103479.65203479592;14068.296585170496;14421.855781442471;14444.350004250044;14616.123032618078;15034.92482537624;14307.499237526637;14694.917606932282;14837.777433338293;15532;15580.766288505909;14248.287585620885;14352;14490;14740.11559306445;14791;14375.640608984859;14412.927935360256;14489.855101448851;14655.92672036633;14877.702445950805;13781.379837907518;13881.652958676113;14112.647183820487;14320.14079155254;14713.043652162574;11603.88396116028;11658.475368608264;11669.574754474166;11696.621064448822;11815.350155741517;8330.458520196167;11293.71765705864;11665.00847427978;11920.165588408729;11970.22193557416 09:10:15 INFO - PROCESS | 1658 | __end_tp_report 09:10:15 INFO - PROCESS | 1658 | __start_cc_report 09:10:15 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,413 09:10:15 INFO - PROCESS | 1658 | __end_cc_report 09:10:15 INFO - PROCESS | 1658 | __startTimestamp1470759015022__endTimestamp 09:10:15 INFO - PROCESS | 1658 | 09:10:15 INFO - PROCESS | 1658 | ------- Summary: start ------- 09:10:15 INFO - PROCESS | 1658 | Number of tests: 6 09:10:15 INFO - PROCESS | 1658 | 09:10:15 INFO - PROCESS | 1658 | [#0] dojo.html Cycles:165 Average:5818.66 Median:6110.48 stddev:685.74 (11.2%) stddev-sans-first:660.80 09:10:15 INFO - PROCESS | 1658 | Values: 3388.0 3478.2 3509.6 3512.6 3519.8 5849.9 5985.3 6013.4 6060.1 6067.0 5851.4 5977.0 5991.9 5998.0 6110.5 4334.6 4435.4 5184.4 5325.0 5361.9 4840.2 5467.9 5488.3 5488.9 5543.5 4483.6 5026.9 5284.2 5345.7 5361.1 4082.0 5194.1 5298.6 5299.7 5328.8 4277.4 5309.8 5372.1 5372.8 5394.1 4538.4 5861.4 5954.4 5976.1 6036.5 5243.4 6241.7 6258.4 6280.4 6293.7 5588.6 6158.5 6397.6 6398.4 6415.7 6017.8 6054.0 6092.8 6145.1 6145.7 6459.5 6477.1 6485.1 6489.4 6519.6 6121.3 6300.8 6318.2 6322.4 6329.1 6217.6 6222.0 6240.3 6247.8 6249.0 6169.7 6207.2 6250.9 6274.6 6279.5 6234.9 6272.8 6275.3 6279.5 6297.4 5026.4 5067.4 5086.0 5133.2 5140.7 4554.1 4606.0 4620.1 4643.0 4664.3 5943.4 6024.0 6027.0 6041.3 6046.4 6173.4 6220.3 6243.0 6258.0 6330.2 6034.5 6044.8 6050.5 6059.8 6094.5 4850.3 5571.3 5591.2 5602.9 5621.2 6271.7 6375.1 6397.4 6421.8 6480.1 5754.4 6330.2 6331.5 6350.7 6352.0 5605.8 5616.6 5875.2 6465.6 6569.2 6201.5 6205.7 6223.1 6233.6 6317.1 6312.8 6335.7 6364.6 6370.9 6379.3 6089.9 6191.7 6224.7 6240.7 6248.8 6100.6 6115.7 6133.4 6142.8 6224.3 5347.4 6128.5 6181.0 6201.4 6210.8 6176.5 6237.0 6243.9 6248.2 6262.5 6096.7 6137.3 6141.8 6144.8 6245.0 09:10:15 INFO - PROCESS | 1658 | 09:10:15 INFO - PROCESS | 1658 | [#1] ext.html Cycles:180 Average:75800.96 Median:41386.09 stddev:169823.35 (410.3%) stddev-sans-first:170205.58 09:10:15 INFO - PROCESS | 1658 | Values: 477.6 514.1 521.1 533.4 543.6 34259.0 41962.0 42035.0 42083.4 42090.9 42800.4 43380.7 43413.0 43415.0 43578.3 9706.0 9874.9 9910.3 9932.5 9957.2 9787.5 9800.6 9889.3 9900.2 9908.9 9893.0 9895.9 9895.9 9920.9 9922.4 9822.2 9827.3 9877.5 9925.6 9934.2 8493.6 8496.8 8540.1 9362.9 9705.5 39121.0 41097.6 41412.8 41520.2 41612.0 2998.5 3088.8 3352.3 3433.2 3554.1 30924.1 32279.7 33166.5 34036.0 36025.3 2727.7 3247.7 3265.6 3284.7 3405.7 525964.0 529471.0 530005.0 534158.3 538289.0 67545.7 68787.0 69040.7 69296.0 70096.6 35902.3 37154.8 37230.8 71232.6 71824.6 14393.7 16909.0 16947.6 16952.0 16980.6 12631.9 12746.6 12764.6 12767.2 12786.3 15776.9 20590.9 20842.3 21030.3 21104.6 7881.9 8854.2 8873.4 8918.2 9084.9 853.1 933.9 1008.2 1016.4 1073.6 28103.0 30912.4 31574.5 31929.8 32607.2 1007.4 1021.7 1074.8 1076.6 1080.9 15107.2 15755.8 16068.8 16138.2 16272.0 696121.0 696477.0 703426.0 704947.0 707501.0 60270.4 60371.7 60753.1 61025.7 61113.7 1366.9 1382.9 1413.4 1425.3 1427.7 28105.2 30231.0 30787.2 31210.5 31302.8 46047.8 46641.8 47618.0 51128.7 56634.9 655164.0 656431.0 656573.0 656754.0 656836.0 23351.4 27649.0 28074.7 28076.4 28348.1 48763.8 49309.5 50126.5 50566.5 51015.7 40546.6 40685.2 41359.4 42106.2 42174.8 39244.2 40246.8 41075.0 41294.7 42101.6 29715.0 35051.5 37263.9 39540.8 39629.8 34616.5 34686.5 36028.8 36220.5 36704.4 24989.9 25543.2 26690.7 26870.7 27222.7 09:10:15 INFO - PROCESS | 1658 | 09:10:15 INFO - PROCESS | 1658 | [#2] jquery.html Cycles:180 Average:34292.86 Median:2984.06 stddev:96320.69 (3227.8%) stddev-sans-first:96557.16 09:10:15 INFO - PROCESS | 1658 | Values: 340.3 366.2 376.7 388.8 397.8 15630.8 19760.5 20768.3 20890.6 21092.3 11338.4 11867.9 11998.1 11999.2 12005.8 5026.4 5303.8 5347.5 5432.3 5436.3 5333.1 5359.9 5390.9 5423.0 5430.3 5362.2 5407.6 5415.0 5425.5 5617.3 5945.3 5989.7 6078.6 6084.5 6123.1 5300.5 5319.8 5341.7 5394.8 5394.9 13278.3 13444.4 15667.5 24957.6 25756.4 2570.8 2764.0 2888.5 2969.3 2998.8 9492.8 10015.6 10115.7 10231.2 16087.8 1774.3 1873.3 1894.3 1924.8 1945.7 124121.0 129117.0 130297.0 130579.0 135112.0 10207.8 11295.9 11501.2 11598.4 11789.9 14354.6 14368.9 14386.0 14395.0 14436.8 8132.4 8336.2 8382.7 8461.3 8472.7 6877.2 8179.7 8188.9 8204.2 8398.8 3800.0 4436.1 4513.3 4526.3 4528.3 1629.3 1733.3 1930.3 2008.4 2041.8 892.5 958.6 974.0 1038.2 1047.9 19622.2 20088.0 21167.5 21182.6 21810.6 918.3 918.5 962.0 963.6 1006.0 6239.7 10609.7 11288.6 11314.4 11550.4 487813.4 549282.0 593673.0 605014.0 615505.0 93776.1 130747.3 133786.0 135822.3 139537.0 844.6 848.7 850.6 855.6 862.7 16234.4 18458.4 19134.8 19346.6 19413.6 51791.0 57416.7 58135.0 58323.7 58500.1 83837.0 84498.6 84689.0 86633.0 87616.0 7737.0 7769.7 7990.6 7990.9 8211.9 10846.0 12428.1 12591.4 12793.7 12866.3 7959.8 11061.8 11066.9 11075.7 11176.4 9221.3 10748.9 10839.5 11183.2 11242.1 9550.8 10969.7 10973.7 11230.3 11265.5 7273.7 7616.5 7967.7 7977.3 8196.0 10695.0 11056.8 11058.5 11157.8 11225.0 09:10:15 INFO - PROCESS | 1658 | 09:10:15 INFO - PROCESS | 1658 | [#3] mootools.html Cycles:180 Average:5681.09 Median:5066.96 stddev:3175.58 (62.7%) stddev-sans-first:3168.79 09:10:15 INFO - PROCESS | 1658 | Values: 1479.2 1527.5 1704.9 1803.0 1860.8 7905.8 8473.4 8561.1 8889.2 8913.5 8536.0 8740.2 8746.5 8760.0 8769.3 2332.0 2337.8 2370.2 2376.2 2384.2 2346.3 2351.3 2363.8 2381.2 2391.3 2361.4 2364.1 2367.7 2383.1 2389.7 2334.8 2350.2 2350.7 2350.8 2359.4 2334.8 2344.4 2357.3 2366.3 2366.5 8682.9 8708.5 8714.7 8845.1 8858.4 5175.8 5261.1 5337.1 5339.0 5352.0 3559.8 3584.4 3622.0 3622.8 3637.3 1086.3 1092.1 1094.2 1103.5 1113.9 11365.4 11411.0 11455.5 11482.3 11522.8 3847.3 4467.7 4757.8 4759.5 4759.9 9840.2 9849.4 9851.0 9877.2 9885.5 5234.9 5252.7 5290.2 5291.2 5345.2 4404.9 4697.4 4824.1 4861.5 4869.7 3833.6 3912.3 3923.0 3996.5 4012.4 1084.8 1269.3 1272.1 1275.6 1282.8 851.8 865.0 910.7 941.2 964.9 5898.1 5980.4 6046.6 6140.8 6157.8 419.9 423.9 438.5 443.1 443.7 3456.2 3921.3 4069.8 4150.5 4155.9 10080.8 10565.0 10569.7 10573.7 10604.0 10509.7 10519.2 10552.3 10589.2 10602.9 7903.3 8007.7 8056.5 8163.3 8167.7 5888.9 5931.0 5969.1 6009.1 6030.4 9710.8 9786.9 9905.6 9907.9 9964.5 9538.3 9933.3 9944.9 9960.6 10043.4 6982.1 7552.0 7696.1 7953.5 8085.3 8252.5 8354.1 8445.7 8457.2 8461.8 7395.7 7430.0 7473.1 7488.0 7517.1 7269.8 7302.6 7312.8 7343.9 7347.7 6867.8 7018.8 7033.3 7047.6 7048.9 4924.4 4975.6 5004.0 5081.0 5167.6 4355.0 5052.9 5126.9 5270.4 5287.5 09:10:15 INFO - PROCESS | 1658 | 09:10:15 INFO - PROCESS | 1658 | [#4] prototype.html Cycles:180 Average:4791.80 Median:5451.27 stddev:1515.22 (27.8%) stddev-sans-first:1480.95 09:10:15 INFO - PROCESS | 1658 | Values: 268.5 301.8 307.6 310.4 314.1 4912.5 5583.8 5701.4 5719.4 5751.4 5430.2 5568.6 5591.2 5608.3 5624.0 4016.8 4109.4 4171.9 4185.6 4203.5 4175.5 4191.5 4222.7 4228.9 4242.7 4171.2 4176.2 4198.3 4212.7 4237.2 3935.8 4171.0 4212.2 4219.1 4247.7 3211.4 3391.9 3669.6 3680.1 3687.4 5432.8 5443.4 5458.5 5469.7 5495.6 4583.0 4602.4 4896.3 4951.9 4964.2 4593.6 5249.0 5884.4 5901.0 5920.6 3654.7 3951.0 4084.9 4100.5 4133.2 5321.5 5603.8 6022.2 6100.7 6128.0 3663.7 3839.2 3965.3 3970.0 4023.4 4051.5 4058.0 4063.2 4067.3 4080.4 4517.9 4698.9 4764.9 4783.8 4812.5 4685.4 4842.4 5882.3 5899.3 6040.8 1266.9 1405.3 1423.0 1449.4 1454.0 807.0 890.2 896.5 897.3 925.6 4474.4 4592.9 5381.6 5404.6 5486.9 6010.7 6013.6 6059.3 6081.3 6118.7 5236.9 5486.1 5495.9 5612.8 5709.3 2563.8 2863.2 2963.4 3201.3 3228.5 5924.9 6297.9 6322.9 6384.0 6389.7 5347.5 5547.9 6232.3 6284.0 6294.9 5645.4 6375.6 6406.0 6424.1 6435.5 5884.9 6159.9 6196.8 6199.6 6212.7 6125.6 6132.7 6141.7 6160.4 6180.3 6021.8 6317.7 6384.6 6449.8 6473.9 3395.4 3439.2 3444.8 3966.7 3996.1 5112.3 5444.0 5927.5 5982.4 6109.1 5398.4 5732.8 5890.9 6044.9 6075.5 5797.2 5954.3 6079.5 6106.1 6134.5 4733.6 4842.5 5372.9 5860.8 5919.2 5549.2 5714.7 5852.3 5997.8 6017.4 5614.0 5838.2 5847.2 5871.8 5884.9 09:10:15 INFO - PROCESS | 1658 | 09:10:15 INFO - PROCESS | 1658 | [#5] yui.html Cycles:180 Average:27165.58 Median:24134.40 stddev:44667.66 (185.1%) stddev-sans-first:44748.68 09:10:15 INFO - PROCESS | 1658 | Values: 673.5 723.3 769.6 796.7 811.5 15436.9 16027.4 16075.8 16256.8 16371.8 14670.9 14751.8 14770.8 14778.5 14809.8 703.9 853.0 857.2 880.2 882.7 879.4 895.2 899.1 899.1 900.9 946.6 950.9 951.7 951.7 953.3 943.2 945.9 946.5 946.7 950.3 710.7 711.3 711.7 711.9 713.3 15239.1 15243.4 15246.2 15250.5 15294.5 14812.6 14871.6 15012.9 15020.4 15152.1 12006.2 12299.0 12495.8 12513.6 12648.1 3904.2 3907.7 4103.9 4125.6 4126.1 117416.0 121071.4 122355.0 126109.4 127183.4 11405.6 11439.3 11505.9 11532.7 12052.3 23921.9 24129.4 24139.4 24159.2 24201.0 8372.6 8392.0 8417.2 8456.8 8494.4 3848.0 4644.7 4646.3 4658.6 4823.1 3650.4 3746.0 3754.7 3757.1 3769.5 631.8 703.4 726.9 746.8 763.0 590.2 674.9 701.1 703.5 703.9 8531.4 9228.0 9686.0 9804.7 9993.7 607.1 632.7 648.7 674.2 684.8 4170.5 4379.8 4651.5 4788.6 4873.4 171616.0 174568.0 175568.0 177200.0 179368.0 71287.3 128368.0 147210.0 148988.0 151151.0 126930.4 130440.7 130912.0 131174.0 133828.0 9443.9 9893.6 10215.3 10293.8 10361.0 59656.4 66958.7 67282.0 67963.7 68352.7 78522.0 99704.0 101194.0 101421.0 103479.7 14068.3 14421.9 14444.4 14616.1 15034.9 14307.5 14694.9 14837.8 15532.0 15580.8 14248.3 14352.0 14490.0 14740.1 14791.0 14375.6 14412.9 14489.9 14655.9 14877.7 13781.4 13881.7 14112.6 14320.1 14713.0 11603.9 11658.5 11669.6 11696.6 11815.4 8330.5 11293.7 11665.0 11920.2 11970.2 09:10:15 INFO - PROCESS | 1658 | -------- Summary: end -------- 09:10:15 INFO - PROCESS | 1658 | 09:10:15 INFO - TEST-INFO | 1658: exit 0 09:10:15 INFO - TEST-OK | dromaeo_css | took 1115028ms 09:10:15 INFO - TEST-START | kraken 09:10:15 INFO - operating with platform_type : mac_ 09:10:15 INFO - Initialising browser for kraken test... 09:10:15 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/tmphuiijM/profile http://localhost:49271/getInfo.html) 09:10:18 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 09:10:18 INFO - PROCESS | 1669 | colorDepth:24 09:10:18 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 09:10:18 INFO - PROCESS | 1669 | __metrics 09:10:18 ERROR - PROCESS | 1669 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 09:10:19 INFO - TEST-INFO | 1669: exit 0 09:10:19 INFO - Browser initialized. 09:10:19 INFO - Running cycle 1/1 for kraken test... 09:10:19 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/T/tmphuiijM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:10:31 INFO - PROCESS | 1671 | RSS: Main: 254861312 09:10:31 INFO - PROCESS | 1671 | 09:10:34 INFO - PROCESS | 1671 | 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) 09:10:35 INFO - PROCESS | 1671 | RSS: Main: 267350016 09:10:35 INFO - PROCESS | 1671 | 09:10:37 INFO - PROCESS | 1671 | 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) 09:10:38 INFO - PROCESS | 1671 | RSS: Main: 275984384 09:10:38 INFO - PROCESS | 1671 | 09:10:42 INFO - PROCESS | 1671 | 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) 09:10:42 INFO - PROCESS | 1671 | RSS: Main: 278913024 09:10:42 INFO - PROCESS | 1671 | 09:10:44 INFO - PROCESS | 1671 | 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) 09:10:45 INFO - PROCESS | 1671 | RSS: Main: 280244224 09:10:45 INFO - PROCESS | 1671 | 09:10:47 INFO - PROCESS | 1671 | 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) 09:10:48 INFO - PROCESS | 1671 | RSS: Main: 282075136 09:10:48 INFO - PROCESS | 1671 | 09:10:50 INFO - PROCESS | 1671 | 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) 09:10:51 INFO - PROCESS | 1671 | RSS: Main: 282087424 09:10:51 INFO - PROCESS | 1671 | 09:10:54 INFO - PROCESS | 1671 | 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) 09:10:55 INFO - PROCESS | 1671 | RSS: Main: 282263552 09:10:55 INFO - PROCESS | 1671 | 09:10:58 INFO - PROCESS | 1671 | 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) 09:10:59 INFO - PROCESS | 1671 | RSS: Main: 282411008 09:10:59 INFO - PROCESS | 1671 | 09:11:00 INFO - PROCESS | 1671 | 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) 09:11:01 INFO - PROCESS | 1671 | RSS: Main: 282198016 09:11:01 INFO - PROCESS | 1671 | 09:11:03 INFO - PROCESS | 1671 | 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) 09:11:03 INFO - PROCESS | 1671 | RSS: Main: 282382336 09:11:03 INFO - PROCESS | 1671 | 09:11:06 INFO - PROCESS | 1671 | 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) 09:11:06 INFO - PROCESS | 1671 | RSS: Main: 282710016 09:11:06 INFO - PROCESS | 1671 | 09:11:09 INFO - PROCESS | 1671 | 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) 09:11:10 INFO - PROCESS | 1671 | RSS: Main: 282804224 09:11:10 INFO - PROCESS | 1671 | 09:11:13 INFO - PROCESS | 1671 | 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) 09:11:13 INFO - PROCESS | 1671 | RSS: Main: 282730496 09:11:13 INFO - PROCESS | 1671 | 09:11:15 INFO - PROCESS | 1671 | 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) 09:11:15 INFO - PROCESS | 1671 | RSS: Main: 283508736 09:11:15 INFO - PROCESS | 1671 | 09:11:15 INFO - PROCESS | 1671 | __start_tp_report 09:11:15 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 09:11:15 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 09:11:15 INFO - PROCESS | 1671 | |i|pagename|runs| 09:11:15 INFO - PROCESS | 1671 | |0;ai-astar;105;121;113;169;170;135;113;119;115;115 09:11:15 INFO - PROCESS | 1671 | |1;audio-beat-detection;141;113;146;156;110;131;127;108;158;110 09:11:15 INFO - PROCESS | 1671 | |2;audio-dft;200;203;208;246;175;254;253;257;274;199 09:11:15 INFO - PROCESS | 1671 | |3;audio-fft;97;112;102;143;57;60;58;60;133;60 09:11:15 INFO - PROCESS | 1671 | |4;audio-oscillator;68;72;126;72;72;79;141;101;101;120 09:11:15 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;90;87;77;129;135;86;89;86;89;85 09:11:15 INFO - PROCESS | 1671 | |6;imaging-darkroom;119;148;152;96;126;94;95;95;97;97 09:11:15 INFO - PROCESS | 1671 | |7;imaging-desaturate;125;91;90;94;92;118;81;137;144;123 09:11:15 INFO - PROCESS | 1671 | |8;json-parse-financial;56;55;56;54;54;56;54;57;59;58 09:11:15 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;65;72;74;70;72;96;71;70;70;72 09:11:15 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;89;87;85;84;84;98;107;75;104;104 09:11:15 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;140;151;133;135;137;137;137;138;152;141 09:11:15 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;179;172;166;169;166;168;166;170;167;165 09:11:15 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;68;69;68;67;70;66;67;69;67;67 09:11:15 INFO - PROCESS | 1671 | __end_tp_report 09:11:15 INFO - PROCESS | 1671 | __start_cc_report 09:11:15 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1041 09:11:15 INFO - PROCESS | 1671 | __end_cc_report 09:11:15 INFO - PROCESS | 1671 | __startTimestamp1470759075651__endTimestamp 09:11:15 INFO - PROCESS | 1671 | 09:11:15 INFO - PROCESS | 1671 | ------- Summary: start ------- 09:11:15 INFO - PROCESS | 1671 | Number of tests: 14 09:11:15 INFO - PROCESS | 1671 | 09:11:15 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:127.50 Median:120.00 stddev:23.42 (19.5%) stddev-sans-first:23.39 09:11:15 INFO - PROCESS | 1671 | Values: 105.0 121.0 113.0 169.0 170.0 135.0 113.0 119.0 115.0 115.0 09:11:15 INFO - PROCESS | 1671 | 09:11:15 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:130.00 Median:136.00 stddev:19.49 (14.3%) stddev-sans-first:20.27 09:11:15 INFO - PROCESS | 1671 | Values: 141.0 113.0 146.0 156.0 110.0 131.0 127.0 108.0 158.0 110.0 09:11:15 INFO - PROCESS | 1671 | 09:11:15 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:226.90 Median:249.50 stddev:33.38 (13.4%) stddev-sans-first:33.96 09:11:15 INFO - PROCESS | 1671 | Values: 200.0 203.0 208.0 246.0 175.0 254.0 253.0 257.0 274.0 199.0 09:11:15 INFO - PROCESS | 1671 | 09:11:15 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:88.20 Median:59.00 stddev:33.53 (56.8%) stddev-sans-first:35.41 09:11:15 INFO - PROCESS | 1671 | Values: 97.0 112.0 102.0 143.0 57.0 60.0 58.0 60.0 133.0 60.0 09:11:15 INFO - PROCESS | 1671 | 09:11:15 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:95.20 Median:70.00 stddev:26.55 (37.9%) stddev-sans-first:26.28 09:11:15 INFO - PROCESS | 1671 | Values: 68.0 72.0 126.0 72.0 72.0 79.0 141.0 101.0 101.0 120.0 09:11:15 INFO - PROCESS | 1671 | 09:11:15 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:95.30 Median:86.50 stddev:19.73 (22.8%) stddev-sans-first:20.83 09:11:15 INFO - PROCESS | 1671 | Values: 90.0 87.0 77.0 129.0 135.0 86.0 89.0 86.0 89.0 85.0 09:11:15 INFO - PROCESS | 1671 | 09:11:15 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:111.90 Median:95.00 stddev:22.97 (24.2%) stddev-sans-first:24.22 09:11:15 INFO - PROCESS | 1671 | Values: 119.0 148.0 152.0 96.0 126.0 94.0 95.0 95.0 97.0 97.0 09:11:15 INFO - PROCESS | 1671 | 09:11:15 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:109.50 Median:85.50 stddev:22.42 (26.2%) stddev-sans-first:23.06 09:11:15 INFO - PROCESS | 1671 | Values: 125.0 91.0 90.0 94.0 92.0 118.0 81.0 137.0 144.0 123.0 09:11:15 INFO - PROCESS | 1671 | 09:11:15 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:55.90 Median:56.00 stddev:1.73 (3.1%) stddev-sans-first:1.83 09:11:15 INFO - PROCESS | 1671 | Values: 56.0 55.0 56.0 54.0 54.0 56.0 54.0 57.0 59.0 58.0 09:11:15 INFO - PROCESS | 1671 | 09:11:15 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:73.20 Median:72.00 stddev:8.35 (11.6%) stddev-sans-first:8.31 09:11:15 INFO - PROCESS | 1671 | Values: 65.0 72.0 74.0 70.0 72.0 96.0 71.0 70.0 70.0 72.0 09:11:15 INFO - PROCESS | 1671 | 09:11:15 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:91.70 Median:84.50 stddev:10.79 (12.8%) stddev-sans-first:11.40 09:11:15 INFO - PROCESS | 1671 | Values: 89.0 87.0 85.0 84.0 84.0 98.0 107.0 75.0 104.0 104.0 09:11:15 INFO - PROCESS | 1671 | 09:11:15 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:140.10 Median:139.00 stddev:6.42 (4.6%) stddev-sans-first:6.81 09:11:15 INFO - PROCESS | 1671 | Values: 140.0 151.0 133.0 135.0 137.0 137.0 137.0 138.0 152.0 141.0 09:11:15 INFO - PROCESS | 1671 | 09:11:15 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.80 Median:168.50 stddev:4.18 (2.5%) stddev-sans-first:2.29 09:11:15 INFO - PROCESS | 1671 | Values: 179.0 172.0 166.0 169.0 166.0 168.0 166.0 170.0 167.0 165.0 09:11:15 INFO - PROCESS | 1671 | 09:11:15 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:68.00 stddev:1.23 (1.8%) stddev-sans-first:1.30 09:11:15 INFO - PROCESS | 1671 | Values: 68.0 69.0 68.0 67.0 70.0 66.0 67.0 69.0 67.0 67.0 09:11:15 INFO - PROCESS | 1671 | -------- Summary: end -------- 09:11:15 INFO - PROCESS | 1671 | 09:11:16 INFO - TEST-INFO | 1671: exit 0 09:11:16 INFO - TEST-OK | kraken | took 60873ms 09:11:16 INFO - SUITE-END | took 1175s 09:11:16 INFO - Completed test suite (00:19:36) 09:11:16 INFO - javascript benchmark 09:11:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7480.442636554802, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3388.0, 3478.1652403423172, 3509.6139424663284, 3512.63117372676, 3519.824008799561, 5849.941500584993, 5985.311689155748, 6013.428724271198, 6060.1212824140475, 6066.969665151668, 5851.3563508014195, 5977.0, 5991.910121348184, 5997.970010149954, 6110.480609148217, 4334.631556317717, 4435.401220835178, 5184.351956005499, 5325.0, 5361.946380536184, 4840.177169881111, 5467.863303417426, 5488.341399032121, 5488.91766623501, 5543.473370029841, 4483.618892394151, 5026.919212369322, 5284.180951952415, 5345.705986170759, 5361.08861493547, 4082.0, 5194.091034069061, 5298.602604804655, 5299.682019078868, 5328.8401347959625, 4277.401163837066, 5309.814156504503, 5372.140457526777, 5372.785088596452, 5394.109971854678, 4538.364628951949, 5861.413858614147, 5954.374790646963, 5976.073708575178, 6036.486898613623, 5243.370795504526, 6241.7191226394925, 6258.374162583751, 6280.403361680633, 6293.716782744787, 5588.608797384185, 6158.507319414436, 6397.616143031387, 6398.360163983611, 6415.67921603918, 6017.849553761191, 6053.969730151321, 6092.847678808065, 6145.139680444741, 6145.692715364214, 6459.450946669492, 6477.125588045647, 6485.1245081914285, 6489.448396886318, 6519.6414197218655, 6121.331035925228, 6300.810975670737, 6318.241810982619, 6322.399372059647, 6329.113924050636, 6217.564770466024, 6221.968890155611, 6240.282367527792, 6247.843803904939, 6249.0, 6169.722362493699, 6207.224096987877, 6250.937490625127, 6274.6235225885675, 6279.529035322369, 6234.937650623527, 6272.780452684134, 6275.309715931244, 6279.4662453691035, 6297.370262973666, 5026.447090820006, 5067.391912970467, 5086.033653605803, 5133.178691409356, 5140.665856719302, 4554.066416384633, 4606.0, 4620.122176786467, 4642.9535704643195, 4664.300354946718, 5943.405659434108, 6023.969880150659, 6026.969865150734, 6041.335453100243, 6046.395360463918, 6173.351798061229, 6220.284667263322, 6243.0, 6257.968710156511, 6330.177076979963, 6034.547408944347, 6044.788432404933, 6050.485708714808, 6059.757609695563, 6094.481969032593, 4850.296706977469, 5571.303587051619, 5591.217229587861, 5602.85992850182, 5621.18492818539, 6271.686415679289, 6375.075614036032, 6397.424231819158, 6421.8073457796345, 6480.092787009728, 5754.395788442153, 6330.240371155491, 6331.461825744863, 6350.714217860115, 6351.96824015877, 5605.757696921264, 5616.578756593353, 5875.236219291465, 6465.579737317059, 6569.244536878129, 6201.503879689704, 6205.689715514297, 6223.097650840693, 6233.563650544418, 6317.115603815561, 6312.779052733133, 6335.683215839282, 6364.586301890362, 6370.936290637034, 6379.298277189599, 6089.908651370144, 6191.659458729633, 6224.657643829632, 6240.719167637558, 6248.750049997949, 6100.550895826025, 6115.696722738907, 6133.365267548864, 6142.775043102407, 6224.346443623354, 5347.43851895561, 6128.4790792783115, 6181.0, 6201.410865967726, 6210.813675589739, 6176.536759743127, 6236.968815155895, 6243.937560624518, 6248.156498872594, 6262.467690246198, 6096.664683442454, 6137.285055221507, 6141.762184092961, 6144.846378840565, 6244.968775156095], "value": 5777.0452880944085, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [477.5534874891975, 514.1413838889057, 521.1219095823537, 533.4292307231261, 543.5841581190388, 34259.0, 41961.95095122624, 42035.0, 42083.36874946875, 42090.947726306855, 42800.35799463003, 43380.6985790426, 43413.0, 43415.0, 43578.346324805076, 9705.951470242639, 9874.90125098747, 9910.256730745186, 9932.503374831265, 9957.203423726085, 9787.510624468749, 9800.558974846113, 9889.307748457612, 9900.158486528619, 9908.851367229498, 9892.950535247313, 9895.851562226573, 9895.851562226573, 9920.851187232198, 9922.355046921963, 9822.165115965152, 9827.262955278311, 9877.456739879304, 9925.602975880956, 9934.205263578968, 8493.575321233913, 8496.83006339876, 8540.103289154673, 9362.859557106649, 9705.466199359033, 39121.0, 41097.58902410998, 41412.792936035126, 41520.169596608204, 41612.0, 2998.4752668282963, 3088.7528997680133, 3352.2792599591203, 3433.2275238071484, 3554.1470047188745, 30924.0722778317, 32279.6772032279, 33166.502502462245, 34035.97892063242, 36025.279494409966, 2727.7408646178583, 3247.675232476757, 3265.6081270247487, 3284.6715328467203, 3405.6934875861043, 525964.0, 529471.0, 530005.0, 534158.3292083554, 538288.999999996, 67545.66227168833, 68787.0, 69040.6547967267, 69296.0, 70096.64951675209, 35902.281954361286, 37154.814225929236, 37230.8138459306, 71232.64383678048, 71824.64087679528, 14393.712125757425, 16909.0, 16947.576310592332, 16952.0, 16980.575485612957, 12631.873681263254, 12746.617601471971, 12764.61706148817, 12767.233965962072, 12786.296753678636, 15776.895617306678, 20590.897045514677, 20842.270520531707, 21030.263940762, 21104.577908441745, 7881.921180788233, 8854.210923559058, 8873.378863479495, 8918.24194943437, 9084.8637270441, 853.1377229395413, 933.869258303838, 1008.1581879130861, 1016.3952448293254, 1073.6027669762082, 28103.0, 30912.381752364825, 31574.526382104286, 31929.8403507981, 32607.18482037968, 1007.4459047523833, 1021.7088129883097, 1074.7689246811817, 1076.5908954597203, 1080.8811030786608, 15107.244637768068, 15755.842441575438, 16068.75896861548, 16138.193090345436, 16272.0, 696121.0, 696477.0, 703426.0, 704947.0, 707501.0, 60270.39729602648, 60371.69814150901, 60753.088703669484, 61025.69487152625, 61113.69443152756, 1366.9043166978415, 1382.9446822127204, 1413.371049882813, 1425.3443416028742, 1427.7215942891244, 28105.22151898702, 30231.0, 30787.230319241302, 31210.531842021934, 31302.843485782425, 46047.76976115098, 46641.83395415006, 47618.0, 51128.74435627798, 56634.86730265289, 655164.0, 656431.0, 656573.0, 656754.0, 656836.0, 23351.41621459477, 27649.03228386997, 28074.719252808027, 28076.43847123087, 28348.149555513366, 48763.756181218865, 49309.506904930495, 50126.49873501218, 50566.49433505618, 51015.74492127515, 40546.59453405428, 40685.1862962745, 41359.37960930528, 42106.1578768429, 42174.78912605417, 39244.234009470216, 40246.79876600598, 41075.0, 41294.69827115384, 42101.578984209766, 29715.0, 35051.474227886094, 37263.88208353754, 39540.80229598834, 39629.81110566688, 34616.48075278923, 34686.47970280498, 36028.819855900554, 36220.456693150154, 36704.449433259055, 24989.87505062463, 25543.23370298894, 26690.733092668826, 26870.731292687604, 27222.725539994055], "value": 20518.854742005773, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.3414393149256, 366.20350737146697, 376.664768356163, 388.83280189518496, 397.75538044102865, 15630.843691563081, 19760.505987350305, 20768.273110441136, 20890.582188356242, 21092.261770837984, 11338.376389298584, 11867.940660296686, 11998.100142489304, 11999.160058795866, 12005.759884802299, 5026.421961474432, 5303.814366497173, 5347.46525347466, 5432.293801805759, 5436.293281873351, 5333.093374126388, 5359.919601205985, 5390.8921821563545, 5422.9728851355885, 5430.266913966623, 5362.249285100089, 5407.621466497347, 5414.972925135369, 5425.511703946644, 5617.269754931853, 5945.346011938684, 5989.700514974255, 6078.574499785039, 6084.543659225554, 6123.142760013601, 5300.549453296474, 5319.840404787863, 5341.679499230058, 5394.811181608664, 5394.946050539484, 13278.269695166762, 13444.395002224923, 15667.451639192574, 24957.62563561548, 25756.356091097685, 2570.768630823235, 2764.0, 2888.5378339465583, 2969.346743716379, 2998.7750918680968, 9492.762680933032, 10015.59937602495, 10115.747106322327, 10231.181505479544, 16087.839121608751, 1774.290283886443, 1873.3443294846832, 1894.336982056283, 1924.846012319025, 1945.727598136248, 124121.0, 129117.0, 130297.0, 130579.0, 135112.0, 10207.846882296772, 11295.887041129483, 11501.194916355775, 11598.362090084958, 11789.882101178879, 14354.641133971525, 14368.85631143696, 14386.0, 14394.992350535571, 14436.783448248285, 8132.430729848854, 8336.208060234268, 8382.748517544484, 8461.280791132822, 8472.703455379033, 6877.2091209510545, 8179.71371002024, 8188.918110818935, 8204.220599043081, 8398.790030249293, 3799.981000094982, 4436.068425630589, 4513.300438432048, 4526.321051842184, 4528.275475923836, 1629.2831154292073, 1733.3153404405189, 1930.3340347580063, 2008.357325655776, 2041.8468614853648, 892.5269607108243, 958.6453012385323, 974.0405700385115, 1038.1746511523206, 1047.889971552976, 19622.215111395384, 20088.0, 21167.470813229793, 21182.576348472943, 21810.563788724456, 918.3250311021394, 918.5269586163131, 961.9951900240594, 963.5808423335872, 1006.0081039541749, 6239.656818875005, 10609.726832780116, 11288.604898828666, 11314.434278286053, 11550.364729939934, 487813.3655990326, 549282.0, 593673.031634839, 605014.0, 615505.0, 93776.13996349741, 130747.34626326807, 133786.0, 135822.32088839493, 139537.0, 844.6241422567022, 848.6690190825583, 850.6427300533667, 855.5935930433119, 862.7239283429242, 16234.431794887123, 18458.35395761142, 19134.808651913303, 19346.613067738846, 19413.61172776565, 51790.96418071693, 57416.71291643682, 58135.0, 58323.70838145782, 58500.12249816171, 83837.0, 84498.57750711207, 84689.0, 86633.0, 87616.0, 7736.961315193388, 7769.72805951789, 7990.6004699765945, 7990.920090799018, 8211.876821847796, 10846.0, 12428.13003089775, 12591.433385497492, 12793.680157996125, 12866.292353920624, 7959.761207163794, 11061.83407248908, 11066.889331106908, 11075.72310692239, 11176.385298808318, 9221.308401869752, 10748.946255268986, 10839.512221950188, 11183.217174798012, 11242.100631949588, 9550.808983820423, 10969.683637963495, 10973.725656858642, 11230.3261804292, 11265.493052812806, 7273.709051637875, 7616.4767046592015, 7967.6576265934045, 7977.321927635984, 8195.95902020486, 10695.03744662984, 11056.778864422506, 11058.502367393326, 11157.832632510357, 11224.989750922456], "value": 9258.75667741009, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1479.1864474539004, 1527.5264667952931, 1704.8891822031567, 1802.9819701802978, 1860.795312515623, 7905.762827115182, 8473.44922580032, 8561.101084386144, 8889.199972002518, 8913.509756963364, 8535.95732021339, 8740.169683880036, 8746.475211487299, 8760.036395996436, 8769.298456123512, 2332.0, 2337.848039877411, 2370.20598099636, 2376.1802178248563, 2384.201292566992, 2346.2609278077434, 2351.294611616508, 2363.822713296501, 2381.166591692911, 2391.2826152154285, 2361.445060410802, 2364.125273648752, 2367.6566897799808, 2383.1420688552107, 2389.713234411873, 2334.7665233476687, 2350.15394457995, 2350.7061617297836, 2350.788429041377, 2359.3983534198755, 2334.8015418689433, 2344.4256157241584, 2357.2574638988835, 2366.2901129661204, 2366.503034362786, 8682.86975695365, 8708.477491350539, 8714.738557843211, 8845.115488451167, 8858.379913406065, 5175.844724658266, 5261.053010458116, 5337.11937530307, 5338.973305133488, 5352.0, 3559.8220088995445, 3584.354816133095, 3622.0, 3622.7826330420257, 3637.272545490886, 1086.315621158665, 1092.0771947704177, 1094.2285688589525, 1103.5144536403957, 1113.9164562657752, 11365.374904380256, 11411.0, 11455.484503197293, 11482.253653512578, 11522.827157592643, 3847.3459511883048, 4467.659702089425, 4757.762111894392, 4759.4764575896625, 4759.881002974953, 9840.16358609512, 9849.359791613666, 9851.0, 9877.160441362565, 9885.45629990343, 5234.947650523446, 5252.737363131828, 5290.232916227125, 5291.232771248147, 5345.1714984177515, 4404.867853964322, 4697.436307643036, 4824.059308434821, 4861.51384861511, 4869.6591238612955, 3833.5974722654278, 3912.2762288976633, 3923.0, 3996.480457540501, 4012.378081397388, 1084.7776205877776, 1269.3399432295155, 1272.1476610670873, 1275.6428200103985, 1282.7691015617092, 851.7785375802335, 865.0138841720444, 910.6630546697764, 941.1952780372819, 964.9083337082966, 5898.085796701518, 5980.372060933626, 6046.63720176782, 6140.815775526741, 6157.753689852445, 419.8908283846161, 423.8749568877127, 438.48916012845314, 443.05407954018403, 443.70715327884085, 3456.187795867969, 3921.3333733265413, 4069.755814651071, 4150.481189851269, 4155.8753237402325, 10080.798384032278, 10565.0, 10569.735756606146, 10573.682789516326, 10604.045635892806, 10509.737256568495, 10519.211059170593, 10552.261341706007, 10589.205809564313, 10602.939706029336, 7903.288704016666, 8007.719729809429, 8056.516609003478, 8163.306118979954, 8167.714130005421, 5888.911666325094, 5931.0, 5969.104634304889, 6009.098635204754, 6030.366811484906, 9710.805783884423, 9786.902130978599, 9905.55425005862, 9907.900920990698, 9964.451955142536, 9538.28462865274, 9933.304668673123, 9944.900550994398, 9960.601575936882, 10043.397396155962, 6982.1272340957385, 7552.018237629073, 7696.114946781192, 7953.5227886327, 8085.312748416215, 8252.546109963769, 8354.122817104117, 8445.746627600936, 8457.196566326189, 8461.788455288666, 7395.667194976347, 7430.034095567542, 7473.140588832354, 7487.962560187164, 7517.135529414188, 7269.781906542812, 7302.598357090411, 7312.817179570553, 7343.935129406313, 7347.66935487915, 6867.793966181022, 7018.824529386806, 7033.261507541634, 7047.6123813188715, 7048.929510704827, 4924.409070911371, 4975.601951843916, 5004.0, 5081.034603425485, 5167.61242906791, 4355.0, 5052.888364559791, 5126.949465068278, 5270.446603106619, 5287.4976877196605], "value": 4495.194554653116, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [268.52337101644576, 301.7736697476892, 307.5832247304902, 310.3854368350665, 314.12516142543024, 4912.508749125086, 5583.83248502545, 5701.429857014302, 5719.369979555828, 5751.4248575142465, 5430.185472179175, 5568.60784803798, 5591.245181900453, 5608.298962629671, 5623.971880140603, 4016.7589944603346, 4109.4246805447265, 4171.916561668765, 4185.644220241283, 4203.48674477188, 4175.498940127189, 4191.517975432834, 4222.698629247067, 4228.894277643053, 4242.724222925515, 4171.186618609372, 4176.227397931377, 4198.349255865346, 4212.705110642256, 4237.152569486099, 3935.8425662973445, 4171.0396113826455, 4212.241796476633, 4219.11398606293, 4247.745135291892, 3211.373782112489, 3391.86432542698, 3669.5963444021136, 3680.0799800049986, 3687.4100143976834, 5432.837014889559, 5443.374011988632, 5458.536024437927, 5469.699166545838, 5495.560355171577, 4582.954170458286, 4602.4477062752685, 4896.338994235768, 4951.876203094916, 4964.156093464084, 4593.63250939924, 5248.950209958024, 5884.440978107073, 5901.0, 5920.585559010871, 3654.6710796028483, 3951.0122469382654, 4084.8570300039355, 4100.528439229497, 4133.2146892090705, 5321.547668448186, 5603.831885043455, 6022.217111775485, 6100.694965251675, 6127.969360153215, 3663.6519530644546, 3839.1745774658584, 3965.325894597925, 3969.960300397017, 4023.376376661622, 4051.5340735815757, 4058.0, 4063.2076745034433, 4067.349224124126, 4080.4287399764057, 4517.909641807211, 4698.882527936829, 4764.856434455705, 4783.784729687172, 4812.542808433193, 4685.390899183084, 4842.443119041285, 5882.264716910387, 5899.262592175978, 6040.7885724000325, 1266.8733126687441, 1405.311397415259, 1422.9715405692036, 1449.3695242569306, 1453.9563813085624, 806.9596520173968, 890.2210565754964, 896.5158814240367, 897.2687259883174, 925.6204955968021, 4474.373587697725, 4592.88517787058, 5381.5694744421135, 5404.567634589223, 5486.890262194734, 6010.664081434758, 6013.639181649115, 6059.333893087028, 6081.270247570232, 6118.663473509, 5236.92144617831, 5486.1222204447895, 5495.862603434946, 5612.831615051555, 5709.3434255060365, 2563.8205325627027, 2863.18399256211, 2963.392504536565, 3201.3437245364644, 3228.5157226416227, 5924.940750592526, 6297.866384050915, 6322.936770632326, 6384.0, 6389.6805159741825, 5347.465253474621, 5547.944520554743, 6232.3144454110015, 6283.96858015707, 6294.874102518015, 5645.435456454322, 6375.585586936927, 6406.0, 6424.081310400746, 6435.517336199804, 5884.852878678067, 6159.876802464015, 6196.752129914933, 6199.628022318675, 6212.689365531795, 6125.621735109565, 6132.724027418688, 6141.7409431065635, 6160.44555989963, 6180.320164781961, 6021.849453763691, 6317.6841157941, 6384.585001974857, 6449.806505804833, 6473.867073262254, 3395.3548825723033, 3439.194422927883, 3444.7588668792946, 3966.662833659171, 3996.080901392754, 5112.309838171799, 5444.0, 5927.496162826208, 5982.371850955587, 6109.11417844419, 5398.406175320633, 5732.770689172553, 5890.910181616415, 6044.879102417839, 6075.453209211016, 5797.173890224566, 5954.345022047484, 6079.544034197364, 6106.076585875435, 6134.509239260759, 4733.621310295237, 4842.539958703917, 5372.892542149213, 5860.794872179624, 5919.171316015847, 5549.171071960334, 5714.714264286852, 5852.297724273115, 5997.790077347272, 6017.3681763414215, 5613.971930140323, 5838.241028666246, 5847.239858818326, 5871.7651293947765, 5884.911726324194], "value": 4296.903925756246, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [673.4982438083626, 723.3020135569178, 769.6459628570857, 796.7330944133722, 811.5333683132199, 15436.922815385922, 16027.35890564376, 16075.75886361704, 16256.83743162568, 16371.75442368364, 14670.853291467081, 14751.77872331916, 14770.778438323434, 14778.482753103644, 14809.777853332209, 703.9331263529956, 852.9530875801828, 857.2499063319611, 880.1990188928077, 882.7263548300048, 879.406400679542, 895.171965931514, 899.1053901368148, 899.1323372945106, 900.8694088918398, 946.640276694855, 950.9476978766165, 951.6954574536152, 951.7240000399876, 953.2802733935869, 943.2406912435487, 945.9405465878226, 946.4794363100302, 946.7443790176683, 950.3300173377752, 710.6588837358046, 711.2958171410319, 711.7295427737479, 711.9394851437634, 713.3223437734173, 15239.085654860633, 15243.39026438941, 15246.23768811555, 15250.466233681767, 15294.464693735667, 14812.555623331318, 14871.628209294746, 15012.924935375362, 15020.399184032625, 15152.090874547452, 12006.21959572625, 12299.0, 12495.75008499825, 12513.624591262276, 12648.114631975764, 3904.2386734586767, 3907.7069219808345, 4103.9179216415505, 4125.5874412558505, 4126.133511962492, 117416.0, 121071.3946430271, 122355.0, 126109.36945315215, 127183.364083179, 11405.600803971822, 11439.256448330832, 11505.884941150649, 11532.711682208012, 12052.276863388224, 23921.880390598282, 24129.396765080663, 24139.396515087265, 24159.15442959488, 24201.031958721454, 8372.58137093143, 8391.95804020976, 8417.242448179693, 8456.78858028542, 8494.362922780816, 3847.9807600962376, 4644.674872758875, 4646.326282688991, 4658.557437043475, 4823.107725070873, 3650.379435495972, 3745.981270093687, 3754.6808521276, 3757.1170774867883, 3769.5288088988877, 631.8388810853295, 703.3634560722554, 726.8552030551901, 746.8356961468468, 762.9809254768675, 590.2091197794889, 674.9460043196533, 701.1411021498624, 703.4653663215993, 703.9225685174627, 8531.402801803813, 9228.0, 9686.0, 9804.65683701067, 9993.700188994342, 607.0893659510734, 632.6994677528229, 648.6691787188568, 674.1606699658956, 684.7603338831369, 4170.541240463546, 4379.824807007748, 4651.511516314747, 4788.587366726824, 4873.390826146731, 171616.0, 174568.0, 175568.0, 177200.0, 179368.0, 71287.28712712911, 128368.0, 147210.0, 148988.0, 151151.0, 126930.36534817451, 130440.69559304476, 130912.03631945528, 131174.0, 133828.0, 9443.858342125011, 9893.554790034323, 10215.284930055122, 10293.794124117327, 10361.0, 59656.40343596682, 66958.66520667366, 67282.0, 67963.66018169878, 68352.65823671049, 78522.0, 99704.0, 101194.0, 101421.0, 103479.65203479592, 14068.296585170496, 14421.855781442471, 14444.350004250044, 14616.123032618078, 15034.92482537624, 14307.499237526637, 14694.917606932282, 14837.777433338293, 15532.0, 15580.766288505909, 14248.287585620885, 14352.0, 14490.0, 14740.11559306445, 14791.0, 14375.640608984859, 14412.927935360256, 14489.855101448851, 14655.92672036633, 14877.702445950805, 13781.379837907518, 13881.652958676113, 14112.647183820487, 14320.14079155254, 14713.043652162574, 11603.88396116028, 11658.475368608264, 11669.574754474166, 11696.621064448822, 11815.350155741517, 8330.458520196167, 11293.71765705864, 11665.00847427978, 11920.165588408729, 11970.22193557416], "value": 8264.240696114439, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1582.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 121.0, 113.0, 169.0, 170.0, 135.0, 113.0, 119.0, 115.0, 115.0], "value": 127.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 113.0, 146.0, 156.0, 110.0, 131.0, 127.0, 108.0, 158.0, 110.0], "value": 130.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 203.0, 208.0, 246.0, 175.0, 254.0, 253.0, 257.0, 274.0, 199.0], "value": 226.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 112.0, 102.0, 143.0, 57.0, 60.0, 58.0, 60.0, 133.0, 60.0], "value": 88.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 72.0, 126.0, 72.0, 72.0, 79.0, 141.0, 101.0, 101.0, 120.0], "value": 95.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 77.0, 129.0, 135.0, 86.0, 89.0, 86.0, 89.0, 85.0], "value": 95.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 148.0, 152.0, 96.0, 126.0, 94.0, 95.0, 95.0, 97.0, 97.0], "value": 111.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 91.0, 90.0, 94.0, 92.0, 118.0, 81.0, 137.0, 144.0, 123.0], "value": 109.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 56.0, 54.0, 54.0, 56.0, 54.0, 57.0, 59.0, 58.0], "value": 55.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 74.0, 70.0, 72.0, 96.0, 71.0, 70.0, 70.0, 72.0], "value": 73.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 85.0, 84.0, 84.0, 98.0, 107.0, 75.0, 104.0, 104.0], "value": 91.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 151.0, 133.0, 135.0, 137.0, 137.0, 137.0, 138.0, 152.0, 141.0], "value": 140.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 172.0, 166.0, 169.0, 166.0, 168.0, 166.0, 170.0, 167.0, 165.0], "value": 168.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 68.0, 67.0, 70.0, 66.0, 67.0, 69.0, 67.0, 67.0], "value": 67.8, "unit": "score"}]}]} 09:11:16 INFO - Return code: 0 09:11:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:11:16 ERROR - # TBPL SUCCESS # 09:11:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:11:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:11:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:11:16 INFO - 2016-08-09 09:11:16.974 screenresolution[1676:10384] starting screenresolution argv=screenresolution get 09:11:16 INFO - 2016-08-09 09:11:16.977 screenresolution[1676:10384] Display 0: 1600x1200x32@60 09:11:16 INFO - 2016-08-09 09:11:16.983 screenresolution[1677:10387] starting screenresolution argv=screenresolution list 09:11:16 INFO - Available Modes on Display 0 09:11:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:11:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:11:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:11:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:11:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:11:17 INFO - Intel Iris: 09:11:17 INFO - Chipset Model: Intel Iris 09:11:17 INFO - Type: GPU 09:11:17 INFO - Bus: Built-In 09:11:17 INFO - VRAM (Dynamic, Max): 1536 MB 09:11:17 INFO - Vendor: Intel (0x8086) 09:11:17 INFO - Device ID: 0x0a2e 09:11:17 INFO - Revision ID: 0x0009 09:11:17 INFO - Displays: 09:11:17 INFO - Display: 09:11:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:11:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:11:17 INFO - Display Serial Number: 1600x1200 60 09:11:17 INFO - Main Display: Yes 09:11:17 INFO - Mirror: Off 09:11:17 INFO - Online: Yes 09:11:17 INFO - Rotation: Supported 09:11:17 INFO - Return code: 0 09:11:17 INFO - Running post-action listener: _resource_record_post_action 09:11:17 INFO - [mozharness: 2016-08-09 16:11:17.217793Z] Finished run-tests step (success) 09:11:17 INFO - Running post-run listener: _resource_record_post_run 09:11:17 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 37045248; Write bytes: 702223872; Read time: 234; Write time: 2021 09:11:17 INFO - TinderboxPrint: CPU usage
54.5% 09:11:17 INFO - TinderboxPrint: I/O read bytes / time
37,045,248 / 234 09:11:17 INFO - TinderboxPrint: I/O write bytes / time
702,223,872 / 2,021 09:11:17 INFO - TinderboxPrint: CPU idle
2,206.8 (45.5%) 09:11:17 INFO - TinderboxPrint: CPU system
981.7 (20.3%) 09:11:17 INFO - TinderboxPrint: CPU user
1,657.1 (34.2%) 09:11:17 INFO - TinderboxPrint: Swap in / out
440,336,384 / 0 09:11:17 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 396956160; Write bytes: 391181824; Read time: 29482; Write time: 853 09:11:17 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 34980864; Write bytes: 295235584; Read time: 215; Write time: 1126 09:11:17 INFO - Running post-run listener: _upload_blobber_files 09:11:17 INFO - Blob upload gear active. 09:11:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:11:17 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 09:11:17 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'] 09:11:17 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 09:11:18 INFO - (blobuploader) - INFO - Open directory for files ... 09:11:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:11:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:11:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:11:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:11:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:11:18 INFO - (blobuploader) - INFO - Done attempting. 09:11:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:11:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:11:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:11:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:11:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:11:19 INFO - (blobuploader) - INFO - Done attempting. 09:11:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:11:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:11:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:11:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:11:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:11:22 INFO - (blobuploader) - INFO - Done attempting. 09:11:22 INFO - (blobuploader) - INFO - Iteration through files over. 09:11:22 INFO - Return code: 0 09:11:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:11:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:11:22 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9cd0a342360162b6fb3d7c63352bd9cee4c925e4d07592fd7e18de9cb3078fa349113d3f6a177df444f32be7a4e8bb82ccf6589e3ef5022e330375923ebb97ff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58bae17f74960ddc016af6a4ee3c5f9056b0cba6f11996c065350985c908aedd66c6751a4fe7ae4e24d7c1ec9780c9a27328e13fb5ac9bfe965420e2a3bc5a6b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be1ef6bc32200e7fbe5cfc0f72a51036994925d9ef1d4e44b22f2666ac2607800119d3a47bed769d4d4ea566844409091aa4ad08e8eaf9fa9c4e2cf962d440bb"} 09:11:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:11:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:11:22 INFO - Contents: 09:11:22 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9cd0a342360162b6fb3d7c63352bd9cee4c925e4d07592fd7e18de9cb3078fa349113d3f6a177df444f32be7a4e8bb82ccf6589e3ef5022e330375923ebb97ff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58bae17f74960ddc016af6a4ee3c5f9056b0cba6f11996c065350985c908aedd66c6751a4fe7ae4e24d7c1ec9780c9a27328e13fb5ac9bfe965420e2a3bc5a6b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be1ef6bc32200e7fbe5cfc0f72a51036994925d9ef1d4e44b22f2666ac2607800119d3a47bed769d4d4ea566844409091aa4ad08e8eaf9fa9c4e2cf962d440bb"} 09:11:22 INFO - Running post-run listener: copy_logs_to_upload_dir 09:11:22 INFO - Copying logs to upload dir... 09:11:22 INFO - mkdir: /builds/slave/test/build/upload/logs 09:11:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.466070 ========= master_lag: 1.95 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-08-09 09:11:24.377237) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 09:11:24.385095) ========= 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.jOh2RWEDtn/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.8AxWr1r4KF/Listeners TMPDIR=/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/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/9cd0a342360162b6fb3d7c63352bd9cee4c925e4d07592fd7e18de9cb3078fa349113d3f6a177df444f32be7a4e8bb82ccf6589e3ef5022e330375923ebb97ff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58bae17f74960ddc016af6a4ee3c5f9056b0cba6f11996c065350985c908aedd66c6751a4fe7ae4e24d7c1ec9780c9a27328e13fb5ac9bfe965420e2a3bc5a6b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be1ef6bc32200e7fbe5cfc0f72a51036994925d9ef1d4e44b22f2666ac2607800119d3a47bed769d4d4ea566844409091aa4ad08e8eaf9fa9c4e2cf962d440bb"} build_url:https://queue.taskcluster.net/v1/task/PW1bgKdlTYmwig-HuUkgBQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010954 build_url: 'https://queue.taskcluster.net/v1/task/PW1bgKdlTYmwig-HuUkgBQ/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/9cd0a342360162b6fb3d7c63352bd9cee4c925e4d07592fd7e18de9cb3078fa349113d3f6a177df444f32be7a4e8bb82ccf6589e3ef5022e330375923ebb97ff", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/58bae17f74960ddc016af6a4ee3c5f9056b0cba6f11996c065350985c908aedd66c6751a4fe7ae4e24d7c1ec9780c9a27328e13fb5ac9bfe965420e2a3bc5a6b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be1ef6bc32200e7fbe5cfc0f72a51036994925d9ef1d4e44b22f2666ac2607800119d3a47bed769d4d4ea566844409091aa4ad08e8eaf9fa9c4e2cf962d440bb"}' ========= master_lag: 0.45 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 09:11:24.845690) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 09:11:24.846015) ========= 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.jOh2RWEDtn/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.8AxWr1r4KF/Listeners TMPDIR=/var/folders/f4/cdjwhlw50ss_jzdmf27sh_yw00000w/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.005002 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 09:11:24.871306) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 09:11:24.871640) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 09:11:24.871998) ========= ========= Total master_lag: 2.49 =========