builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0120 starttime: 1461982034.22 results: success (0) buildid: 20160429164537 builduid: 53a89d8735f0429799062611998c5db8 revision: 1347c189b6da67d2142fb266c82f74272b09139b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-29 19:07:14.218106) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-29 19:07:14.218730) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 36 secs) (at 2016-04-29 19:07:14.219112) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 36 secs) (at 2016-04-29 19:07:51.178504) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 14 secs) (at 2016-04-29 19:07:51.178763) ========= 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.taurJMB9t8/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.gstmZkM6VM/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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-04-29 19:08:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 175M=0s 2016-04-29 19:08:01 (175 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.182138 ========= master_lag: 14.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 14 secs) (at 2016-04-29 19:08:05.449447) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 8 secs) (at 2016-04-29 19:08:05.449746) ========= 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.taurJMB9t8/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.gstmZkM6VM/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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.026116 ========= master_lag: 8.13 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 8 secs) (at 2016-04-29 19:08:13.606390) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 7 secs) (at 2016-04-29 19:08:13.606693) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1347c189b6da67d2142fb266c82f74272b09139b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1347c189b6da67d2142fb266c82f74272b09139b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.taurJMB9t8/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.gstmZkM6VM/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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-04-29 19:08:16,753 truncating revision to first 12 chars 2016-04-29 19:08:16,753 Setting DEBUG logging. 2016-04-29 19:08:16,753 attempt 1/10 2016-04-29 19:08:16,753 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/1347c189b6da?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-29 19:08:17,919 unpacking tar archive at: mozilla-aurora-1347c189b6da/testing/mozharness/ program finished with exit code 0 elapsedTime=1.416452 ========= master_lag: 5.77 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 7 secs) (at 2016-04-29 19:08:20.793870) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-29 19:08:20.794138) ========= script_repo_revision: 1347c189b6da67d2142fb266c82f74272b09139b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-29 19:08:20.794569) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 12 secs) (at 2016-04-29 19:08:20.794885) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 12 secs) (at 2016-04-29 19:08:32.859109) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-04-29 19:08:32.859390) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.taurJMB9t8/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.gstmZkM6VM/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 19:08:33 INFO - MultiFileLogger online at 20160429 19:08:33 in /builds/slave/test 19:08:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 19:08:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:08:33 INFO - {'append_to_log': False, 19:08:33 INFO - 'base_work_dir': '/builds/slave/test', 19:08:33 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 19:08:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:08:33 INFO - 'branch': 'Mozilla-Aurora', 19:08:33 INFO - 'buildbot_json_path': 'buildprops.json', 19:08:33 INFO - 'config_files': ('talos/mac_config.py',), 19:08:33 INFO - 'default_actions': ('clobber', 19:08:33 INFO - 'read-buildbot-config', 19:08:33 INFO - 'download-and-extract', 19:08:33 INFO - 'populate-webroot', 19:08:33 INFO - 'create-virtualenv', 19:08:33 INFO - 'install', 19:08:33 INFO - 'run-tests'), 19:08:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:08:33 INFO - 'download_minidump_stackwalk': True, 19:08:33 INFO - 'download_symbols': 'ondemand', 19:08:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:08:33 INFO - 'tooltool.py': '/tools/tooltool.py', 19:08:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:08:33 INFO - '/tools/misc-python/virtualenv.py')}, 19:08:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:08:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:08:33 INFO - 'installer_path': 'installer.exe', 19:08:33 INFO - 'log_level': 'info', 19:08:33 INFO - 'log_name': 'talos', 19:08:33 INFO - 'log_to_console': True, 19:08:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:08:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:08:33 INFO - 'opt_config_files': (), 19:08:33 INFO - 'pip_index': False, 19:08:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:08:33 INFO - 'cmd': ('bash', 19:08:33 INFO - '-c', 19:08:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:08:33 INFO - 'enabled': True, 19:08:33 INFO - 'halt_on_failure': False, 19:08:33 INFO - 'name': 'check_screen_resolution'},), 19:08:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:08:33 INFO - 'cmd': ('bash', 19:08:33 INFO - '-c', 19:08:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:08:33 INFO - 'enabled': True, 19:08:33 INFO - 'halt_on_failure': False, 19:08:33 INFO - 'name': 'check_screen_resolution'},), 19:08:33 INFO - 'run_cmd_checks_enabled': True, 19:08:33 INFO - 'sps_profile': False, 19:08:33 INFO - 'sps_profile_interval': 0, 19:08:33 INFO - 'suite': 'dromaeojs', 19:08:33 INFO - 'system_bits': '32', 19:08:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:08:33 INFO - 'title': 't-yosemite-r7-0120', 19:08:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:08:33 INFO - 'use_talos_json': True, 19:08:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:08:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:08:33 INFO - 'work_dir': 'build'} 19:08:33 INFO - ##### 19:08:33 INFO - ##### Running clobber step. 19:08:33 INFO - ##### 19:08:33 INFO - Running pre-action listener: _resource_record_pre_action 19:08:33 INFO - Running main action method: clobber 19:08:33 INFO - rmtree: /builds/slave/test/build 19:08:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:08:34 INFO - Running post-action listener: _resource_record_post_action 19:08:34 INFO - ##### 19:08:34 INFO - ##### Running read-buildbot-config step. 19:08:34 INFO - ##### 19:08:34 INFO - Running pre-action listener: _resource_record_pre_action 19:08:34 INFO - Running main action method: read_buildbot_config 19:08:34 INFO - Using buildbot properties: 19:08:34 INFO - { 19:08:34 INFO - "project": "", 19:08:34 INFO - "product": "firefox", 19:08:34 INFO - "script_repo_revision": "production", 19:08:34 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 19:08:34 INFO - "repository": "", 19:08:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 19:08:34 INFO - "buildid": "20160429164537", 19:08:34 INFO - "pgo_build": "False", 19:08:34 INFO - "basedir": "/builds/slave/test", 19:08:34 INFO - "buildnumber": 213, 19:08:34 INFO - "slavename": "t-yosemite-r7-0120", 19:08:34 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 19:08:34 INFO - "platform": "yosemite_r7", 19:08:34 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 19:08:34 INFO - "branch": "mozilla-aurora", 19:08:34 INFO - "repo_path": "releases/mozilla-aurora", 19:08:34 INFO - "revision": "1347c189b6da67d2142fb266c82f74272b09139b", 19:08:34 INFO - "stage_platform": "macosx64", 19:08:34 INFO - "builduid": "53a89d8735f0429799062611998c5db8", 19:08:34 INFO - "slavebuilddir": "test" 19:08:34 INFO - } 19:08:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 19:08:34 INFO - Running post-action listener: _resource_record_post_action 19:08:34 INFO - ##### 19:08:34 INFO - ##### Running download-and-extract step. 19:08:34 INFO - ##### 19:08:34 INFO - Running pre-action listener: _resource_record_pre_action 19:08:34 INFO - Running main action method: download_and_extract 19:08:34 INFO - mkdir: /builds/slave/test/build/tests 19:08:34 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:34 INFO - https://queue.taskcluster.net/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 19:08:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 19:08:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 19:08:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 19:08:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 19:08:36 INFO - Downloaded 1199 bytes. 19:08:36 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 19:08:36 INFO - Using the following test package requirements: 19:08:36 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 19:08:36 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 19:08:36 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 19:08:36 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 19:08:36 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 19:08:36 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 19:08:36 INFO - u'jsshell-mac.zip'], 19:08:36 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 19:08:36 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 19:08:36 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 19:08:36 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 19:08:36 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 19:08:36 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 19:08:36 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 19:08:36 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 19:08:36 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 19:08:36 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 19:08:36 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 19:08:36 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 19:08:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:36 INFO - https://queue.taskcluster.net/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:08:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 19:08:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 19:08:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 19:08:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 19:08:37 INFO - Downloaded 18110518 bytes. 19:08:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:08:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:08:40 INFO - Return code: 0 19:08:40 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 19:08: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:40 INFO - https://queue.taskcluster.net/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:08:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 19:08:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 19:08:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 19:08:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 19:08:42 INFO - Downloaded 18110518 bytes. 19:08:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:08:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:08:45 INFO - Return code: 0 19:08:45 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:45 INFO - https://queue.taskcluster.net/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 19:08:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 19:08:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 19:08:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 19:08:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 19:08:46 INFO - Downloaded 10840145 bytes. 19:08:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:08:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 19:08:46 INFO - Return code: 0 19:08: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:46 INFO - https://queue.taskcluster.net/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 19:08:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 19:08:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 19:08:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 19:08:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 19:08:50 INFO - Downloaded 114074265 bytes. 19:08:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 19:08:50 INFO - mkdir: /builds/slave/test/properties 19:08:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:08:50 INFO - Writing to file /builds/slave/test/properties/build_url 19:08:50 INFO - Contents: 19:08:50 INFO - build_url:https://queue.taskcluster.net/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 19:08:51 INFO - Running post-action listener: _resource_record_post_action 19:08:51 INFO - Running post-action listener: set_extra_try_arguments 19:08:51 INFO - ##### 19:08:51 INFO - ##### Running populate-webroot step. 19:08:51 INFO - ##### 19:08:51 INFO - Running pre-action listener: _resource_record_pre_action 19:08:51 INFO - Running main action method: populate_webroot 19:08:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:08:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:08:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:08:51 INFO - '--tppagecycles', 19:08:51 INFO - '7'], 19:08:51 INFO - 'tests': ['tsvgm']}}, 19:08:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:08:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:08:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:08:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:08:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:08:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:08:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:08:51 INFO - 'talos_options': ['--disable-e10s'], 19:08:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:08:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:08:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:08:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:08:51 INFO - 'talos_options': ['--disable-e10s'], 19:08:51 INFO - 'tests': ['damp', 'tps']}, 19:08:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:08:51 INFO - 'tests': ['damp', 'tps']}, 19:08:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:08:51 INFO - 'tests': ['dromaeo_dom']}, 19:08:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:08:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:08:51 INFO - 'tests': ['a11yr', 19:08:51 INFO - 'ts_paint', 19:08:51 INFO - 'tpaint', 19:08:51 INFO - 'sessionrestore', 19:08:51 INFO - 'sessionrestore_no_auto_restore', 19:08:51 INFO - 'tabpaint']}, 19:08:51 INFO - 'other-e10s': {'tests': ['a11yr', 19:08:51 INFO - 'ts_paint', 19:08:51 INFO - 'tpaint', 19:08:51 INFO - 'sessionrestore', 19:08:51 INFO - 'sessionrestore_no_auto_restore', 19:08:51 INFO - 'tabpaint']}, 19:08:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:08:51 INFO - 'ts_paint', 19:08:51 INFO - 'tpaint', 19:08:51 INFO - 'sessionrestore', 19:08:51 INFO - 'sessionrestore_no_auto_restore', 19:08:51 INFO - 'tabpaint']}, 19:08:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:08:51 INFO - 'ts_paint', 19:08:51 INFO - 'tpaint', 19:08:51 INFO - 'sessionrestore', 19:08:51 INFO - 'sessionrestore_no_auto_restore', 19:08:51 INFO - 'tabpaint']}, 19:08:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:08:51 INFO - 'tests': ['a11yr', 19:08:51 INFO - 'ts_paint', 19:08:51 INFO - 'tpaint', 19:08:51 INFO - 'sessionrestore', 19:08:51 INFO - 'sessionrestore_no_auto_restore', 19:08:51 INFO - 'tabpaint']}, 19:08:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:08:51 INFO - 'tests': ['a11yr', 19:08:51 INFO - 'ts_paint', 19:08:51 INFO - 'tpaint', 19:08:51 INFO - 'sessionrestore', 19:08:51 INFO - 'sessionrestore_no_auto_restore', 19:08:51 INFO - 'tabpaint']}, 19:08:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:08:51 INFO - 'tests': ['tsvgx', 19:08:51 INFO - 'tsvgr_opacity', 19:08:51 INFO - 'tart', 19:08:51 INFO - 'tscrollx', 19:08:51 INFO - 'cart']}, 19:08:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:08:51 INFO - 'tsvgr_opacity', 19:08:51 INFO - 'tart', 19:08:51 INFO - 'tscrollx', 19:08:51 INFO - 'cart']}, 19:08:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:08:51 INFO - 'talos_options': ['--disable-e10s'], 19:08:51 INFO - 'tests': ['tp5o']}, 19:08:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:08:51 INFO - 'tests': ['tp5o']}, 19:08:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:08:51 INFO - 'talos_options': ['--disable-e10s', 19:08:51 INFO - '--xperf_path', 19:08:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:08:51 INFO - 'tests': ['tp5n']}, 19:08:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:08:51 INFO - 'talos_options': ['--xperf_path', 19:08:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:08:51 INFO - 'tests': ['tp5n']}}, 19:08:51 INFO - 'talos.zip': {'path': '', 19:08:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:08:51 INFO - Running post-action listener: _resource_record_post_action 19:08:51 INFO - ##### 19:08:51 INFO - ##### Running create-virtualenv step. 19:08:51 INFO - ##### 19:08:51 INFO - Running pre-action listener: _resource_record_pre_action 19:08:51 INFO - Running main action method: create_virtualenv 19:08:51 INFO - Creating virtualenv /builds/slave/test/build/venv 19:08:51 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 19:08:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:08:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:08:51 INFO - Using real prefix '/tools/python27' 19:08:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:08:52 INFO - Installing distribute.............................................................................................................................................................................................done. 19:08:54 INFO - Installing pip.................done. 19:08:54 INFO - Return code: 0 19:08:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:08:54 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:54 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:54 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 0x1070ada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070bd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac38c6ab00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f4ce00>, '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 0x106db5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106f47b20>, '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.taurJMB9t8/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.gstmZkM6VM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 19:08:54 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 19:08:54 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 19:08:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.taurJMB9t8/Render', 19:08:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:08:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:08:54 INFO - 'HOME': '/Users/cltbld', 19:08:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:08:54 INFO - 'LOGNAME': 'cltbld', 19:08:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:54 INFO - 'MOZ_NO_REMOTE': '1', 19:08:54 INFO - 'NO_EM_RESTART': '1', 19:08:54 INFO - 'PAGER': '/bin/cat', 19:08:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:08:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:54 INFO - 'PWD': '/builds/slave/test', 19:08:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:08:54 INFO - 'SHELL': '/bin/bash', 19:08:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gstmZkM6VM/Listeners', 19:08:54 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 19:08:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:08:54 INFO - 'USER': 'cltbld', 19:08:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:08:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:08:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:54 INFO - 'XPC_FLAGS': '0x0', 19:08:54 INFO - 'XPC_SERVICE_NAME': '0', 19:08:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:08:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:55 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 19:08:55 INFO - Cleaning up... 19:08:55 INFO - Return code: 0 19:08:55 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 19:08: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1070ada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070bd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac38c6ab00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f4ce00>, '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 0x106db5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106f47b20>, '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.taurJMB9t8/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.gstmZkM6VM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 19:08:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 19:08:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 19:08:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.taurJMB9t8/Render', 19:08:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:08:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:08:55 INFO - 'HOME': '/Users/cltbld', 19:08:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:08:55 INFO - 'LOGNAME': 'cltbld', 19:08:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:55 INFO - 'MOZ_NO_REMOTE': '1', 19:08:55 INFO - 'NO_EM_RESTART': '1', 19:08:55 INFO - 'PAGER': '/bin/cat', 19:08:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:08:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:55 INFO - 'PWD': '/builds/slave/test', 19:08:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:08:55 INFO - 'SHELL': '/bin/bash', 19:08:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gstmZkM6VM/Listeners', 19:08:55 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 19:08:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:08:55 INFO - 'USER': 'cltbld', 19:08:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:08:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:08:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:55 INFO - 'XPC_FLAGS': '0x0', 19:08:55 INFO - 'XPC_SERVICE_NAME': '0', 19:08:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:08:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:08:55 INFO - Downloading/unpacking psutil>=0.7.1 19:08:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:08:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:08:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:08:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:08:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:08:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:08:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:08:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:08:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:08:58 INFO - Installing collected packages: psutil 19:08:58 INFO - Running setup.py install for psutil 19:08:58 INFO - building 'psutil._psutil_osx' extension 19:08:58 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 19:08:59 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 19:08:59 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 19:08:59 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 19:08:59 INFO - building 'psutil._psutil_posix' extension 19:08:59 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 19:08:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:08:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:08:59 INFO - ^ 19:08:59 INFO - 1 warning generated. 19:08:59 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 19:08:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:08:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:08:59 INFO - Successfully installed psutil 19:08:59 INFO - Cleaning up... 19:08:59 INFO - Return code: 0 19:08:59 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 19:08: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:08:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:08:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:08:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1070ada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070bd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac38c6ab00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f4ce00>, '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 0x106db5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106f47b20>, '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.taurJMB9t8/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.gstmZkM6VM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 19:08:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 19:08:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 19:08:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.taurJMB9t8/Render', 19:08:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:08:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:08:59 INFO - 'HOME': '/Users/cltbld', 19:08:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:08:59 INFO - 'LOGNAME': 'cltbld', 19:08:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:59 INFO - 'MOZ_NO_REMOTE': '1', 19:08:59 INFO - 'NO_EM_RESTART': '1', 19:08:59 INFO - 'PAGER': '/bin/cat', 19:08:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:08:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:59 INFO - 'PWD': '/builds/slave/test', 19:08:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:08:59 INFO - 'SHELL': '/bin/bash', 19:08:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gstmZkM6VM/Listeners', 19:08:59 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 19:08:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:08:59 INFO - 'USER': 'cltbld', 19:08:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:08:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:08:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:59 INFO - 'XPC_FLAGS': '0x0', 19:08:59 INFO - 'XPC_SERVICE_NAME': '0', 19:08:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:09:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:00 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 19:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:02 INFO - Downloading mozsystemmonitor-0.0.tar.gz 19:09:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:09:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 19:09:02 INFO - Installing collected packages: mozsystemmonitor 19:09:02 INFO - Running setup.py install for mozsystemmonitor 19:09:02 INFO - Successfully installed mozsystemmonitor 19:09:02 INFO - Cleaning up... 19:09:02 INFO - Return code: 0 19:09:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:09:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1070ada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070bd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac38c6ab00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f4ce00>, '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 0x106db5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106f47b20>, '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.taurJMB9t8/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.gstmZkM6VM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 19:09:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:09:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:09:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.taurJMB9t8/Render', 19:09:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:09:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:09:02 INFO - 'HOME': '/Users/cltbld', 19:09:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:09:02 INFO - 'LOGNAME': 'cltbld', 19:09:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:02 INFO - 'MOZ_NO_REMOTE': '1', 19:09:02 INFO - 'NO_EM_RESTART': '1', 19:09:02 INFO - 'PAGER': '/bin/cat', 19:09:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:09:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:02 INFO - 'PWD': '/builds/slave/test', 19:09:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:09:02 INFO - 'SHELL': '/bin/bash', 19:09:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gstmZkM6VM/Listeners', 19:09:02 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 19:09:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:09:02 INFO - 'USER': 'cltbld', 19:09:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:09:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:09:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:02 INFO - 'XPC_FLAGS': '0x0', 19:09:02 INFO - 'XPC_SERVICE_NAME': '0', 19:09:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:09:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:03 INFO - Downloading/unpacking blobuploader==1.2.4 19:09:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:05 INFO - Downloading blobuploader-1.2.4.tar.gz 19:09:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:09:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:09:05 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 19:09:05 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 19:09:05 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 19:09:05 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 19:09:05 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 19:09:05 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 19:09:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:09:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:06 INFO - Downloading docopt-0.6.1.tar.gz 19:09:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:09:06 INFO - Installing collected packages: blobuploader, requests, docopt 19:09:06 INFO - Running setup.py install for blobuploader 19:09:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:09:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:09:06 INFO - Running setup.py install for requests 19:09:07 INFO - Running setup.py install for docopt 19:09:07 INFO - Successfully installed blobuploader requests docopt 19:09:07 INFO - Cleaning up... 19:09:07 INFO - Return code: 0 19:09:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:09:07 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:07 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:07 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 0x1070ada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070bd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac38c6ab00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f4ce00>, '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 0x106db5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106f47b20>, '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.taurJMB9t8/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.gstmZkM6VM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 19:09:07 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 19:09:07 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 19:09:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.taurJMB9t8/Render', 19:09:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:09:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:09:07 INFO - 'HOME': '/Users/cltbld', 19:09:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:09:07 INFO - 'LOGNAME': 'cltbld', 19:09:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:07 INFO - 'MOZ_NO_REMOTE': '1', 19:09:07 INFO - 'NO_EM_RESTART': '1', 19:09:07 INFO - 'PAGER': '/bin/cat', 19:09:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:09:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:07 INFO - 'PWD': '/builds/slave/test', 19:09:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:09:07 INFO - 'SHELL': '/bin/bash', 19:09:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gstmZkM6VM/Listeners', 19:09:07 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 19:09:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:09:07 INFO - 'USER': 'cltbld', 19:09:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:09:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:09:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:07 INFO - 'XPC_FLAGS': '0x0', 19:09:07 INFO - 'XPC_SERVICE_NAME': '0', 19:09:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:09:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:09:07 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-UUEuaJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:09:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:09:07 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-xfO6j0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:09:08 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-p1MvTa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:09:08 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-tnwREj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:09:08 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-yL7mxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:09:08 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-u4El4k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:09:08 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-9vHDAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:09:08 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-uugUg5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:09:08 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-dSnd9R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:09:08 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-_CoYsy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:09:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:09:08 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-FQ011G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:09:09 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-vQhRKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:09:09 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-f0rgnp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:09:09 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-O7Jxfc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:09:09 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-hHrtJX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:09:09 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-9rqU0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:09:09 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-boAc_A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:09:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:09:09 INFO - Running setup.py install for manifestparser 19:09:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:09:09 INFO - Running setup.py install for mozcrash 19:09:09 INFO - Running setup.py install for mozdebug 19:09:10 INFO - Running setup.py install for mozdevice 19:09:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:09:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:09:10 INFO - Running setup.py install for mozfile 19:09:10 INFO - Running setup.py install for mozhttpd 19:09:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:09:10 INFO - Running setup.py install for mozinfo 19:09:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:09:10 INFO - Running setup.py install for mozInstall 19:09:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:09:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:09:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:09:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:09:10 INFO - Running setup.py install for mozleak 19:09:10 INFO - Running setup.py install for mozlog 19:09:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:09:11 INFO - Running setup.py install for moznetwork 19:09:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:09:11 INFO - Running setup.py install for mozprocess 19:09:11 INFO - Running setup.py install for mozprofile 19:09:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:09:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:09:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:09:11 INFO - Running setup.py install for mozrunner 19:09:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:09:11 INFO - Running setup.py install for mozscreenshot 19:09:11 INFO - Running setup.py install for moztest 19:09:12 INFO - Running setup.py install for mozversion 19:09:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:09:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:09:12 INFO - Cleaning up... 19:09:12 INFO - Return code: 0 19:09:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:09:12 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:12 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:12 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 0x1070ada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070bd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac38c6ab00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f4ce00>, '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 0x106db5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106f47b20>, '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.taurJMB9t8/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.gstmZkM6VM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 19:09:12 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 19:09:12 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 19:09:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.taurJMB9t8/Render', 19:09:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:09:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:09:12 INFO - 'HOME': '/Users/cltbld', 19:09:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:09:12 INFO - 'LOGNAME': 'cltbld', 19:09:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:12 INFO - 'MOZ_NO_REMOTE': '1', 19:09:12 INFO - 'NO_EM_RESTART': '1', 19:09:12 INFO - 'PAGER': '/bin/cat', 19:09:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:09:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:12 INFO - 'PWD': '/builds/slave/test', 19:09:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:09:12 INFO - 'SHELL': '/bin/bash', 19:09:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gstmZkM6VM/Listeners', 19:09:12 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 19:09:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:09:12 INFO - 'USER': 'cltbld', 19:09:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:09:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:09:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:12 INFO - 'XPC_FLAGS': '0x0', 19:09:12 INFO - 'XPC_SERVICE_NAME': '0', 19:09:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:09:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:09:12 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-cNvHXq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:09:12 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)) 19:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:09:12 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-5kiX9B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:09:12 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)) 19:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:09:12 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-vfeNil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:09:12 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)) 19:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:09:12 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-Qt2_dI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:09:12 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)) 19:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:09:12 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-c5Yizz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:09:12 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)) 19:09:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:09:12 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-UmBNn5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:09:13 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)) 19:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:09:13 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-SQlSuu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:09:13 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)) 19:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:09:13 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-1T4iPw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:09:13 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)) 19:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:09:13 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-NEUX9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:09:13 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)) 19:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:09:13 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-zpR_TR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:09:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 19:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:09:13 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-qnPCXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:09:13 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)) 19:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:09:13 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-Buysin-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:09:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 19:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:09:13 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-QBoS0g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:09:13 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)) 19:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:09:13 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-wRMMG5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:09:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 19:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:09:13 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-wMYPtv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:09:14 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)) 19:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:09:14 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-oeIF3o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:09:14 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)) 19:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:09:14 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-B5zB3A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:09:14 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)) 19:09:14 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)) 19:09:14 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)) 19:09:14 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)) 19:09:14 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)) 19:09:14 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)) 19:09:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:09:14 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 19:09:14 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 19:09:14 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 19:09:14 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 19:09:14 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 19:09:14 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 19:09:16 INFO - Downloading blessings-1.6.tar.gz 19:09:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:09:16 INFO - Installing collected packages: blessings 19:09:16 INFO - Running setup.py install for blessings 19:09:16 INFO - Successfully installed blessings 19:09:16 INFO - Cleaning up... 19:09:17 INFO - Return code: 0 19:09:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:09:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:09:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:09:17 INFO - Reading from file tmpfile_stdout 19:09:17 INFO - Current package versions: 19:09:17 INFO - blessings == 1.6 19:09:17 INFO - blobuploader == 1.2.4 19:09:17 INFO - docopt == 0.6.1 19:09:17 INFO - manifestparser == 1.1 19:09:17 INFO - mozInstall == 1.12 19:09:17 INFO - mozcrash == 0.17 19:09:17 INFO - mozdebug == 0.1 19:09:17 INFO - mozdevice == 0.48 19:09:17 INFO - mozfile == 1.2 19:09:17 INFO - mozhttpd == 0.7 19:09:17 INFO - mozinfo == 0.9 19:09:17 INFO - mozleak == 0.1 19:09:17 INFO - mozlog == 3.1 19:09:17 INFO - moznetwork == 0.27 19:09:17 INFO - mozprocess == 0.22 19:09:17 INFO - mozprofile == 0.28 19:09:17 INFO - mozrunner == 6.11 19:09:17 INFO - mozscreenshot == 0.1 19:09:17 INFO - mozsystemmonitor == 0.0 19:09:17 INFO - moztest == 0.7 19:09:17 INFO - mozversion == 1.4 19:09:17 INFO - psutil == 3.1.1 19:09:17 INFO - requests == 1.2.3 19:09:17 INFO - wsgiref == 0.1.2 19:09:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:09:17 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:17 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:17 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 0x1070ada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070bd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac38c6ab00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f4ce00>, '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 0x106db5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106f47b20>, '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.taurJMB9t8/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.gstmZkM6VM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 19:09:17 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 19:09:17 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 19:09:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.taurJMB9t8/Render', 19:09:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:09:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:09:17 INFO - 'HOME': '/Users/cltbld', 19:09:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:09:17 INFO - 'LOGNAME': 'cltbld', 19:09:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:17 INFO - 'MOZ_NO_REMOTE': '1', 19:09:17 INFO - 'NO_EM_RESTART': '1', 19:09:17 INFO - 'PAGER': '/bin/cat', 19:09:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:09:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:17 INFO - 'PWD': '/builds/slave/test', 19:09:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:09:17 INFO - 'SHELL': '/bin/bash', 19:09:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gstmZkM6VM/Listeners', 19:09:17 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 19:09:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:09:17 INFO - 'USER': 'cltbld', 19:09:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:09:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:09:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:17 INFO - 'XPC_FLAGS': '0x0', 19:09:17 INFO - 'XPC_SERVICE_NAME': '0', 19:09:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:09:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:17 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)) 19:09:17 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)) 19:09:17 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)) 19:09:17 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)) 19:09:17 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)) 19:09:17 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)) 19:09:17 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)) 19:09:17 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)) 19:09:17 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)) 19:09:17 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)) 19:09:17 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)) 19:09:17 INFO - Cleaning up... 19:09:17 INFO - Return code: 0 19:09:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:09:17 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:17 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:17 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 0x1070ada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070bd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac38c6ab00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f4ce00>, '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 0x106db5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106f47b20>, '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.taurJMB9t8/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.gstmZkM6VM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 19:09:17 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 19:09:17 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 19:09:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.taurJMB9t8/Render', 19:09:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:09:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:09:17 INFO - 'HOME': '/Users/cltbld', 19:09:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:09:17 INFO - 'LOGNAME': 'cltbld', 19:09:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:17 INFO - 'MOZ_NO_REMOTE': '1', 19:09:17 INFO - 'NO_EM_RESTART': '1', 19:09:17 INFO - 'PAGER': '/bin/cat', 19:09:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:09:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:17 INFO - 'PWD': '/builds/slave/test', 19:09:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:09:17 INFO - 'SHELL': '/bin/bash', 19:09:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gstmZkM6VM/Listeners', 19:09:17 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 19:09:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:09:17 INFO - 'USER': 'cltbld', 19:09:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:09:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:09:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:17 INFO - 'XPC_FLAGS': '0x0', 19:09:17 INFO - 'XPC_SERVICE_NAME': '0', 19:09:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:09:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:17 INFO - Downloading/unpacking jsonschema 19:09:17 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 19:09:17 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 19:09:17 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 19:09:17 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 19:09:17 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 19:09:17 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 19:09:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:09:20 INFO - Downloading/unpacking functools32 (from jsonschema) 19:09:20 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 19:09:20 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 19:09:20 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 19:09:20 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 19:09:20 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 19:09:20 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 19:09:20 INFO - Downloading functools32-3.2.3-2.tar.gz 19:09:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:09:20 INFO - warning: no files found matching '*.txt' 19:09:20 INFO - no previously-included directories found matching 'build' 19:09:20 INFO - no previously-included directories found matching 'dist' 19:09:20 INFO - no previously-included directories found matching '.git*' 19:09:20 INFO - Installing collected packages: jsonschema, functools32 19:09:20 INFO - Running setup.py install for functools32 19:09:21 INFO - warning: no files found matching '*.txt' 19:09:21 INFO - no previously-included directories found matching 'build' 19:09:21 INFO - no previously-included directories found matching 'dist' 19:09:21 INFO - no previously-included directories found matching '.git*' 19:09:21 INFO - Successfully installed jsonschema functools32 19:09:21 INFO - Cleaning up... 19:09:21 INFO - Return code: 0 19:09:21 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 19:09: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:21 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1070ada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1070bd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fac38c6ab00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f4ce00>, '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 0x106db5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106f47b20>, '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.taurJMB9t8/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.gstmZkM6VM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 19:09:21 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', 'flake8'] in /builds/slave/test/build 19:09:21 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 flake8 19:09:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.taurJMB9t8/Render', 19:09:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:09:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:09:21 INFO - 'HOME': '/Users/cltbld', 19:09:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:09:21 INFO - 'LOGNAME': 'cltbld', 19:09:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:21 INFO - 'MOZ_NO_REMOTE': '1', 19:09:21 INFO - 'NO_EM_RESTART': '1', 19:09:21 INFO - 'PAGER': '/bin/cat', 19:09:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:09:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:21 INFO - 'PWD': '/builds/slave/test', 19:09:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:09:21 INFO - 'SHELL': '/bin/bash', 19:09:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gstmZkM6VM/Listeners', 19:09:21 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 19:09:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:09:21 INFO - 'USER': 'cltbld', 19:09:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:09:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:09:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:21 INFO - 'XPC_FLAGS': '0x0', 19:09:21 INFO - 'XPC_SERVICE_NAME': '0', 19:09:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:09:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:21 INFO - Downloading/unpacking flake8 19:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:23 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:09:23 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:09:23 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 19:09:23 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 19:09:23 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 19:09:23 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 19:09:23 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 19:09:23 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 19:09:24 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:09:24 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:09:24 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 19:09:24 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 19:09:24 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 19:09:24 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 19:09:24 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 19:09:24 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 19:09:24 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:09:24 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 19:09:24 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 19:09:24 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 19:09:24 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 19:09:24 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 19:09:24 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 19:09:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 19:09:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:09:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:09:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:09:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:09:25 INFO - no previously-included directories found matching 'docs/_build' 19:09:25 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:09:25 INFO - Running setup.py install for pep8 19:09:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:09:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:09:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:09:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:09:25 INFO - no previously-included directories found matching 'docs/_build' 19:09:25 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 19:09:25 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:09:25 INFO - Cleaning up... 19:09:25 INFO - Return code: 0 19:09:25 INFO - Running post-action listener: _resource_record_post_action 19:09:25 INFO - Running post-action listener: _start_resource_monitoring 19:09:25 INFO - Starting resource monitoring. 19:09:25 INFO - ##### 19:09:25 INFO - ##### Running install step. 19:09:25 INFO - ##### 19:09:25 INFO - Running pre-action listener: _resource_record_pre_action 19:09:25 INFO - Running main action method: install 19:09:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:09:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:09:25 INFO - Reading from file tmpfile_stdout 19:09:25 INFO - Detecting whether we're running mozinstall >=1.0... 19:09:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:09:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:09:25 INFO - Reading from file tmpfile_stdout 19:09:25 INFO - Output received: 19:09:25 INFO - Usage: mozinstall [options] installer 19:09:25 INFO - Options: 19:09:25 INFO - -h, --help show this help message and exit 19:09:25 INFO - -d DEST, --destination=DEST 19:09:25 INFO - Directory to install application into. [default: 19:09:25 INFO - "/builds/slave/test"] 19:09:25 INFO - --app=APP Application being installed. [default: firefox] 19:09:25 INFO - mkdir: /builds/slave/test/build/application 19:09:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:09:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 19:09:57 INFO - Reading from file tmpfile_stdout 19:09:57 INFO - Output received: 19:09:57 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 19:09:57 INFO - Running post-action listener: _resource_record_post_action 19:09:57 INFO - ##### 19:09:57 INFO - ##### Running run-tests step. 19:09:57 INFO - ##### 19:09:57 INFO - Running pre-action listener: _resource_record_pre_action 19:09:57 INFO - Running main action method: run_tests 19:09:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:09:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:09:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:09:57 INFO - 2016-04-29 19:09:57.470 screenresolution[1644:8364] starting screenresolution argv=screenresolution get 19:09:57 INFO - 2016-04-29 19:09:57.472 screenresolution[1644:8364] Display 0: 1600x1200x32@60 19:09:57 INFO - 2016-04-29 19:09:57.478 screenresolution[1645:8366] starting screenresolution argv=screenresolution list 19:09:57 INFO - Available Modes on Display 0 19:09:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:09:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:09:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:09:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:09:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:09:57 INFO - Intel Iris: 19:09:57 INFO - Chipset Model: Intel Iris 19:09:57 INFO - Type: GPU 19:09:57 INFO - Bus: Built-In 19:09:57 INFO - VRAM (Dynamic, Max): 1536 MB 19:09:57 INFO - Vendor: Intel (0x8086) 19:09:57 INFO - Device ID: 0x0a2e 19:09:57 INFO - Revision ID: 0x0009 19:09:57 INFO - Displays: 19:09:57 INFO - Display: 19:09:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:09:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:09:57 INFO - Display Serial Number: 1600x1200 60 19:09:57 INFO - Main Display: Yes 19:09:57 INFO - Mirror: Off 19:09:57 INFO - Online: Yes 19:09:57 INFO - Rotation: Supported 19:09:57 INFO - Return code: 0 19:09:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:09:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:09:57 INFO - Python 2.7.3 19:09:57 INFO - Return code: 0 19:09:57 INFO - grabbing minidump binary from tooltool 19:09:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:57 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f4ce00>, '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 0x106db5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106f47b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:09:57 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 19:09:57 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 19:09:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:09:57 INFO - Return code: 0 19:09:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:09:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:09:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:09:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:09:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:09:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 19:09:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 19:09:58 INFO - Return code: 0 19:09:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0120', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 19:09:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0120 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 19:09:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.taurJMB9t8/Render', 19:09:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:09:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:09:58 INFO - 'HOME': '/Users/cltbld', 19:09:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:09:58 INFO - 'LOGNAME': 'cltbld', 19:09:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:09:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:09:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:58 INFO - 'MOZ_NO_REMOTE': '1', 19:09:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:09:58 INFO - 'NO_EM_RESTART': '1', 19:09:58 INFO - 'PAGER': '/bin/cat', 19:09:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:09:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:09:58 INFO - 'PWD': '/builds/slave/test', 19:09:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:09:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:09:58 INFO - 'SHELL': '/bin/bash', 19:09:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gstmZkM6VM/Listeners', 19:09:58 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 19:09:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:09:58 INFO - 'USER': 'cltbld', 19:09:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:09:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:09:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:58 INFO - 'XPC_FLAGS': '0x0', 19:09:58 INFO - 'XPC_SERVICE_NAME': '0', 19:09:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:09:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0120', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 19:09:59 INFO - mozversion application_buildid: 20160429164537 19:09:59 INFO - mozversion application_changeset: 1347c189b6da67d2142fb266c82f74272b09139b 19:09:59 INFO - mozversion application_display_name: FirefoxDeveloperEdition 19:09:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:09:59 INFO - mozversion application_name: Firefox 19:09:59 INFO - mozversion application_remotingname: firefox-dev 19:09:59 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:09:59 INFO - mozversion application_vendor: Mozilla 19:09:59 INFO - mozversion application_version: 48.0a2 19:09:59 INFO - mozversion platform_buildid: 20160429164537 19:09:59 INFO - mozversion platform_changeset: 1347c189b6da67d2142fb266c82f74272b09139b 19:09:59 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:09:59 INFO - mozversion platform_version: 48.0a2 19:09:59 INFO - using testdate: 1461982199 19:09:59 INFO - actual date: 1461982199 19:09:59 INFO - starting webserver on 'localhost:49279' 19:09:59 INFO - SUITE-START | Running 2 tests 19:09:59 INFO - TEST-START | dromaeo_css 19:09:59 INFO - operating with platform_type : mac_ 19:09:59 INFO - Initialising browser for dromaeo_css test... 19:09:59 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/tmp8qtBTJ/profile http://localhost:49279/getInfo.html) 19:10:01 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 19:10:01 INFO - PROCESS | 1663 | colorDepth:24 19:10:01 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 19:10:01 INFO - PROCESS | 1663 | __metrics 19:10:01 INFO - PROCESS | 1663 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:10:01 ERROR - PROCESS | 1663 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:10:02 INFO - TEST-INFO | 1663: exit 0 19:10:02 INFO - Browser initialized. 19:10:02 INFO - Running cycle 1/1 for dromaeo_css test... 19:10:02 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/tmp8qtBTJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:10:14 INFO - PROCESS | 1666 | RSS: Main: 211468288 19:10:14 INFO - PROCESS | 1666 | 19:11:05 INFO - PROCESS | 1666 | 1461982265569 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] 19:13:16 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 19:13:17 INFO - PROCESS | 1666 | RSS: Main: 348467200 19:13:17 INFO - PROCESS | 1666 | 19:16:20 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 19:16:21 INFO - PROCESS | 1666 | RSS: Main: 355553280 19:16:21 INFO - PROCESS | 1666 | 19:19:23 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 19:19:23 INFO - PROCESS | 1666 | RSS: Main: 362991616 19:19:23 INFO - PROCESS | 1666 | 19:22:26 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 19:22:26 INFO - PROCESS | 1666 | RSS: Main: 361779200 19:22:26 INFO - PROCESS | 1666 | 19:25:30 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 19:25:30 INFO - PROCESS | 1666 | RSS: Main: 329916416 19:25:30 INFO - PROCESS | 1666 | 19:28:33 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 19:28:33 INFO - PROCESS | 1666 | RSS: Main: 371142656 19:28:33 INFO - PROCESS | 1666 | 19:28:33 INFO - PROCESS | 1666 | __start_tp_report 19:28:33 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 19:28:33 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 19:28:33 INFO - PROCESS | 1666 | |i|pagename|runs| 19:28:33 INFO - PROCESS | 1666 | |0;dojo.html;3431.9656803431967;3500.824958752062;3508.4737289406576;3513.4027215373385;3561.732870034748;5795.420457954203;5795.652260864351;5799.449052340032;5812.331581868087;5824.796132135376;5801.854953626162;5820.912686309698;5826.388229235922;5832.300123985117;5834.2998840139135;5162.354705661793;5207.843764687065;5268.604854635908;5283.894322113555;5341.545968592655;5243.895122097556;5249.42256351801;5262.7631756571045;5263.289455923459;5267.631265811395;5225.268462415265;5246.685198888079;5276.445973172819;5325.520703136717;5335.5198032177095;5295.814646487393;5301.0723123453245;5328.307320048408;5334.493223143795;5347.224652425415;5039.21892106724;5202.7918883245;5203.9219411708855;5225.399079105913;5235.188545775411;5584;5606.719664016783;5608.887822243532;5661.8584535386535;5692.5161361284345;5206.427292997767;6278.529110316699;6286.214223222098;6294.622322660655;6315.684215789192;6208.348123447018;6295.559310848243;6351.460125889305;6358.364163583605;6362.204724409449;5828.708564571797;5863.560232982458;5886.293644762571;5922.8815423691285;5931.317898441691;6277.96861015692;6307.905381419282;6316.368363163739;6316.652584107826;6322.36776322364;5962.642241465439;6088.6042407243385;6137.5302083125;6171.352008039181;6220.098085777628;6288.465480434214;6326.778562750282;6327.209098862642;6351.237851457855;6356.173697419306;6309.779157729457;6316.021016742412;6319.715612797435;6322.968385158137;6345.302016778151;6110.892920173543;6206.627602343874;6230.501559875199;6264.279607845065;6359.777407790705;4921.26181072842;4926.531979461945;4999.800007999639;5007.674501157413;5012.097822391932;4444.155610434072;4454.844080457168;4464.687471877002;4470.9105817883465;4495.213337665896;5624.831255062355;5747.712614369265;5748.166515855261;5757.165211044375;5774.740136693859;6249.937500625027;6257.468115210167;6324.430801227912;6340.144080549159;6363.140975968277;4868.707877527359;5719.19931209631;5734.885302293931;5744.798932037442;5748.597598168088;5321.308229930084;5419.674819510842;5434.836954891281;5453.372862120907;5473.261109750212;6239.157713708682;6346.555741098171;6407.487401007815;6409.070684750778;6418.133551970424;6277.027060805583;6303.338149494237;6310.305866354789;6315.715792789428;6318.715657795502;6231.345708700703;6415.615063096229;6416.871662566629;6436.742530298923;6443.872322343477;6118.13190915367;6234.812955611339;6242.469390101711;6247.3440288769025;6253.687315634291;6290.119383286252;6310.400511951358;6330.8100756977365;6342.365763423621;6353;4846.273059041172;5205.427402985744;6013.849653758516;6188.164597779422;6231.906521402273;6142.109394137736;6194.039923811816;6227.96886015567;6239.75040998355;6243.843903902257;6006.189164462741;6027.126066720389;6030.216071910623;6042.305134909366;6082.148499210026;5989.431004054608;6103.05402662588;6179.783707570214;6191.102290167991;6197.4732147767945;5939.1982082420045;6014.72933717975;6029.1559181715475;6056.788012419721;6071.787487437919 19:28:33 INFO - PROCESS | 1666 | |1;ext.html;498.35960790384365;504.5736352781898;545.0978630366744;552.552432529651;602.3735315272118;31036.034234522453;31657.683423165763;39573.60426395735;40507;40662.983425414306;44935.77532112343;45620.315695264595;45719.542804571945;45794;45904.54095459045;9557.761055973604;9813.558389872473;9829.50852457378;9835.65575204868;9862.358946668479;8501.107383724713;8736.301095912311;11381.260218085798;11604.59383921563;11646.068314534818;11535.423228838525;11707.063434925229;11709.473073711704;11710.297382157056;11710.590129345475;8503.61733721984;8747;11437;11588.53645854169;11600.013998810111;8654.00478944923;8730.650773969033;11177.776444471147;11453.656390308304;11466.541338346457;39965.80017099896;40493.797531012155;40501.39247911284;40595.59404405948;40668.79665601653;3151.8739250429953;3256.169676732418;3321;3461.463473161639;3526.0479670488826;28650;28811.71188288111;28988.985385511616;29201.415971680446;29504.26239344012;2753.29630476039;2786.4427114577165;2805.0322638689618;2823.110720123155;2915.096320140742;486425;486633;488114.55942720413;488390;489211;62712.68643656753;65540;65597.34402656008;65726.67136664382;65745;68902.65548672325;68921;69021.65489172522;69030;69159;17412.738808917875;18021.549461263574;18022.91862488255;18038.917664940145;18048.639027219382;12223;12396;13174.275414852083;13741.587752367444;13850.238236896867;18005.639887202444;18010.729839052423;18052.81947180538;18076.005819680046;18100.63798724018;5879.676617786063;6096.481799047131;6856.314368563104;7033.347163416593;7096.112985876765;927.5176908007933;980.3039841712322;1000.5197505197546;1029.7888932768765;1168.6026750904564;28005.859970700018;28401.28996775097;28465.857670711513;28527.286817829307;29074.982375616753;800.7317548621161;1001.0590045357341;1002.5137808163036;1041.3231399590175;1085.4464223246043;13326.400311985984;15906.443274485337;15981.680366392608;15982.920085399732;16302.673946521003;601092;605520;612805;615511.922440385;616039;63466;63701.681491593175;63767.68116159389;63851.80740963026;64005;1362.6184668292894;1512.3950419831936;1517.597836573331;1529.4646873594374;1607.549886031913;29337;30886.69113308838;30899.845500772353;31015.53476697806;31757.364852702354;44389.556104439835;52605.7369713149;52944.470555293956;53061;53109;436202;437600.8719738413;437928;438074.6192538034;438340;22944.082236710343;26018.829152687795;26549.469010619294;26778.866105670128;26809.8659506709;43023;44168.779156104014;45237;45938.540614593425;46484.30273545967;36376;36966.07584810401;37265;38075.80962095282;38128;36477.63522364743;37143.814280928425;37472.812635937735;37876.81061594674;37913.620863792115;34752.826235868655;36526.63473365339;38415.35054169849;38529.84410467691;38773.41839872461;32777.50833737448;33733.156671083416;33916;33931.66068339285;34126;24865.87567062153;25210.49579008373;25283.241502754947;25563.872180638977;25631.487370252864 19:28:33 INFO - PROCESS | 1666 | |2;jquery.html;343.5619585029088;406.9206504731579;407.4743580780795;408.2243736899888;413.7104027180972;13842.792358114611;20257.088431020602;20985.790142098573;20989.160433582656;21012.369628911158;16806.83193168068;18422.631547369077;20565.28021519247;20858.791412085873;20930.790692093073;5465.99999999999;6162.291336496293;6187.752489900398;6194.977828658266;6204.81385558434;5806.419358064202;6115.602485838429;6122.663253521055;6124.020156774917;6180.690965451732;6042.818715438544;6044.24446944132;6067;6067.7572897084065;6084.847878803021;5598.832035038955;5965.5525835562075;5967.16459695643;5975.910361344583;6066.363031881655;5487.862803429906;5766.798162064308;5771.451712087345;5914.37899020601;5945.31628862682;13745.03784735069;14064.945129115356;14099.22454265015;17583.824161758348;26126.69366531666;2019.4547472182576;2092.5605622819226;2110.504031552584;2222.977770222293;2342.7657234276608;9903.306768526207;10079.395236285847;10219.437930913797;10350.430726310049;10602.416867072308;1586.3495966653616;1719.7678313427652;1736.1753167245583;1781.8218178182208;1811.8641101917408;121603.3919830404;127612.06715529917;128056.99999999907;130709;135627.32186339103;11614.825777613343;11774.529018839321;11791.88208117925;11831.585894493652;11842.230255033395;15559.377624894878;15568.820365148888;15581;15610.3755849767;15624.453144139901;7187.640617969185;7840.607969601611;8264.876026859602;8603.569821509025;9036.8644470333;8886.555672216364;8887.688930887389;8895.110488951053;9014.414063085878;9028.909710902939;4565.931511027337;4662.603678687349;4667.299905014275;4697.201475749131;4719.38647975761;1780.8931464112195;1903.9428817135508;1918.529960159742;1965.628891656289;1989.6418644643813;854.2269246332079;943.5140902435252;944.4616568555857;973.9366941148803;1037.5331101004426;20326.898365508077;21272.255471058746;21443.21238239019;21640.458988525097;21779;927.6474939522938;951.46242373059;1028.5834237133972;1044.3264094658903;1074.3661239868359;10225.079742823182;10939.83590246147;11049.509864747488;11109.611163609397;11184.60853870127;596209;597728;623299;630200;641287;131212;131700;132510.33744831214;132952;134170;1010.3281317923645;1013.7668336282696;1030.6547306652205;1030.8041472120274;1036.2953191829629;18036.45890623231;18289;18477.353292634692;18829.24683012719;19058.809411905702;54054;54842.725786370815;54856.451435485134;55312.72343638256;55763.44236557582;73930.89103663557;76725;77255;77265.2273477258;80791;7974.681012759656;8124.39067069984;8171.713990010321;8265.256126948603;8359.289460395692;8649.697260595849;11944.64166075019;12826.743465130832;12959.287239201934;13225.412950446009;11164.665060048212;11344.20590558653;11359.261647992855;11596.246243994114;11673.007794337576;10218.744531386477;10411.895881041395;11157.888421115686;11202.159838012345;11444.542218311175;11301.26541774782;11444.71388215268;11693.415329233674;11798.764024719287;11924.224925379822;8233.094359620556;8307.044689860744;8329.416940814084;8487.533185674847;8689.217970382695;9861.309708320636;11284.605038823602;11324;11546.711332216762;11735.823962640396 19:28:33 INFO - PROCESS | 1666 | |3;mootools.html;1404.9719005619884;1417.0364152376392;1606.2771752711278;1776.697961346571;1972.4181366496882;7877.842443151134;8612.052674205841;8815.471071735701;8816.911830881705;8819.647214111435;8441.873371899426;8673.17604827542;8677.652893884253;8689.131086891308;8707.390482666216;2149.0651566568513;2364.716234051916;2409.0604664180983;2436.4274395517127;2445.76765207305;2433.038949614901;2465.0386349323776;2467.346153269376;2468.02513007362;2475.158446128316;2376.217886421973;2428.2836563213805;2435.914742983996;2436.378723425533;2437.6709144265565;2127.6702111172795;2352.3883790214495;2415.0943396226417;2432.404061005065;2438.0491608272787;2424.527217192648;2430.91491797788;2465.988944532733;2469.8271121021535;2475.047106863852;7495.925040749577;8746;8834.983976842617;8840.336974726857;8855.380123391365;5208.869778255536;5408.13469844823;5427.565794736412;5462.781488740446;5471;3591.3535563598543;3623.1666716655054;3663.835127419246;3702.5186725725753;3733.607971163043;927.972160835176;1036.5905467340433;1057.9100776433936;1058.6136060338;1058.9947050264777;11725.472353744017;11801.409929503576;11815.11386646005;11817.94091029548;11822.586209482713;4817.421909370828;4858.514148585114;4927.162382394929;4927.413108118267;4942.456329803718;10056.145227655585;10075.445850478294;10084.798304033879;10085.647002354883;10099.545520451451;5380.542653874386;5428.75570599324;5430.158325459481;5454.318210223722;5484.122540393518;4443.955560444419;4644.047970166175;4806.543378379049;4967.627427942919;4992.675476094042;4017.678585713136;4022.5935835392893;4119.711620186558;4161.188568229227;4165.416841642173;1110.7389763405592;1261.9558315458914;1299.0387113535915;1331.9477612686064;1358.246173373787;777.7705576854728;837.2590257622048;886.1833241854301;955.8614000969959;963.4460185393427;5318.361796584435;5782.826515204551;5831.300243970666;6009.978303688384;6045.909311360333;827.8013276813642;975.2490582251768;991.8809742830906;1009.3590569988111;1035.570238351076;3928.744631598937;4011.0774521860135;4045.4336392905016;4075.5313138988804;4098.959010409858;10303.9484802577;10753.408562529135;10775.83836242457;10901.727456813644;10906.61826836057;10578.735531611617;10700.732481687864;10712.678619641423;10730;10731.83902241467;8211.630476628567;8235.423520353517;8238.917610823935;8247.21651443112;8278.71024514139;5696.430356964271;5921.733521991435;5938.079597662369;6095.359987201457;6130.754769809336;9462.668806591737;9941.453220072675;10216.927222641812;10225.181985441295;10255.487225638539;9944.35361701487;9983.700488985342;9990.10089091956;9993.600255989679;10027.398356098658;5901.763929442946;6705.161854768154;7099.751508697171;7424.740134095281;7614.505057171266;7830.921690783247;8433.493990360597;8449.493030418194;8477.109903460294;8481.703140390056;7386.482946193715;7482.588457634664;7493.025906632103;7614.428917831074;7619.580923049286;7300.56196628204;7337;7399.963000184965;7470.738524151629;7479.513831600929;6927.3419025192525;7126.714931402686;7159.785206443815;7161.4270858330165;7170.820729481805;4840.636952228527;4948.925766113439;4984.800607975641;4987.725675087905;5089.51649593288;4967.7019378837385;4987.625928055338;5003.674761140514;5062.038212739568;5247.580193584427 19:28:33 INFO - PROCESS | 1666 | |4;prototype.html;319.0365097405834;320.8620293273892;327.3191761136834;328.49411905665266;332.03212635168484;4287.378330142127;4405.669574781896;5421.837344879655;5432.674039557629;5546.611737178399;4528.388667529883;4773.832915847946;4797.673128532659;5313.946860531393;5350.91973620396;3835.1974571951137;3909.042284640267;3982.2632812929555;4045.2920738870725;4164.813028286939;3103.5965324507897;3830.9464897153366;3998.300297447935;4113.732607380525;4179.456670632813;4063.9390409143884;4094.7747873866924;4119.155573107506;4121.464209652756;4123.567025462328;3229.3702727968057;3805.3911374180275;4030.556638769733;4068.1863627274515;4151.398047283157;3553.5913369962527;3578.6779189873037;3590.2281009583066;3615.692666123383;3615.94576081359;4101.118259574176;4455.794381781006;4506.7070640408265;5145.536901678829;5194.220866869962;4223.704340696153;4569.72581645099;4735.879784587397;4749.572538471554;4755.95244047562;5197.7920883164625;5617.8033768817895;5663.377028526858;5664.40523745005;5673.6879471629045;3525.906968839668;3791.7156213284115;4018.8392464301687;4031.286703151149;4031.677465802758;5593.4338385252195;6021.2473440819895;6023.969880150615;6029.698515074228;6063.120847477136;3817.1411432428035;3947.7103280097476;3981.8009099544906;3987.322155233617;4004.039030632627;3537.663921927413;3975;3984.960150398517;4024.4969378827645;4061.248668996245;4115.403266526397;4145.440365550672;4604.608608268335;4689.484156742756;4745.810167593258;5722.484976352065;5729.512991395694;5746.683932383759;5789.623674461147;5809.0124678804705;1462.283481094256;1474.2039298778539;1477.6084337650532;1479.3948565806006;1483.6513419346445;786.9409794265339;903.6249956268207;929.6188562689265;954.2175416158683;960.6349587156835;4538.954610453853;4846.466888642247;4971.055499455092;5193.246979187997;5335.519803217729;5891.522442943308;5899.321578018497;5911;5912.260967379078;5942.465178133989;4237.5762423758;4644.349791029259;5108.744562771921;5114.846554603368;5279.947200527946;2877.453283876057;3361.3445378151027;3390.6100798408006;3397.5413319202084;3402.247842361186;5969.641821490638;6705.631190284581;6733.104764951118;6750.848228339536;6754.696038678271;5057.418396884332;5899.76400943966;6304.842378940563;6452.935470645328;6639.074668346222;6016.669083200466;6454.676791257781;6521.804345869631;6688.498362622726;6708.6981085850275;5341.41244463101;5463.852590955944;6122.234720659918;6404.263509696445;6448.967755161382;5459.617826752089;5932.851678708067;6020.066889632114;6195.349488303663;6281.685915704105;5798.710064496842;5863.794767183299;6516.185476815398;6679.4656427484715;6692.83267918306;3526.259485508072;3960.3861401481663;3984.8007599619323;4012.7391719538136;4017.357222844331;4524.638028957741;4624.514425985358;5946.583739138218;5958.2631807712105;5973.641581505123;5424.457554244544;5743.8564035899435;5777.480026797609;5799.710014499174;5840.737166827418;4937.28409380645;5288.524032837063;5690;5720.055181238399;5745.798897038583;5592.608517403905;5603.32760068778;5612.85967850807;5631.887362252814;5644.971775141263;5105.821296254613;5383.973080134731;5540.271321506556;5570.944290557042;5613.663180209201;4747.572718455355;4749.691270067435;5125.8974820502635;5188.610854185876;5631.380548139619 19:28:33 INFO - PROCESS | 1666 | |5;yui.html;656.7569999100333;733.644182571453;741.7700512841018;743.4536711762213;747.2564798025965;12531.49874005041;14486.782698259522;14503.782443263359;15198.848011519882;15239.619009524768;13823.861761382383;13834.031617786759;13956.092853964485;13956.44174233032;13956.651083722913;733.7248531800575;758.4956004257162;885.1723638398087;912.8767616371788;929.4376901974309;919.6045700348848;925.2181906289209;928.0209379105041;929.707142250189;930.0885132570052;981.9312648114635;984.0798853072364;987.239825334492;987.501311837521;988.8220120378329;757.3827330725442;940.5673390240497;973.8441849304113;975.4196253229317;978.8776402949632;728.0644371982031;728.1953441447231;729.117767501324;729.4346881167085;730.8903664450374;14551.05418147817;14600.634984125481;14626.926865365711;14644;14648;12161.331126788024;12373.690657733629;12451.688707782287;12634.50121581616;12896.09727319088;9237;10015.549300281506;10310.587576496931;10477.84283235752;10551.997560231766;3316.8341582920757;3505.8071806050657;3531.2937412517344;3582.2119133790525;3739.139997800521;78634;79978;80706.78939815908;81253;84047.5797621008;9432.764180895396;9493.477858717699;9668.661596844076;9825.263105267133;9842.212622990144;21971.231006915;22104.889475552518;22156.22453214162;22411;22446.77553224447;7504.812379690442;7549.09410870688;7626.8093297668;7644.235576442423;7647.887464657811;4069.8575549855614;4165.812538435777;4169.624733773975;4194.979025104855;4202.85290014848;3546.5744110706883;3666.8533258669354;3675.1547144156457;3678.0988657779017;3686.6866316362875;507.54321111000394;550.9531689806412;649.7141257846532;655.6787174284566;656.3239862941178;510.4742115620916;514.7400562715804;540.753956949587;586.0154939701257;601.5127746525328;7987.9201207988335;8331.416800823885;8332.458390204618;8334.708285209988;8391.496510209407;518.5721779531901;581.0122791254884;601.8194541637491;610.3926593039954;619.1610367951452;3766.6233376662663;3892.338302488584;3949.5458022327225;4032.8790136295956;4147.605977432139;101345;102540.48729756303;103056;104633.95366046394;105833;73005.63497182586;79171.62485125456;83637.58181209055;86149.56925215333;87575.56212218897;72286;72951;75565.24434755692;75647.24352756512;77257.61371193221;7853.214678532101;7915.881261781193;7938.761837144895;8002.7199048033035;8077.636506357346;45255.77372113118;45713.542864570925;46500.53499464962;46781.766091169324;48031.55905322846;61562.4609384769;64991.35008649853;66000;66184.6690766543;67421;10090.394576325443;10273.897261027294;10312;10536;11234.438278086227;10503.632372866912;11095.503511709801;11096.470073442619;11457.369844658624;12200.962918151701;6959.705142641664;9202.907970920205;11419.181356438536;11706.824397633873;12310.938445308075;11252.606158784403;11473.139514536277;11574.131940104357;11727.765444690887;11925;11105.444727763741;11375.262719594004;11436.313821170756;11748;11800.114991375853;9119.589618467053;9547.474888881177;9751.561179746786;9885.406875587487;10014.39913605186;8021.358291336795;9985.350952188084;9993.550290236812;10008.899911000797;10013 19:28:33 INFO - PROCESS | 1666 | __end_tp_report 19:28:33 INFO - PROCESS | 1666 | __start_cc_report 19:28:33 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,1304 19:28:33 INFO - PROCESS | 1666 | __end_cc_report 19:28:33 INFO - PROCESS | 1666 | __startTimestamp1461983313602__endTimestamp 19:28:33 INFO - PROCESS | 1666 | 19:28:33 INFO - PROCESS | 1666 | ------- Summary: start ------- 19:28:33 INFO - PROCESS | 1666 | Number of tests: 6 19:28:33 INFO - PROCESS | 1666 | 19:28:33 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5782.10 Median:6013.85 stddev:644.09 (10.7%) stddev-sans-first:619.12 19:28:33 INFO - PROCESS | 1666 | Values: 3432.0 3500.8 3508.5 3513.4 3561.7 5795.4 5795.7 5799.4 5812.3 5824.8 5801.9 5820.9 5826.4 5832.3 5834.3 5162.4 5207.8 5268.6 5283.9 5341.5 5243.9 5249.4 5262.8 5263.3 5267.6 5225.3 5246.7 5276.4 5325.5 5335.5 5295.8 5301.1 5328.3 5334.5 5347.2 5039.2 5202.8 5203.9 5225.4 5235.2 5584.0 5606.7 5608.9 5661.9 5692.5 5206.4 6278.5 6286.2 6294.6 6315.7 6208.3 6295.6 6351.5 6358.4 6362.2 5828.7 5863.6 5886.3 5922.9 5931.3 6278.0 6307.9 6316.4 6316.7 6322.4 5962.6 6088.6 6137.5 6171.4 6220.1 6288.5 6326.8 6327.2 6351.2 6356.2 6309.8 6316.0 6319.7 6323.0 6345.3 6110.9 6206.6 6230.5 6264.3 6359.8 4921.3 4926.5 4999.8 5007.7 5012.1 4444.2 4454.8 4464.7 4470.9 4495.2 5624.8 5747.7 5748.2 5757.2 5774.7 6249.9 6257.5 6324.4 6340.1 6363.1 4868.7 5719.2 5734.9 5744.8 5748.6 5321.3 5419.7 5434.8 5453.4 5473.3 6239.2 6346.6 6407.5 6409.1 6418.1 6277.0 6303.3 6310.3 6315.7 6318.7 6231.3 6415.6 6416.9 6436.7 6443.9 6118.1 6234.8 6242.5 6247.3 6253.7 6290.1 6310.4 6330.8 6342.4 6353.0 4846.3 5205.4 6013.8 6188.2 6231.9 6142.1 6194.0 6228.0 6239.8 6243.8 6006.2 6027.1 6030.2 6042.3 6082.1 5989.4 6103.1 6179.8 6191.1 6197.5 5939.2 6014.7 6029.2 6056.8 6071.8 19:28:33 INFO - PROCESS | 1666 | 19:28:33 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:65873.35 Median:36502.13 stddev:137750.49 (377.4%) stddev-sans-first:138049.47 19:28:33 INFO - PROCESS | 1666 | Values: 498.4 504.6 545.1 552.6 602.4 31036.0 31657.7 39573.6 40507.0 40663.0 44935.8 45620.3 45719.5 45794.0 45904.5 9557.8 9813.6 9829.5 9835.7 9862.4 8501.1 8736.3 11381.3 11604.6 11646.1 11535.4 11707.1 11709.5 11710.3 11710.6 8503.6 8747.0 11437.0 11588.5 11600.0 8654.0 8730.7 11177.8 11453.7 11466.5 39965.8 40493.8 40501.4 40595.6 40668.8 3151.9 3256.2 3321.0 3461.5 3526.0 28650.0 28811.7 28989.0 29201.4 29504.3 2753.3 2786.4 2805.0 2823.1 2915.1 486425.0 486633.0 488114.6 488390.0 489211.0 62712.7 65540.0 65597.3 65726.7 65745.0 68902.7 68921.0 69021.7 69030.0 69159.0 17412.7 18021.5 18022.9 18038.9 18048.6 12223.0 12396.0 13174.3 13741.6 13850.2 18005.6 18010.7 18052.8 18076.0 18100.6 5879.7 6096.5 6856.3 7033.3 7096.1 927.5 980.3 1000.5 1029.8 1168.6 28005.9 28401.3 28465.9 28527.3 29075.0 800.7 1001.1 1002.5 1041.3 1085.4 13326.4 15906.4 15981.7 15982.9 16302.7 601092.0 605520.0 612805.0 615511.9 616039.0 63466.0 63701.7 63767.7 63851.8 64005.0 1362.6 1512.4 1517.6 1529.5 1607.5 29337.0 30886.7 30899.8 31015.5 31757.4 44389.6 52605.7 52944.5 53061.0 53109.0 436202.0 437600.9 437928.0 438074.6 438340.0 22944.1 26018.8 26549.5 26778.9 26809.9 43023.0 44168.8 45237.0 45938.5 46484.3 36376.0 36966.1 37265.0 38075.8 38128.0 36477.6 37143.8 37472.8 37876.8 37913.6 34752.8 36526.6 38415.4 38529.8 38773.4 32777.5 33733.2 33916.0 33931.7 34126.0 24865.9 25210.5 25283.2 25563.9 25631.5 19:28:33 INFO - PROCESS | 1666 | 19:28:33 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:35832.05 Median:20987.48 stddev:103362.94 (492.5%) stddev-sans-first:103618.55 19:28:33 INFO - PROCESS | 1666 | Values: 343.6 406.9 407.5 408.2 413.7 13842.8 20257.1 20985.8 20989.2 21012.4 16806.8 18422.6 20565.3 20858.8 20930.8 5466.0 6162.3 6187.8 6195.0 6204.8 5806.4 6115.6 6122.7 6124.0 6180.7 6042.8 6044.2 6067.0 6067.8 6084.8 5598.8 5965.6 5967.2 5975.9 6066.4 5487.9 5766.8 5771.5 5914.4 5945.3 13745.0 14064.9 14099.2 17583.8 26126.7 2019.5 2092.6 2110.5 2223.0 2342.8 9903.3 10079.4 10219.4 10350.4 10602.4 1586.3 1719.8 1736.2 1781.8 1811.9 121603.4 127612.1 128057.0 130709.0 135627.3 11614.8 11774.5 11791.9 11831.6 11842.2 15559.4 15568.8 15581.0 15610.4 15624.5 7187.6 7840.6 8264.9 8603.6 9036.9 8886.6 8887.7 8895.1 9014.4 9028.9 4565.9 4662.6 4667.3 4697.2 4719.4 1780.9 1903.9 1918.5 1965.6 1989.6 854.2 943.5 944.5 973.9 1037.5 20326.9 21272.3 21443.2 21640.5 21779.0 927.6 951.5 1028.6 1044.3 1074.4 10225.1 10939.8 11049.5 11109.6 11184.6 596209.0 597728.0 623299.0 630200.0 641287.0 131212.0 131700.0 132510.3 132952.0 134170.0 1010.3 1013.8 1030.7 1030.8 1036.3 18036.5 18289.0 18477.4 18829.2 19058.8 54054.0 54842.7 54856.5 55312.7 55763.4 73930.9 76725.0 77255.0 77265.2 80791.0 7974.7 8124.4 8171.7 8265.3 8359.3 8649.7 11944.6 12826.7 12959.3 13225.4 11164.7 11344.2 11359.3 11596.2 11673.0 10218.7 10411.9 11157.9 11202.2 11444.5 11301.3 11444.7 11693.4 11798.8 11924.2 8233.1 8307.0 8329.4 8487.5 8689.2 9861.3 11284.6 11324.0 11546.7 11735.8 19:28:33 INFO - PROCESS | 1666 | 19:28:33 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5737.34 Median:4967.66 stddev:3184.10 (64.1%) stddev-sans-first:3176.38 19:28:33 INFO - PROCESS | 1666 | Values: 1405.0 1417.0 1606.3 1776.7 1972.4 7877.8 8612.1 8815.5 8816.9 8819.6 8441.9 8673.2 8677.7 8689.1 8707.4 2149.1 2364.7 2409.1 2436.4 2445.8 2433.0 2465.0 2467.3 2468.0 2475.2 2376.2 2428.3 2435.9 2436.4 2437.7 2127.7 2352.4 2415.1 2432.4 2438.0 2424.5 2430.9 2466.0 2469.8 2475.0 7495.9 8746.0 8835.0 8840.3 8855.4 5208.9 5408.1 5427.6 5462.8 5471.0 3591.4 3623.2 3663.8 3702.5 3733.6 928.0 1036.6 1057.9 1058.6 1059.0 11725.5 11801.4 11815.1 11817.9 11822.6 4817.4 4858.5 4927.2 4927.4 4942.5 10056.1 10075.4 10084.8 10085.6 10099.5 5380.5 5428.8 5430.2 5454.3 5484.1 4444.0 4644.0 4806.5 4967.6 4992.7 4017.7 4022.6 4119.7 4161.2 4165.4 1110.7 1262.0 1299.0 1331.9 1358.2 777.8 837.3 886.2 955.9 963.4 5318.4 5782.8 5831.3 6010.0 6045.9 827.8 975.2 991.9 1009.4 1035.6 3928.7 4011.1 4045.4 4075.5 4099.0 10303.9 10753.4 10775.8 10901.7 10906.6 10578.7 10700.7 10712.7 10730.0 10731.8 8211.6 8235.4 8238.9 8247.2 8278.7 5696.4 5921.7 5938.1 6095.4 6130.8 9462.7 9941.5 10216.9 10225.2 10255.5 9944.4 9983.7 9990.1 9993.6 10027.4 5901.8 6705.2 7099.8 7424.7 7614.5 7830.9 8433.5 8449.5 8477.1 8481.7 7386.5 7482.6 7493.0 7614.4 7619.6 7300.6 7337.0 7400.0 7470.7 7479.5 6927.3 7126.7 7159.8 7161.4 7170.8 4840.6 4948.9 4984.8 4987.7 5089.5 4967.7 4987.6 5003.7 5062.0 5247.6 19:28:33 INFO - PROCESS | 1666 | 19:28:33 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:4663.92 Median:5111.80 stddev:1478.80 (28.9%) stddev-sans-first:1446.54 19:28:33 INFO - PROCESS | 1666 | Values: 319.0 320.9 327.3 328.5 332.0 4287.4 4405.7 5421.8 5432.7 5546.6 4528.4 4773.8 4797.7 5313.9 5350.9 3835.2 3909.0 3982.3 4045.3 4164.8 3103.6 3830.9 3998.3 4113.7 4179.5 4063.9 4094.8 4119.2 4121.5 4123.6 3229.4 3805.4 4030.6 4068.2 4151.4 3553.6 3578.7 3590.2 3615.7 3615.9 4101.1 4455.8 4506.7 5145.5 5194.2 4223.7 4569.7 4735.9 4749.6 4756.0 5197.8 5617.8 5663.4 5664.4 5673.7 3525.9 3791.7 4018.8 4031.3 4031.7 5593.4 6021.2 6024.0 6029.7 6063.1 3817.1 3947.7 3981.8 3987.3 4004.0 3537.7 3975.0 3985.0 4024.5 4061.2 4115.4 4145.4 4604.6 4689.5 4745.8 5722.5 5729.5 5746.7 5789.6 5809.0 1462.3 1474.2 1477.6 1479.4 1483.7 786.9 903.6 929.6 954.2 960.6 4539.0 4846.5 4971.1 5193.2 5335.5 5891.5 5899.3 5911.0 5912.3 5942.5 4237.6 4644.3 5108.7 5114.8 5279.9 2877.5 3361.3 3390.6 3397.5 3402.2 5969.6 6705.6 6733.1 6750.8 6754.7 5057.4 5899.8 6304.8 6452.9 6639.1 6016.7 6454.7 6521.8 6688.5 6708.7 5341.4 5463.9 6122.2 6404.3 6449.0 5459.6 5932.9 6020.1 6195.3 6281.7 5798.7 5863.8 6516.2 6679.5 6692.8 3526.3 3960.4 3984.8 4012.7 4017.4 4524.6 4624.5 5946.6 5958.3 5973.6 5424.5 5743.9 5777.5 5799.7 5840.7 4937.3 5288.5 5690.0 5720.1 5745.8 5592.6 5603.3 5612.9 5631.9 5645.0 5105.8 5384.0 5540.3 5570.9 5613.7 4747.6 4749.7 5125.9 5188.6 5631.4 19:28:33 INFO - PROCESS | 1666 | 19:28:33 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:18627.43 Median:565.98 stddev:26965.75 (4764.4%) stddev-sans-first:27007.63 19:28:33 INFO - PROCESS | 1666 | Values: 656.8 733.6 741.8 743.5 747.3 12531.5 14486.8 14503.8 15198.8 15239.6 13823.9 13834.0 13956.1 13956.4 13956.7 733.7 758.5 885.2 912.9 929.4 919.6 925.2 928.0 929.7 930.1 981.9 984.1 987.2 987.5 988.8 757.4 940.6 973.8 975.4 978.9 728.1 728.2 729.1 729.4 730.9 14551.1 14600.6 14626.9 14644.0 14648.0 12161.3 12373.7 12451.7 12634.5 12896.1 9237.0 10015.5 10310.6 10477.8 10552.0 3316.8 3505.8 3531.3 3582.2 3739.1 78634.0 79978.0 80706.8 81253.0 84047.6 9432.8 9493.5 9668.7 9825.3 9842.2 21971.2 22104.9 22156.2 22411.0 22446.8 7504.8 7549.1 7626.8 7644.2 7647.9 4069.9 4165.8 4169.6 4195.0 4202.9 3546.6 3666.9 3675.2 3678.1 3686.7 507.5 551.0 649.7 655.7 656.3 510.5 514.7 540.8 586.0 601.5 7987.9 8331.4 8332.5 8334.7 8391.5 518.6 581.0 601.8 610.4 619.2 3766.6 3892.3 3949.5 4032.9 4147.6 101345.0 102540.5 103056.0 104634.0 105833.0 73005.6 79171.6 83637.6 86149.6 87575.6 72286.0 72951.0 75565.2 75647.2 77257.6 7853.2 7915.9 7938.8 8002.7 8077.6 45255.8 45713.5 46500.5 46781.8 48031.6 61562.5 64991.4 66000.0 66184.7 67421.0 10090.4 10273.9 10312.0 10536.0 11234.4 10503.6 11095.5 11096.5 11457.4 12201.0 6959.7 9202.9 11419.2 11706.8 12310.9 11252.6 11473.1 11574.1 11727.8 11925.0 11105.4 11375.3 11436.3 11748.0 11800.1 9119.6 9547.5 9751.6 9885.4 10014.4 8021.4 9985.4 9993.6 10008.9 10013.0 19:28:33 INFO - PROCESS | 1666 | -------- Summary: end -------- 19:28:33 INFO - PROCESS | 1666 | 19:28:34 INFO - TEST-INFO | 1666: exit 0 19:28:34 INFO - TEST-OK | dromaeo_css | took 1115053ms 19:28:34 INFO - TEST-START | kraken 19:28:34 INFO - operating with platform_type : mac_ 19:28:34 INFO - Initialising browser for kraken test... 19:28:34 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/tmp89D0qy/profile http://localhost:49279/getInfo.html) 19:28:36 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 19:28:36 INFO - PROCESS | 1673 | colorDepth:24 19:28:36 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 19:28:36 INFO - PROCESS | 1673 | __metrics 19:28:36 INFO - PROCESS | 1673 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:28:36 ERROR - PROCESS | 1673 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:28:37 INFO - TEST-INFO | 1673: exit 0 19:28:37 INFO - Browser initialized. 19:28:37 INFO - Running cycle 1/1 for kraken test... 19:28:37 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/tmp89D0qy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:28:49 INFO - PROCESS | 1675 | RSS: Main: 211333120 19:28:49 INFO - PROCESS | 1675 | 19:28:51 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 19:28:52 INFO - PROCESS | 1675 | RSS: Main: 270446592 19:28:52 INFO - PROCESS | 1675 | 19:28:55 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 19:28:56 INFO - PROCESS | 1675 | RSS: Main: 284123136 19:28:56 INFO - PROCESS | 1675 | 19:28:59 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 19:28:59 INFO - PROCESS | 1675 | RSS: Main: 370704384 19:28:59 INFO - PROCESS | 1675 | 19:29:01 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 19:29:02 INFO - PROCESS | 1675 | RSS: Main: 277319680 19:29:02 INFO - PROCESS | 1675 | 19:29:04 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:29:05 INFO - PROCESS | 1675 | RSS: Main: 279252992 19:29:05 INFO - PROCESS | 1675 | 19:29:07 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 19:29:08 INFO - PROCESS | 1675 | RSS: Main: 317161472 19:29:08 INFO - PROCESS | 1675 | 19:29:11 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 19:29:11 INFO - PROCESS | 1675 | RSS: Main: 318451712 19:29:11 INFO - PROCESS | 1675 | 19:29:14 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 19:29:15 INFO - PROCESS | 1675 | RSS: Main: 316678144 19:29:15 INFO - PROCESS | 1675 | 19:29:17 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:29:17 INFO - PROCESS | 1675 | RSS: Main: 306982912 19:29:17 INFO - PROCESS | 1675 | 19:29:19 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:29:20 INFO - PROCESS | 1675 | RSS: Main: 313442304 19:29:20 INFO - PROCESS | 1675 | 19:29:22 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:29:22 INFO - PROCESS | 1675 | RSS: Main: 316620800 19:29:22 INFO - PROCESS | 1675 | 19:29:25 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:29:26 INFO - PROCESS | 1675 | RSS: Main: 322101248 19:29:26 INFO - PROCESS | 1675 | 19:29:29 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:29:29 INFO - PROCESS | 1675 | RSS: Main: 319766528 19:29:29 INFO - PROCESS | 1675 | 19:29:31 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 19:29:31 INFO - PROCESS | 1675 | RSS: Main: 384090112 19:29:31 INFO - PROCESS | 1675 | 19:29:31 INFO - PROCESS | 1675 | __start_tp_report 19:29:31 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 19:29:31 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 19:29:31 INFO - PROCESS | 1675 | |i|pagename|runs| 19:29:31 INFO - PROCESS | 1675 | |0;ai-astar;113;115;107;114;114;115;114;114;103;111 19:29:31 INFO - PROCESS | 1675 | |1;audio-beat-detection;170;110;165;201;147;144;116;155;182;144 19:29:31 INFO - PROCESS | 1675 | |2;audio-dft;189;187;186;188;184;183;184;186;183;183 19:29:31 INFO - PROCESS | 1675 | |3;audio-fft;71;78;72;150;56;55;59;62;146;55 19:29:31 INFO - PROCESS | 1675 | |4;audio-oscillator;89;89;142;76;81;77;125;79;77;128 19:29:31 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;88;85;88;86;88;85;88;86;88;84 19:29:31 INFO - PROCESS | 1675 | |6;imaging-darkroom;93;93;100;94;93;94;95;94;93;93 19:29:31 INFO - PROCESS | 1675 | |7;imaging-desaturate;83;86;86;86;84;83;84;82;82;112 19:29:31 INFO - PROCESS | 1675 | |8;json-parse-financial;58;64;66;64;63;65;65;81;59;57 19:29:31 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;64;64;68;71;75;71;67;69;71;68 19:29:31 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;82;81;80;81;81;82;79;78;81;85 19:29:31 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;139;138;136;135;136;137;136;136;168;146 19:29:31 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;233;169;171;168;160;170;170;169;169;167 19:29:31 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;85;94;77;67;68;64;59;59;70;93 19:29:31 INFO - PROCESS | 1675 | __end_tp_report 19:29:31 INFO - PROCESS | 1675 | __start_cc_report 19:29:31 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,2072 19:29:31 INFO - PROCESS | 1675 | __end_cc_report 19:29:31 INFO - PROCESS | 1675 | __startTimestamp1461983371947__endTimestamp 19:29:31 INFO - PROCESS | 1675 | 19:29:31 INFO - PROCESS | 1675 | ------- Summary: start ------- 19:29:31 INFO - PROCESS | 1675 | Number of tests: 14 19:29:31 INFO - PROCESS | 1675 | 19:29:31 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:112.00 Median:114.00 stddev:3.97 (3.5%) stddev-sans-first:4.20 19:29:31 INFO - PROCESS | 1675 | Values: 113.0 115.0 107.0 114.0 114.0 115.0 114.0 114.0 103.0 111.0 19:29:31 INFO - PROCESS | 1675 | 19:29:31 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:153.40 Median:160.00 stddev:27.88 (17.4%) stddev-sans-first:28.92 19:29:31 INFO - PROCESS | 1675 | Values: 170.0 110.0 165.0 201.0 147.0 144.0 116.0 155.0 182.0 144.0 19:29:31 INFO - PROCESS | 1675 | 19:29:31 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:185.30 Median:186.00 stddev:2.21 (1.2%) stddev-sans-first:1.90 19:29:31 INFO - PROCESS | 1675 | Values: 189.0 187.0 186.0 188.0 184.0 183.0 184.0 186.0 183.0 183.0 19:29:31 INFO - PROCESS | 1675 | 19:29:31 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:80.40 Median:60.50 stddev:36.51 (60.3%) stddev-sans-first:38.56 19:29:31 INFO - PROCESS | 1675 | Values: 71.0 78.0 72.0 150.0 56.0 55.0 59.0 62.0 146.0 55.0 19:29:31 INFO - PROCESS | 1675 | 19:29:31 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:96.30 Median:78.00 stddev:25.20 (32.3%) stddev-sans-first:26.59 19:29:31 INFO - PROCESS | 1675 | Values: 89.0 89.0 142.0 76.0 81.0 77.0 125.0 79.0 77.0 128.0 19:29:31 INFO - PROCESS | 1675 | 19:29:31 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:88.00 stddev:1.58 (1.8%) stddev-sans-first:1.59 19:29:31 INFO - PROCESS | 1675 | Values: 88.0 85.0 88.0 86.0 88.0 85.0 88.0 86.0 88.0 84.0 19:29:31 INFO - PROCESS | 1675 | 19:29:31 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:94.20 Median:93.50 stddev:2.15 (2.3%) stddev-sans-first:2.24 19:29:31 INFO - PROCESS | 1675 | Values: 93.0 93.0 100.0 94.0 93.0 94.0 95.0 94.0 93.0 93.0 19:29:31 INFO - PROCESS | 1675 | 19:29:31 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:86.80 Median:84.00 stddev:8.99 (10.7%) stddev-sans-first:9.43 19:29:31 INFO - PROCESS | 1675 | Values: 83.0 86.0 86.0 86.0 84.0 83.0 84.0 82.0 82.0 112.0 19:29:31 INFO - PROCESS | 1675 | 19:29:31 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:64.20 Median:64.50 stddev:6.71 (10.4%) stddev-sans-first:6.74 19:29:31 INFO - PROCESS | 1675 | Values: 58.0 64.0 66.0 64.0 63.0 65.0 65.0 81.0 59.0 57.0 19:29:31 INFO - PROCESS | 1675 | 19:29:31 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:68.80 Median:70.00 stddev:3.39 (4.8%) stddev-sans-first:3.12 19:29:31 INFO - PROCESS | 1675 | Values: 64.0 64.0 68.0 71.0 75.0 71.0 67.0 69.0 71.0 68.0 19:29:31 INFO - PROCESS | 1675 | 19:29:31 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:81.00 Median:81.00 stddev:1.89 (2.3%) stddev-sans-first:1.96 19:29:31 INFO - PROCESS | 1675 | Values: 82.0 81.0 80.0 81.0 81.0 82.0 79.0 78.0 81.0 85.0 19:29:31 INFO - PROCESS | 1675 | 19:29:31 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:140.70 Median:137.50 stddev:10.10 (7.3%) stddev-sans-first:10.69 19:29:31 INFO - PROCESS | 1675 | Values: 139.0 138.0 136.0 135.0 136.0 137.0 136.0 136.0 168.0 146.0 19:29:31 INFO - PROCESS | 1675 | 19:29:31 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.60 Median:169.50 stddev:20.75 (12.2%) stddev-sans-first:3.26 19:29:31 INFO - PROCESS | 1675 | Values: 233.0 169.0 171.0 168.0 160.0 170.0 170.0 169.0 169.0 167.0 19:29:31 INFO - PROCESS | 1675 | 19:29:31 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.60 Median:73.50 stddev:13.08 (17.8%) stddev-sans-first:13.21 19:29:31 INFO - PROCESS | 1675 | Values: 85.0 94.0 77.0 67.0 68.0 64.0 59.0 59.0 70.0 93.0 19:29:31 INFO - PROCESS | 1675 | -------- Summary: end -------- 19:29:31 INFO - PROCESS | 1675 | 19:29:32 INFO - TEST-INFO | 1675: exit 0 19:29:32 INFO - TEST-OK | kraken | took 58569ms 19:29:32 INFO - SUITE-END | took 1173s 19:29:33 INFO - Completed test suite (00:19:33) 19:29:33 INFO - javascript benchmark 19:29:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3431.9656803431967, 3500.824958752062, 3508.4737289406576, 3513.4027215373385, 3561.732870034748, 5795.420457954203, 5795.652260864351, 5799.449052340032, 5812.331581868087, 5824.796132135376, 5801.854953626162, 5820.912686309698, 5826.388229235922, 5832.300123985117, 5834.2998840139135, 5162.354705661793, 5207.843764687065, 5268.604854635908, 5283.894322113555, 5341.545968592655, 5243.895122097556, 5249.42256351801, 5262.7631756571045, 5263.289455923459, 5267.631265811395, 5225.268462415265, 5246.685198888079, 5276.445973172819, 5325.520703136717, 5335.5198032177095, 5295.814646487393, 5301.0723123453245, 5328.307320048408, 5334.493223143795, 5347.224652425415, 5039.21892106724, 5202.7918883245, 5203.9219411708855, 5225.399079105913, 5235.188545775411, 5584.0, 5606.719664016783, 5608.887822243532, 5661.8584535386535, 5692.5161361284345, 5206.427292997767, 6278.529110316699, 6286.214223222098, 6294.622322660655, 6315.684215789192, 6208.348123447018, 6295.559310848243, 6351.460125889305, 6358.364163583605, 6362.204724409449, 5828.708564571797, 5863.560232982458, 5886.293644762571, 5922.8815423691285, 5931.317898441691, 6277.96861015692, 6307.905381419282, 6316.368363163739, 6316.652584107826, 6322.36776322364, 5962.642241465439, 6088.6042407243385, 6137.5302083125, 6171.352008039181, 6220.098085777628, 6288.465480434214, 6326.778562750282, 6327.209098862642, 6351.237851457855, 6356.173697419306, 6309.779157729457, 6316.021016742412, 6319.715612797435, 6322.968385158137, 6345.302016778151, 6110.892920173543, 6206.627602343874, 6230.501559875199, 6264.279607845065, 6359.777407790705, 4921.26181072842, 4926.531979461945, 4999.800007999639, 5007.674501157413, 5012.097822391932, 4444.155610434072, 4454.844080457168, 4464.687471877002, 4470.9105817883465, 4495.213337665896, 5624.831255062355, 5747.712614369265, 5748.166515855261, 5757.165211044375, 5774.740136693859, 6249.937500625027, 6257.468115210167, 6324.430801227912, 6340.144080549159, 6363.140975968277, 4868.707877527359, 5719.19931209631, 5734.885302293931, 5744.798932037442, 5748.597598168088, 5321.308229930084, 5419.674819510842, 5434.836954891281, 5453.372862120907, 5473.261109750212, 6239.157713708682, 6346.555741098171, 6407.487401007815, 6409.070684750778, 6418.133551970424, 6277.027060805583, 6303.338149494237, 6310.305866354789, 6315.715792789428, 6318.715657795502, 6231.345708700703, 6415.615063096229, 6416.871662566629, 6436.742530298923, 6443.872322343477, 6118.13190915367, 6234.812955611339, 6242.469390101711, 6247.3440288769025, 6253.687315634291, 6290.119383286252, 6310.400511951358, 6330.8100756977365, 6342.365763423621, 6353.0, 4846.273059041172, 5205.427402985744, 6013.849653758516, 6188.164597779422, 6231.906521402273, 6142.109394137736, 6194.039923811816, 6227.96886015567, 6239.75040998355, 6243.843903902257, 6006.189164462741, 6027.126066720389, 6030.216071910623, 6042.305134909366, 6082.148499210026, 5989.431004054608, 6103.05402662588, 6179.783707570214, 6191.102290167991, 6197.4732147767945, 5939.1982082420045, 6014.72933717975, 6029.1559181715475, 6056.788012419721, 6071.787487437919], "value": 5742.544447082077, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.35960790384365, 504.5736352781898, 545.0978630366744, 552.552432529651, 602.3735315272118, 31036.034234522453, 31657.683423165763, 39573.60426395735, 40507.0, 40662.983425414306, 44935.77532112343, 45620.315695264595, 45719.542804571945, 45794.0, 45904.54095459045, 9557.761055973604, 9813.558389872473, 9829.50852457378, 9835.65575204868, 9862.358946668479, 8501.107383724713, 8736.301095912311, 11381.260218085798, 11604.59383921563, 11646.068314534818, 11535.423228838525, 11707.063434925229, 11709.473073711704, 11710.297382157056, 11710.590129345475, 8503.61733721984, 8747.0, 11437.0, 11588.53645854169, 11600.013998810111, 8654.00478944923, 8730.650773969033, 11177.776444471147, 11453.656390308304, 11466.541338346457, 39965.80017099896, 40493.797531012155, 40501.39247911284, 40595.59404405948, 40668.79665601653, 3151.8739250429953, 3256.169676732418, 3321.0, 3461.463473161639, 3526.0479670488826, 28650.0, 28811.71188288111, 28988.985385511616, 29201.415971680446, 29504.26239344012, 2753.29630476039, 2786.4427114577165, 2805.0322638689618, 2823.110720123155, 2915.096320140742, 486425.0, 486633.0, 488114.55942720413, 488390.0, 489211.0, 62712.68643656753, 65540.0, 65597.34402656008, 65726.67136664382, 65745.0, 68902.65548672325, 68921.0, 69021.65489172522, 69030.0, 69159.0, 17412.738808917875, 18021.549461263574, 18022.91862488255, 18038.917664940145, 18048.639027219382, 12223.0, 12396.0, 13174.275414852083, 13741.587752367444, 13850.238236896867, 18005.639887202444, 18010.729839052423, 18052.81947180538, 18076.005819680046, 18100.63798724018, 5879.676617786063, 6096.481799047131, 6856.314368563104, 7033.347163416593, 7096.112985876765, 927.5176908007933, 980.3039841712322, 1000.5197505197546, 1029.7888932768765, 1168.6026750904564, 28005.859970700018, 28401.28996775097, 28465.857670711513, 28527.286817829307, 29074.982375616753, 800.7317548621161, 1001.0590045357341, 1002.5137808163036, 1041.3231399590175, 1085.4464223246043, 13326.400311985984, 15906.443274485337, 15981.680366392608, 15982.920085399732, 16302.673946521003, 601092.0, 605520.0, 612805.0, 615511.922440385, 616039.0, 63466.0, 63701.681491593175, 63767.68116159389, 63851.80740963026, 64005.0, 1362.6184668292894, 1512.3950419831936, 1517.597836573331, 1529.4646873594374, 1607.549886031913, 29337.0, 30886.69113308838, 30899.845500772353, 31015.53476697806, 31757.364852702354, 44389.556104439835, 52605.7369713149, 52944.470555293956, 53061.0, 53109.0, 436202.0, 437600.8719738413, 437928.0, 438074.6192538034, 438340.0, 22944.082236710343, 26018.829152687795, 26549.469010619294, 26778.866105670128, 26809.8659506709, 43023.0, 44168.779156104014, 45237.0, 45938.540614593425, 46484.30273545967, 36376.0, 36966.07584810401, 37265.0, 38075.80962095282, 38128.0, 36477.63522364743, 37143.814280928425, 37472.812635937735, 37876.81061594674, 37913.620863792115, 34752.826235868655, 36526.63473365339, 38415.35054169849, 38529.84410467691, 38773.41839872461, 32777.50833737448, 33733.156671083416, 33916.0, 33931.66068339285, 34126.0, 24865.87567062153, 25210.49579008373, 25283.241502754947, 25563.872180638977, 25631.487370252864], "value": 20027.105440995827, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.5619585029088, 406.9206504731579, 407.4743580780795, 408.2243736899888, 413.7104027180972, 13842.792358114611, 20257.088431020602, 20985.790142098573, 20989.160433582656, 21012.369628911158, 16806.83193168068, 18422.631547369077, 20565.28021519247, 20858.791412085873, 20930.790692093073, 5465.99999999999, 6162.291336496293, 6187.752489900398, 6194.977828658266, 6204.81385558434, 5806.419358064202, 6115.602485838429, 6122.663253521055, 6124.020156774917, 6180.690965451732, 6042.818715438544, 6044.24446944132, 6067.0, 6067.7572897084065, 6084.847878803021, 5598.832035038955, 5965.5525835562075, 5967.16459695643, 5975.910361344583, 6066.363031881655, 5487.862803429906, 5766.798162064308, 5771.451712087345, 5914.37899020601, 5945.31628862682, 13745.03784735069, 14064.945129115356, 14099.22454265015, 17583.824161758348, 26126.69366531666, 2019.4547472182576, 2092.5605622819226, 2110.504031552584, 2222.977770222293, 2342.7657234276608, 9903.306768526207, 10079.395236285847, 10219.437930913797, 10350.430726310049, 10602.416867072308, 1586.3495966653616, 1719.7678313427652, 1736.1753167245583, 1781.8218178182208, 1811.8641101917408, 121603.3919830404, 127612.06715529917, 128056.99999999907, 130709.0, 135627.32186339103, 11614.825777613343, 11774.529018839321, 11791.88208117925, 11831.585894493652, 11842.230255033395, 15559.377624894878, 15568.820365148888, 15581.0, 15610.3755849767, 15624.453144139901, 7187.640617969185, 7840.607969601611, 8264.876026859602, 8603.569821509025, 9036.8644470333, 8886.555672216364, 8887.688930887389, 8895.110488951053, 9014.414063085878, 9028.909710902939, 4565.931511027337, 4662.603678687349, 4667.299905014275, 4697.201475749131, 4719.38647975761, 1780.8931464112195, 1903.9428817135508, 1918.529960159742, 1965.628891656289, 1989.6418644643813, 854.2269246332079, 943.5140902435252, 944.4616568555857, 973.9366941148803, 1037.5331101004426, 20326.898365508077, 21272.255471058746, 21443.21238239019, 21640.458988525097, 21779.0, 927.6474939522938, 951.46242373059, 1028.5834237133972, 1044.3264094658903, 1074.3661239868359, 10225.079742823182, 10939.83590246147, 11049.509864747488, 11109.611163609397, 11184.60853870127, 596209.0, 597728.0, 623299.0, 630200.0, 641287.0, 131212.0, 131700.0, 132510.33744831214, 132952.0, 134170.0, 1010.3281317923645, 1013.7668336282696, 1030.6547306652205, 1030.8041472120274, 1036.2953191829629, 18036.45890623231, 18289.0, 18477.353292634692, 18829.24683012719, 19058.809411905702, 54054.0, 54842.725786370815, 54856.451435485134, 55312.72343638256, 55763.44236557582, 73930.89103663557, 76725.0, 77255.0, 77265.2273477258, 80791.0, 7974.681012759656, 8124.39067069984, 8171.713990010321, 8265.256126948603, 8359.289460395692, 8649.697260595849, 11944.64166075019, 12826.743465130832, 12959.287239201934, 13225.412950446009, 11164.665060048212, 11344.20590558653, 11359.261647992855, 11596.246243994114, 11673.007794337576, 10218.744531386477, 10411.895881041395, 11157.888421115686, 11202.159838012345, 11444.542218311175, 11301.26541774782, 11444.71388215268, 11693.415329233674, 11798.764024719287, 11924.224925379822, 8233.094359620556, 8307.044689860744, 8329.416940814084, 8487.533185674847, 8689.217970382695, 9861.309708320636, 11284.605038823602, 11324.0, 11546.711332216762, 11735.823962640396], "value": 9592.28215651006, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1404.9719005619884, 1417.0364152376392, 1606.2771752711278, 1776.697961346571, 1972.4181366496882, 7877.842443151134, 8612.052674205841, 8815.471071735701, 8816.911830881705, 8819.647214111435, 8441.873371899426, 8673.17604827542, 8677.652893884253, 8689.131086891308, 8707.390482666216, 2149.0651566568513, 2364.716234051916, 2409.0604664180983, 2436.4274395517127, 2445.76765207305, 2433.038949614901, 2465.0386349323776, 2467.346153269376, 2468.02513007362, 2475.158446128316, 2376.217886421973, 2428.2836563213805, 2435.914742983996, 2436.378723425533, 2437.6709144265565, 2127.6702111172795, 2352.3883790214495, 2415.0943396226417, 2432.404061005065, 2438.0491608272787, 2424.527217192648, 2430.91491797788, 2465.988944532733, 2469.8271121021535, 2475.047106863852, 7495.925040749577, 8746.0, 8834.983976842617, 8840.336974726857, 8855.380123391365, 5208.869778255536, 5408.13469844823, 5427.565794736412, 5462.781488740446, 5471.0, 3591.3535563598543, 3623.1666716655054, 3663.835127419246, 3702.5186725725753, 3733.607971163043, 927.972160835176, 1036.5905467340433, 1057.9100776433936, 1058.6136060338, 1058.9947050264777, 11725.472353744017, 11801.409929503576, 11815.11386646005, 11817.94091029548, 11822.586209482713, 4817.421909370828, 4858.514148585114, 4927.162382394929, 4927.413108118267, 4942.456329803718, 10056.145227655585, 10075.445850478294, 10084.798304033879, 10085.647002354883, 10099.545520451451, 5380.542653874386, 5428.75570599324, 5430.158325459481, 5454.318210223722, 5484.122540393518, 4443.955560444419, 4644.047970166175, 4806.543378379049, 4967.627427942919, 4992.675476094042, 4017.678585713136, 4022.5935835392893, 4119.711620186558, 4161.188568229227, 4165.416841642173, 1110.7389763405592, 1261.9558315458914, 1299.0387113535915, 1331.9477612686064, 1358.246173373787, 777.7705576854728, 837.2590257622048, 886.1833241854301, 955.8614000969959, 963.4460185393427, 5318.361796584435, 5782.826515204551, 5831.300243970666, 6009.978303688384, 6045.909311360333, 827.8013276813642, 975.2490582251768, 991.8809742830906, 1009.3590569988111, 1035.570238351076, 3928.744631598937, 4011.0774521860135, 4045.4336392905016, 4075.5313138988804, 4098.959010409858, 10303.9484802577, 10753.408562529135, 10775.83836242457, 10901.727456813644, 10906.61826836057, 10578.735531611617, 10700.732481687864, 10712.678619641423, 10730.0, 10731.83902241467, 8211.630476628567, 8235.423520353517, 8238.917610823935, 8247.21651443112, 8278.71024514139, 5696.430356964271, 5921.733521991435, 5938.079597662369, 6095.359987201457, 6130.754769809336, 9462.668806591737, 9941.453220072675, 10216.927222641812, 10225.181985441295, 10255.487225638539, 9944.35361701487, 9983.700488985342, 9990.10089091956, 9993.600255989679, 10027.398356098658, 5901.763929442946, 6705.161854768154, 7099.751508697171, 7424.740134095281, 7614.505057171266, 7830.921690783247, 8433.493990360597, 8449.493030418194, 8477.109903460294, 8481.703140390056, 7386.482946193715, 7482.588457634664, 7493.025906632103, 7614.428917831074, 7619.580923049286, 7300.56196628204, 7337.0, 7399.963000184965, 7470.738524151629, 7479.513831600929, 6927.3419025192525, 7126.714931402686, 7159.785206443815, 7161.4270858330165, 7170.820729481805, 4840.636952228527, 4948.925766113439, 4984.800607975641, 4987.725675087905, 5089.51649593288, 4967.7019378837385, 4987.625928055338, 5003.674761140514, 5062.038212739568, 5247.580193584427], "value": 4624.755242041702, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.0365097405834, 320.8620293273892, 327.3191761136834, 328.49411905665266, 332.03212635168484, 4287.378330142127, 4405.669574781896, 5421.837344879655, 5432.674039557629, 5546.611737178399, 4528.388667529883, 4773.832915847946, 4797.673128532659, 5313.946860531393, 5350.91973620396, 3835.1974571951137, 3909.042284640267, 3982.2632812929555, 4045.2920738870725, 4164.813028286939, 3103.5965324507897, 3830.9464897153366, 3998.300297447935, 4113.732607380525, 4179.456670632813, 4063.9390409143884, 4094.7747873866924, 4119.155573107506, 4121.464209652756, 4123.567025462328, 3229.3702727968057, 3805.3911374180275, 4030.556638769733, 4068.1863627274515, 4151.398047283157, 3553.5913369962527, 3578.6779189873037, 3590.2281009583066, 3615.692666123383, 3615.94576081359, 4101.118259574176, 4455.794381781006, 4506.7070640408265, 5145.536901678829, 5194.220866869962, 4223.704340696153, 4569.72581645099, 4735.879784587397, 4749.572538471554, 4755.95244047562, 5197.7920883164625, 5617.8033768817895, 5663.377028526858, 5664.40523745005, 5673.6879471629045, 3525.906968839668, 3791.7156213284115, 4018.8392464301687, 4031.286703151149, 4031.677465802758, 5593.4338385252195, 6021.2473440819895, 6023.969880150615, 6029.698515074228, 6063.120847477136, 3817.1411432428035, 3947.7103280097476, 3981.8009099544906, 3987.322155233617, 4004.039030632627, 3537.663921927413, 3975.0, 3984.960150398517, 4024.4969378827645, 4061.248668996245, 4115.403266526397, 4145.440365550672, 4604.608608268335, 4689.484156742756, 4745.810167593258, 5722.484976352065, 5729.512991395694, 5746.683932383759, 5789.623674461147, 5809.0124678804705, 1462.283481094256, 1474.2039298778539, 1477.6084337650532, 1479.3948565806006, 1483.6513419346445, 786.9409794265339, 903.6249956268207, 929.6188562689265, 954.2175416158683, 960.6349587156835, 4538.954610453853, 4846.466888642247, 4971.055499455092, 5193.246979187997, 5335.519803217729, 5891.522442943308, 5899.321578018497, 5911.0, 5912.260967379078, 5942.465178133989, 4237.5762423758, 4644.349791029259, 5108.744562771921, 5114.846554603368, 5279.947200527946, 2877.453283876057, 3361.3445378151027, 3390.6100798408006, 3397.5413319202084, 3402.247842361186, 5969.641821490638, 6705.631190284581, 6733.104764951118, 6750.848228339536, 6754.696038678271, 5057.418396884332, 5899.76400943966, 6304.842378940563, 6452.935470645328, 6639.074668346222, 6016.669083200466, 6454.676791257781, 6521.804345869631, 6688.498362622726, 6708.6981085850275, 5341.41244463101, 5463.852590955944, 6122.234720659918, 6404.263509696445, 6448.967755161382, 5459.617826752089, 5932.851678708067, 6020.066889632114, 6195.349488303663, 6281.685915704105, 5798.710064496842, 5863.794767183299, 6516.185476815398, 6679.4656427484715, 6692.83267918306, 3526.259485508072, 3960.3861401481663, 3984.8007599619323, 4012.7391719538136, 4017.357222844331, 4524.638028957741, 4624.514425985358, 5946.583739138218, 5958.2631807712105, 5973.641581505123, 5424.457554244544, 5743.8564035899435, 5777.480026797609, 5799.710014499174, 5840.737166827418, 4937.28409380645, 5288.524032837063, 5690.0, 5720.055181238399, 5745.798897038583, 5592.608517403905, 5603.32760068778, 5612.85967850807, 5631.887362252814, 5644.971775141263, 5105.821296254613, 5383.973080134731, 5540.271321506556, 5570.944290557042, 5613.663180209201, 4747.572718455355, 4749.691270067435, 5125.8974820502635, 5188.610854185876, 5631.380548139619], "value": 4208.095394057236, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [656.7569999100333, 733.644182571453, 741.7700512841018, 743.4536711762213, 747.2564798025965, 12531.49874005041, 14486.782698259522, 14503.782443263359, 15198.848011519882, 15239.619009524768, 13823.861761382383, 13834.031617786759, 13956.092853964485, 13956.44174233032, 13956.651083722913, 733.7248531800575, 758.4956004257162, 885.1723638398087, 912.8767616371788, 929.4376901974309, 919.6045700348848, 925.2181906289209, 928.0209379105041, 929.707142250189, 930.0885132570052, 981.9312648114635, 984.0798853072364, 987.239825334492, 987.501311837521, 988.8220120378329, 757.3827330725442, 940.5673390240497, 973.8441849304113, 975.4196253229317, 978.8776402949632, 728.0644371982031, 728.1953441447231, 729.117767501324, 729.4346881167085, 730.8903664450374, 14551.05418147817, 14600.634984125481, 14626.926865365711, 14644.0, 14648.0, 12161.331126788024, 12373.690657733629, 12451.688707782287, 12634.50121581616, 12896.09727319088, 9237.0, 10015.549300281506, 10310.587576496931, 10477.84283235752, 10551.997560231766, 3316.8341582920757, 3505.8071806050657, 3531.2937412517344, 3582.2119133790525, 3739.139997800521, 78634.0, 79978.0, 80706.78939815908, 81253.0, 84047.5797621008, 9432.764180895396, 9493.477858717699, 9668.661596844076, 9825.263105267133, 9842.212622990144, 21971.231006915, 22104.889475552518, 22156.22453214162, 22411.0, 22446.77553224447, 7504.812379690442, 7549.09410870688, 7626.8093297668, 7644.235576442423, 7647.887464657811, 4069.8575549855614, 4165.812538435777, 4169.624733773975, 4194.979025104855, 4202.85290014848, 3546.5744110706883, 3666.8533258669354, 3675.1547144156457, 3678.0988657779017, 3686.6866316362875, 507.54321111000394, 550.9531689806412, 649.7141257846532, 655.6787174284566, 656.3239862941178, 510.4742115620916, 514.7400562715804, 540.753956949587, 586.0154939701257, 601.5127746525328, 7987.9201207988335, 8331.416800823885, 8332.458390204618, 8334.708285209988, 8391.496510209407, 518.5721779531901, 581.0122791254884, 601.8194541637491, 610.3926593039954, 619.1610367951452, 3766.6233376662663, 3892.338302488584, 3949.5458022327225, 4032.8790136295956, 4147.605977432139, 101345.0, 102540.48729756303, 103056.0, 104633.95366046394, 105833.0, 73005.63497182586, 79171.62485125456, 83637.58181209055, 86149.56925215333, 87575.56212218897, 72286.0, 72951.0, 75565.24434755692, 75647.24352756512, 77257.61371193221, 7853.214678532101, 7915.881261781193, 7938.761837144895, 8002.7199048033035, 8077.636506357346, 45255.77372113118, 45713.542864570925, 46500.53499464962, 46781.766091169324, 48031.55905322846, 61562.4609384769, 64991.35008649853, 66000.0, 66184.6690766543, 67421.0, 10090.394576325443, 10273.897261027294, 10312.0, 10536.0, 11234.438278086227, 10503.632372866912, 11095.503511709801, 11096.470073442619, 11457.369844658624, 12200.962918151701, 6959.705142641664, 9202.907970920205, 11419.181356438536, 11706.824397633873, 12310.938445308075, 11252.606158784403, 11473.139514536277, 11574.131940104357, 11727.765444690887, 11925.0, 11105.444727763741, 11375.262719594004, 11436.313821170756, 11748.0, 11800.114991375853, 9119.589618467053, 9547.474888881177, 9751.561179746786, 9885.406875587487, 10014.39913605186, 8021.358291336795, 9985.350952188084, 9993.550290236812, 10008.899911000797, 10013.0], "value": 6885.784072949204, "unit": "score"}], "name": "dromaeo_css", "value": 7271.702564348239, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 115.0, 107.0, 114.0, 114.0, 115.0, 114.0, 114.0, 103.0, 111.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 110.0, 165.0, 201.0, 147.0, 144.0, 116.0, 155.0, 182.0, 144.0], "value": 153.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 187.0, 186.0, 188.0, 184.0, 183.0, 184.0, 186.0, 183.0, 183.0], "value": 185.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 78.0, 72.0, 150.0, 56.0, 55.0, 59.0, 62.0, 146.0, 55.0], "value": 80.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 142.0, 76.0, 81.0, 77.0, 125.0, 79.0, 77.0, 128.0], "value": 96.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 86.0, 88.0, 85.0, 88.0, 86.0, 88.0, 84.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 100.0, 94.0, 93.0, 94.0, 95.0, 94.0, 93.0, 93.0], "value": 94.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 86.0, 86.0, 84.0, 83.0, 84.0, 82.0, 82.0, 112.0], "value": 86.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 64.0, 66.0, 64.0, 63.0, 65.0, 65.0, 81.0, 59.0, 57.0], "value": 64.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 68.0, 71.0, 75.0, 71.0, 67.0, 69.0, 71.0, 68.0], "value": 68.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 80.0, 81.0, 81.0, 82.0, 79.0, 78.0, 81.0, 85.0], "value": 81.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 138.0, 136.0, 135.0, 136.0, 137.0, 136.0, 136.0, 168.0, 146.0], "value": 140.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [233.0, 169.0, 171.0, 168.0, 160.0, 170.0, 170.0, 169.0, 169.0, 167.0], "value": 174.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 94.0, 77.0, 67.0, 68.0, 64.0, 59.0, 59.0, 70.0, 93.0], "value": 73.6, "unit": "score"}], "name": "kraken", "value": 1497.8999999999999, "alertThreshold": 2.0}]} 19:29:33 INFO - Return code: 0 19:29:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:29:33 ERROR - # TBPL SUCCESS # 19:29:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:29:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:29:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:29:33 INFO - 2016-04-29 19:29:33.208 screenresolution[1679:10634] starting screenresolution argv=screenresolution get 19:29:33 INFO - 2016-04-29 19:29:33.211 screenresolution[1679:10634] Display 0: 1600x1200x32@60 19:29:33 INFO - 2016-04-29 19:29:33.217 screenresolution[1680:10637] starting screenresolution argv=screenresolution list 19:29:33 INFO - Available Modes on Display 0 19:29:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:29:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:29:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:29:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:29:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:29:33 INFO - Intel Iris: 19:29:33 INFO - Chipset Model: Intel Iris 19:29:33 INFO - Type: GPU 19:29:33 INFO - Bus: Built-In 19:29:33 INFO - VRAM (Dynamic, Max): 1536 MB 19:29:33 INFO - Vendor: Intel (0x8086) 19:29:33 INFO - Device ID: 0x0a2e 19:29:33 INFO - Revision ID: 0x0009 19:29:33 INFO - Displays: 19:29:33 INFO - Display: 19:29:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:29:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:29:33 INFO - Display Serial Number: 1600x1200 60 19:29:33 INFO - Main Display: Yes 19:29:33 INFO - Mirror: Off 19:29:33 INFO - Online: Yes 19:29:33 INFO - Rotation: Supported 19:29:33 INFO - Return code: 0 19:29:33 INFO - Running post-action listener: _resource_record_post_action 19:29:33 INFO - Running post-run listener: _resource_record_post_run 19:29:34 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 32542208; Write bytes: 655430656; Read time: 327; Write time: 2276 19:29:34 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 342690304; Write bytes: 338550784; Read time: 26438; Write time: 762 19:29:34 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 31624704; Write bytes: 297546752; Read time: 314; Write time: 1460 19:29:34 INFO - Running post-run listener: _upload_blobber_files 19:29:34 INFO - Blob upload gear active. 19:29:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:29:34 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 19:29:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:29:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:29:34 INFO - (blobuploader) - INFO - Open directory for files ... 19:29:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:29:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:29:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:29:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:29:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:29:36 INFO - (blobuploader) - INFO - Done attempting. 19:29:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:29:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:29:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:29:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:29:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:29:37 INFO - (blobuploader) - INFO - Done attempting. 19:29:37 INFO - (blobuploader) - INFO - Iteration through files over. 19:29:37 INFO - Return code: 0 19:29:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:29:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:29:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e8dec3bbb34b472483dc99409dd41bbe95b5929a642adaa54845984db3dff43bd7d81b9be76289609f19ab8d1f21909305122017fec4a4871280bf003ca64d57", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b143e25d1fead36a39d89aae3deb83ddbd8ec0a9170ce597d1502aface472cbc485c83560a184b361e77f99a91f81df76790db4c35ecc6100b611377cd44036"} 19:29:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:29:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:29:37 INFO - Contents: 19:29:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e8dec3bbb34b472483dc99409dd41bbe95b5929a642adaa54845984db3dff43bd7d81b9be76289609f19ab8d1f21909305122017fec4a4871280bf003ca64d57", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b143e25d1fead36a39d89aae3deb83ddbd8ec0a9170ce597d1502aface472cbc485c83560a184b361e77f99a91f81df76790db4c35ecc6100b611377cd44036"} 19:29:37 INFO - Running post-run listener: copy_logs_to_upload_dir 19:29:37 INFO - Copying logs to upload dir... 19:29:37 INFO - mkdir: /builds/slave/test/build/upload/logs 19:29:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.346170 ========= master_lag: 0.20 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-04-29 19:29:37.409371) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-29 19:29:37.412999) ========= 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.taurJMB9t8/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.gstmZkM6VM/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e8dec3bbb34b472483dc99409dd41bbe95b5929a642adaa54845984db3dff43bd7d81b9be76289609f19ab8d1f21909305122017fec4a4871280bf003ca64d57", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b143e25d1fead36a39d89aae3deb83ddbd8ec0a9170ce597d1502aface472cbc485c83560a184b361e77f99a91f81df76790db4c35ecc6100b611377cd44036"} build_url:https://queue.taskcluster.net/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011382 build_url: 'https://queue.taskcluster.net/v1/task/JTqr0gkgT8WGS1NGs3Nj5A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e8dec3bbb34b472483dc99409dd41bbe95b5929a642adaa54845984db3dff43bd7d81b9be76289609f19ab8d1f21909305122017fec4a4871280bf003ca64d57", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b143e25d1fead36a39d89aae3deb83ddbd8ec0a9170ce597d1502aface472cbc485c83560a184b361e77f99a91f81df76790db4c35ecc6100b611377cd44036"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-29 19:29:37.450199) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 19:29:37.450524) ========= 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.taurJMB9t8/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.gstmZkM6VM/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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.005219 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 19:29:37.501577) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-29 19:29:37.502274) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-29 19:29:37.502891) ========= ========= Total master_lag: 28.27 =========