builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0141 starttime: 1461750451.46 results: success (0) buildid: 20160427004105 builduid: f0f0e9373ad248319c38fd47286ed441 revision: 3b60d399d4eef579688a499675c337a252a7bcac ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-27 02:47:31.457937) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-27 02:47:31.460515) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-27 02:47:31.460807) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-27 02:47:31.634356) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 02:47:31.634661) ========= 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.RS0Vpt80Di/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.9HdcxFTHdx/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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-27 02:47:31-- 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% 159M=0s 2016-04-27 02:47:32 (159 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.492311 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 02:47:32.157372) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 02:47:32.157688) ========= 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.RS0Vpt80Di/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.9HdcxFTHdx/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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.027180 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 02:47:32.219074) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 02:47:32.219399) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3b60d399d4eef579688a499675c337a252a7bcac --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 3b60d399d4eef579688a499675c337a252a7bcac --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.RS0Vpt80Di/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.9HdcxFTHdx/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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-27 02:47:32,299 truncating revision to first 12 chars 2016-04-27 02:47:32,299 Setting DEBUG logging. 2016-04-27 02:47:32,299 attempt 1/10 2016-04-27 02:47:32,299 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3b60d399d4ee?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-27 02:47:32,561 unpacking tar archive at: mozilla-aurora-3b60d399d4ee/testing/mozharness/ program finished with exit code 0 elapsedTime=0.512389 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 02:47:32.748718) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-27 02:47:32.749019) ========= script_repo_revision: 3b60d399d4eef579688a499675c337a252a7bcac ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-27 02:47:32.749412) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-27 02:47:32.749677) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-27 02:47:32.772895) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-04-27 02:47:32.773172) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.RS0Vpt80Di/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.9HdcxFTHdx/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 02:47:32 INFO - MultiFileLogger online at 20160427 02:47:32 in /builds/slave/test 02:47:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:47:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:47:32 INFO - {'append_to_log': False, 02:47:32 INFO - 'base_work_dir': '/builds/slave/test', 02:47:32 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:47:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:47:32 INFO - 'branch': 'Mozilla-Aurora', 02:47:32 INFO - 'buildbot_json_path': 'buildprops.json', 02:47:32 INFO - 'config_files': ('talos/mac_config.py',), 02:47:32 INFO - 'default_actions': ('clobber', 02:47:32 INFO - 'read-buildbot-config', 02:47:32 INFO - 'download-and-extract', 02:47:32 INFO - 'populate-webroot', 02:47:32 INFO - 'create-virtualenv', 02:47:32 INFO - 'install', 02:47:32 INFO - 'run-tests'), 02:47:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:47:32 INFO - 'download_minidump_stackwalk': True, 02:47:32 INFO - 'download_symbols': 'ondemand', 02:47:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:47:32 INFO - 'tooltool.py': '/tools/tooltool.py', 02:47:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:47:32 INFO - '/tools/misc-python/virtualenv.py')}, 02:47:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:47:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:47:32 INFO - 'installer_path': 'installer.exe', 02:47:32 INFO - 'log_level': 'info', 02:47:32 INFO - 'log_name': 'talos', 02:47:32 INFO - 'log_to_console': True, 02:47:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:47:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:47:32 INFO - 'opt_config_files': (), 02:47:32 INFO - 'pip_index': False, 02:47:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:47:32 INFO - 'cmd': ('bash', 02:47:32 INFO - '-c', 02:47:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:47:32 INFO - 'enabled': True, 02:47:32 INFO - 'halt_on_failure': False, 02:47:32 INFO - 'name': 'check_screen_resolution'},), 02:47:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:47:32 INFO - 'cmd': ('bash', 02:47:32 INFO - '-c', 02:47:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:47:32 INFO - 'enabled': True, 02:47:32 INFO - 'halt_on_failure': False, 02:47:32 INFO - 'name': 'check_screen_resolution'},), 02:47:32 INFO - 'run_cmd_checks_enabled': True, 02:47:32 INFO - 'sps_profile': False, 02:47:32 INFO - 'sps_profile_interval': 0, 02:47:32 INFO - 'suite': 'dromaeojs-e10s', 02:47:32 INFO - 'system_bits': '32', 02:47:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:47:32 INFO - 'title': 't-yosemite-r7-0141', 02:47:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:47:32 INFO - 'use_talos_json': True, 02:47:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:47:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:47:32 INFO - 'work_dir': 'build'} 02:47:32 INFO - ##### 02:47:32 INFO - ##### Running clobber step. 02:47:32 INFO - ##### 02:47:32 INFO - Running pre-action listener: _resource_record_pre_action 02:47:32 INFO - Running main action method: clobber 02:47:32 INFO - rmtree: /builds/slave/test/build 02:47:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:47:34 INFO - Running post-action listener: _resource_record_post_action 02:47:34 INFO - ##### 02:47:34 INFO - ##### Running read-buildbot-config step. 02:47:34 INFO - ##### 02:47:34 INFO - Running pre-action listener: _resource_record_pre_action 02:47:34 INFO - Running main action method: read_buildbot_config 02:47:34 INFO - Using buildbot properties: 02:47:34 INFO - { 02:47:34 INFO - "project": "", 02:47:34 INFO - "product": "firefox", 02:47:34 INFO - "script_repo_revision": "production", 02:47:34 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:47:34 INFO - "repository": "", 02:47:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:47:34 INFO - "buildid": "20160427004105", 02:47:34 INFO - "stage_platform": "macosx64", 02:47:34 INFO - "basedir": "/builds/slave/test", 02:47:34 INFO - "nightly_build": "True", 02:47:34 INFO - "buildnumber": 268, 02:47:34 INFO - "slavename": "t-yosemite-r7-0141", 02:47:34 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 02:47:34 INFO - "platform": "yosemite_r7", 02:47:34 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:47:34 INFO - "branch": "mozilla-aurora", 02:47:34 INFO - "repo_path": "releases/mozilla-aurora", 02:47:34 INFO - "revision": "3b60d399d4eef579688a499675c337a252a7bcac", 02:47:34 INFO - "pgo_build": "False", 02:47:34 INFO - "builduid": "f0f0e9373ad248319c38fd47286ed441", 02:47:34 INFO - "slavebuilddir": "test" 02:47:34 INFO - } 02:47:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/RYa29tVaSb6PZcdrKHbVmw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 02:47:34 INFO - Running post-action listener: _resource_record_post_action 02:47:34 INFO - ##### 02:47:34 INFO - ##### Running download-and-extract step. 02:47:34 INFO - ##### 02:47:34 INFO - Running pre-action listener: _resource_record_pre_action 02:47:34 INFO - Running main action method: download_and_extract 02:47:34 INFO - mkdir: /builds/slave/test/build/tests 02:47: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')]} 02:47:34 INFO - https://queue.taskcluster.net/v1/task/RYa29tVaSb6PZcdrKHbVmw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:47:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RYa29tVaSb6PZcdrKHbVmw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:47:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RYa29tVaSb6PZcdrKHbVmw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:47:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RYa29tVaSb6PZcdrKHbVmw/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 02:47:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RYa29tVaSb6PZcdrKHbVmw/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 02:47:36 INFO - Downloaded 1199 bytes. 02:47:36 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:47:36 INFO - Using the following test package requirements: 02:47:36 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:47:36 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:47:36 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 02:47:36 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:47:36 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 02:47:36 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:47:36 INFO - u'jsshell-mac.zip'], 02:47:36 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:47:36 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 02:47:36 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:47:36 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:47:36 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 02:47:36 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:47:36 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 02:47:36 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:47:36 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 02:47:36 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:47:36 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 02:47:36 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:47: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')]} 02:47:36 INFO - https://queue.taskcluster.net/v1/task/RYa29tVaSb6PZcdrKHbVmw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:47:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RYa29tVaSb6PZcdrKHbVmw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:47:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RYa29tVaSb6PZcdrKHbVmw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:47:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RYa29tVaSb6PZcdrKHbVmw/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 02:47:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RYa29tVaSb6PZcdrKHbVmw/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 02:47:37 INFO - Downloaded 18110599 bytes. 02:47: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'] 02:47: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 02:47:41 INFO - Return code: 0 02:47:41 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 02:47:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:47:41 INFO - https://queue.taskcluster.net/v1/task/RYa29tVaSb6PZcdrKHbVmw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:47:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RYa29tVaSb6PZcdrKHbVmw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:47:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RYa29tVaSb6PZcdrKHbVmw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:47:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RYa29tVaSb6PZcdrKHbVmw/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 02:47:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RYa29tVaSb6PZcdrKHbVmw/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 02:47:42 INFO - Downloaded 18110599 bytes. 02:47: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'] 02:47: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 02:47:46 INFO - Return code: 0 02:47: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')]} 02:47:46 INFO - https://queue.taskcluster.net/v1/task/RYa29tVaSb6PZcdrKHbVmw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:47:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RYa29tVaSb6PZcdrKHbVmw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:47:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RYa29tVaSb6PZcdrKHbVmw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:47:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RYa29tVaSb6PZcdrKHbVmw/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 02:47:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RYa29tVaSb6PZcdrKHbVmw/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 02:47:48 INFO - Downloaded 10840145 bytes. 02:47:48 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'] 02:47:48 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 02:47:49 INFO - Return code: 0 02:47:49 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')]} 02:47:49 INFO - https://queue.taskcluster.net/v1/task/RYa29tVaSb6PZcdrKHbVmw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 02:47:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RYa29tVaSb6PZcdrKHbVmw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:47:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RYa29tVaSb6PZcdrKHbVmw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:47:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RYa29tVaSb6PZcdrKHbVmw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 02:47:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RYa29tVaSb6PZcdrKHbVmw/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 02:47:53 INFO - Downloaded 114213362 bytes. 02:47:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RYa29tVaSb6PZcdrKHbVmw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:47:53 INFO - mkdir: /builds/slave/test/properties 02:47:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:47:53 INFO - Writing to file /builds/slave/test/properties/build_url 02:47:53 INFO - Contents: 02:47:53 INFO - build_url:https://queue.taskcluster.net/v1/task/RYa29tVaSb6PZcdrKHbVmw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:47:54 INFO - Running post-action listener: _resource_record_post_action 02:47:54 INFO - Running post-action listener: set_extra_try_arguments 02:47:54 INFO - ##### 02:47:54 INFO - ##### Running populate-webroot step. 02:47:54 INFO - ##### 02:47:54 INFO - Running pre-action listener: _resource_record_pre_action 02:47:54 INFO - Running main action method: populate_webroot 02:47:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:47:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:47:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:47:54 INFO - '--tppagecycles', 02:47:54 INFO - '7'], 02:47:54 INFO - 'tests': ['tsvgm']}}, 02:47:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:47:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:47:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:47:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:47:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:47:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:47:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:47:54 INFO - 'talos_options': ['--disable-e10s'], 02:47:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:47:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:47:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:47:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:47:54 INFO - 'talos_options': ['--disable-e10s'], 02:47:54 INFO - 'tests': ['damp', 'tps']}, 02:47:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:47:54 INFO - 'tests': ['damp', 'tps']}, 02:47:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:47:54 INFO - 'tests': ['dromaeo_dom']}, 02:47:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:47:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:47:54 INFO - 'tests': ['a11yr', 02:47:54 INFO - 'ts_paint', 02:47:54 INFO - 'tpaint', 02:47:54 INFO - 'sessionrestore', 02:47:54 INFO - 'sessionrestore_no_auto_restore', 02:47:54 INFO - 'tabpaint']}, 02:47:54 INFO - 'other-e10s': {'tests': ['a11yr', 02:47:54 INFO - 'ts_paint', 02:47:54 INFO - 'tpaint', 02:47:54 INFO - 'sessionrestore', 02:47:54 INFO - 'sessionrestore_no_auto_restore', 02:47:54 INFO - 'tabpaint']}, 02:47:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:47:54 INFO - 'ts_paint', 02:47:54 INFO - 'tpaint', 02:47:54 INFO - 'sessionrestore', 02:47:54 INFO - 'sessionrestore_no_auto_restore', 02:47:54 INFO - 'tabpaint']}, 02:47:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:47:54 INFO - 'ts_paint', 02:47:54 INFO - 'tpaint', 02:47:54 INFO - 'sessionrestore', 02:47:54 INFO - 'sessionrestore_no_auto_restore', 02:47:54 INFO - 'tabpaint']}, 02:47:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:47:54 INFO - 'tests': ['a11yr', 02:47:54 INFO - 'ts_paint', 02:47:54 INFO - 'tpaint', 02:47:54 INFO - 'sessionrestore', 02:47:54 INFO - 'sessionrestore_no_auto_restore', 02:47:54 INFO - 'tabpaint']}, 02:47:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:47:54 INFO - 'tests': ['a11yr', 02:47:54 INFO - 'ts_paint', 02:47:54 INFO - 'tpaint', 02:47:54 INFO - 'sessionrestore', 02:47:54 INFO - 'sessionrestore_no_auto_restore', 02:47:54 INFO - 'tabpaint']}, 02:47:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:47:54 INFO - 'tests': ['tsvgx', 02:47:54 INFO - 'tsvgr_opacity', 02:47:54 INFO - 'tart', 02:47:54 INFO - 'tscrollx', 02:47:54 INFO - 'cart']}, 02:47:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:47:54 INFO - 'tsvgr_opacity', 02:47:54 INFO - 'tart', 02:47:54 INFO - 'tscrollx', 02:47:54 INFO - 'cart']}, 02:47:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:47:54 INFO - 'talos_options': ['--disable-e10s'], 02:47:54 INFO - 'tests': ['tp5o']}, 02:47:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:47:54 INFO - 'tests': ['tp5o']}, 02:47:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:47:54 INFO - 'talos_options': ['--disable-e10s', 02:47:54 INFO - '--xperf_path', 02:47:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:47:54 INFO - 'tests': ['tp5n']}, 02:47:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:47:54 INFO - 'talos_options': ['--xperf_path', 02:47:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:47:54 INFO - 'tests': ['tp5n']}}, 02:47:54 INFO - 'talos.zip': {'path': '', 02:47:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:47:54 INFO - Running post-action listener: _resource_record_post_action 02:47:54 INFO - ##### 02:47:54 INFO - ##### Running create-virtualenv step. 02:47:54 INFO - ##### 02:47:54 INFO - Running pre-action listener: _resource_record_pre_action 02:47:54 INFO - Running main action method: create_virtualenv 02:47:54 INFO - Creating virtualenv /builds/slave/test/build/venv 02:47:54 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 02:47:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:47:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:47:55 INFO - Using real prefix '/tools/python27' 02:47:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:47:55 INFO - Installing distribute.............................................................................................................................................................................................done. 02:47:58 INFO - Installing pip.................done. 02:47:58 INFO - Return code: 0 02:47:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:47:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:47:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:47:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109b21a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b31420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd27257e930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099c0e00>, '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 0x109829db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1099bbb20>, '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.RS0Vpt80Di/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.9HdcxFTHdx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 02:47:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:47:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:47:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RS0Vpt80Di/Render', 02:47:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:47:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:47:58 INFO - 'HOME': '/Users/cltbld', 02:47:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:47:58 INFO - 'LOGNAME': 'cltbld', 02:47:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:58 INFO - 'MOZ_NO_REMOTE': '1', 02:47:58 INFO - 'NO_EM_RESTART': '1', 02:47:58 INFO - 'PAGER': '/bin/cat', 02:47:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:47:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:58 INFO - 'PWD': '/builds/slave/test', 02:47:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:47:58 INFO - 'SHELL': '/bin/bash', 02:47:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9HdcxFTHdx/Listeners', 02:47:58 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 02:47:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:47:58 INFO - 'USER': 'cltbld', 02:47:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:47:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:47:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:58 INFO - 'XPC_FLAGS': '0x0', 02:47:58 INFO - 'XPC_SERVICE_NAME': '0', 02:47:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:47:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:58 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 02:47:58 INFO - Cleaning up... 02:47:58 INFO - Return code: 0 02:47:58 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:47:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:47:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:47:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109b21a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b31420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd27257e930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099c0e00>, '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 0x109829db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1099bbb20>, '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.RS0Vpt80Di/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.9HdcxFTHdx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 02:47:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 02:47:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 02:47:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RS0Vpt80Di/Render', 02:47:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:47:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:47:58 INFO - 'HOME': '/Users/cltbld', 02:47:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:47:58 INFO - 'LOGNAME': 'cltbld', 02:47:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:58 INFO - 'MOZ_NO_REMOTE': '1', 02:47:58 INFO - 'NO_EM_RESTART': '1', 02:47:58 INFO - 'PAGER': '/bin/cat', 02:47:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:47:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:58 INFO - 'PWD': '/builds/slave/test', 02:47:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:47:58 INFO - 'SHELL': '/bin/bash', 02:47:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9HdcxFTHdx/Listeners', 02:47:58 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 02:47:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:47:58 INFO - 'USER': 'cltbld', 02:47:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:47:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:47:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:58 INFO - 'XPC_FLAGS': '0x0', 02:47:58 INFO - 'XPC_SERVICE_NAME': '0', 02:47:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:47:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:59 INFO - Downloading/unpacking psutil>=0.7.1 02:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:48:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:48:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:48:02 INFO - Installing collected packages: psutil 02:48:02 INFO - Running setup.py install for psutil 02:48:02 INFO - building 'psutil._psutil_osx' extension 02:48:02 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 02:48:02 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 02:48:02 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 02:48:02 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 02:48:03 INFO - building 'psutil._psutil_posix' extension 02:48:03 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 02:48:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:48:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:48:03 INFO - ^ 02:48:03 INFO - 1 warning generated. 02:48:03 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 02:48:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:48:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:48:03 INFO - Successfully installed psutil 02:48:03 INFO - Cleaning up... 02:48:03 INFO - Return code: 0 02:48:03 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:48:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:48:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:03 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 0x109b21a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b31420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd27257e930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099c0e00>, '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 0x109829db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1099bbb20>, '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.RS0Vpt80Di/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.9HdcxFTHdx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 02:48:03 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 02:48:03 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 02:48:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RS0Vpt80Di/Render', 02:48:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:03 INFO - 'HOME': '/Users/cltbld', 02:48:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:03 INFO - 'LOGNAME': 'cltbld', 02:48:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:03 INFO - 'MOZ_NO_REMOTE': '1', 02:48:03 INFO - 'NO_EM_RESTART': '1', 02:48:03 INFO - 'PAGER': '/bin/cat', 02:48:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:03 INFO - 'PWD': '/builds/slave/test', 02:48:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:03 INFO - 'SHELL': '/bin/bash', 02:48:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9HdcxFTHdx/Listeners', 02:48:03 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 02:48:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:03 INFO - 'USER': 'cltbld', 02:48:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:03 INFO - 'XPC_FLAGS': '0x0', 02:48:03 INFO - 'XPC_SERVICE_NAME': '0', 02:48:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:03 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:48: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 02:48: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 02:48: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 02:48: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 02:48: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 02:48: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 02:48:06 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:48:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:48:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:48:06 INFO - Installing collected packages: mozsystemmonitor 02:48:06 INFO - Running setup.py install for mozsystemmonitor 02:48:06 INFO - Successfully installed mozsystemmonitor 02:48:06 INFO - Cleaning up... 02:48:06 INFO - Return code: 0 02:48:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:48:06 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')]} 02:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:06 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')]} 02:48:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:06 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 0x109b21a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b31420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd27257e930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099c0e00>, '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 0x109829db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1099bbb20>, '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.RS0Vpt80Di/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.9HdcxFTHdx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 02:48:06 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 02:48:06 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 02:48:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RS0Vpt80Di/Render', 02:48:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:06 INFO - 'HOME': '/Users/cltbld', 02:48:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:06 INFO - 'LOGNAME': 'cltbld', 02:48:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:06 INFO - 'MOZ_NO_REMOTE': '1', 02:48:06 INFO - 'NO_EM_RESTART': '1', 02:48:06 INFO - 'PAGER': '/bin/cat', 02:48:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:06 INFO - 'PWD': '/builds/slave/test', 02:48:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:06 INFO - 'SHELL': '/bin/bash', 02:48:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9HdcxFTHdx/Listeners', 02:48:06 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 02:48:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:06 INFO - 'USER': 'cltbld', 02:48:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:06 INFO - 'XPC_FLAGS': '0x0', 02:48:06 INFO - 'XPC_SERVICE_NAME': '0', 02:48:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:06 INFO - Downloading/unpacking blobuploader==1.2.4 02:48: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 02:48: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 02:48: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 02:48: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 02:48: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 02:48: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 02:48:09 INFO - Downloading blobuploader-1.2.4.tar.gz 02:48:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:48:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:48:09 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 02:48:09 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 02:48:09 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 02:48:09 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 02:48:09 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 02:48:09 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 02:48:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:48:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:48:09 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 02:48:09 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 02:48:09 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 02:48:09 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 02:48:09 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 02:48:09 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 02:48:10 INFO - Downloading docopt-0.6.1.tar.gz 02:48:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:48:10 INFO - Installing collected packages: blobuploader, requests, docopt 02:48:10 INFO - Running setup.py install for blobuploader 02:48:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:48:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:48:10 INFO - Running setup.py install for requests 02:48:11 INFO - Running setup.py install for docopt 02:48:11 INFO - Successfully installed blobuploader requests docopt 02:48:11 INFO - Cleaning up... 02:48:11 INFO - Return code: 0 02:48:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:48:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:48:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:11 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 0x109b21a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b31420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd27257e930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099c0e00>, '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 0x109829db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1099bbb20>, '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.RS0Vpt80Di/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.9HdcxFTHdx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 02:48:11 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 02:48:11 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 02:48:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RS0Vpt80Di/Render', 02:48:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:11 INFO - 'HOME': '/Users/cltbld', 02:48:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:11 INFO - 'LOGNAME': 'cltbld', 02:48:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:11 INFO - 'MOZ_NO_REMOTE': '1', 02:48:11 INFO - 'NO_EM_RESTART': '1', 02:48:11 INFO - 'PAGER': '/bin/cat', 02:48:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:11 INFO - 'PWD': '/builds/slave/test', 02:48:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:11 INFO - 'SHELL': '/bin/bash', 02:48:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9HdcxFTHdx/Listeners', 02:48:11 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 02:48:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:11 INFO - 'USER': 'cltbld', 02:48:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:11 INFO - 'XPC_FLAGS': '0x0', 02:48:11 INFO - 'XPC_SERVICE_NAME': '0', 02:48:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:48:11 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-j1cNIn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:48:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:48:11 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-Vg3vPa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:48:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:48:11 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-41l9SH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:48:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:48:11 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-5zUHhS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:48:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:48:11 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-VV4E_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:48:12 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-uth0c1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:48:12 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-WeGAeY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:48:12 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-TDlY8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:48:12 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-gRCBRF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:48:12 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-tq9l57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:48:12 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-EoyUX0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:48:12 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-bBxERK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:48:12 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-I8Sh3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:48:12 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-s6w659-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:48:13 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-HXdjP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:48:13 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-HAu5o5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:48:13 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-GpA0GI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:48:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:48:13 INFO - Running setup.py install for manifestparser 02:48:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:48:13 INFO - Running setup.py install for mozcrash 02:48:13 INFO - Running setup.py install for mozdebug 02:48:13 INFO - Running setup.py install for mozdevice 02:48:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:48:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:48:13 INFO - Running setup.py install for mozfile 02:48:14 INFO - Running setup.py install for mozhttpd 02:48:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:48:14 INFO - Running setup.py install for mozinfo 02:48:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:48:14 INFO - Running setup.py install for mozInstall 02:48:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:48:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:48:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:48:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:48:14 INFO - Running setup.py install for mozleak 02:48:14 INFO - Running setup.py install for mozlog 02:48:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:48:14 INFO - Running setup.py install for moznetwork 02:48:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:48:14 INFO - Running setup.py install for mozprocess 02:48:15 INFO - Running setup.py install for mozprofile 02:48:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:48:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:48:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:48:15 INFO - Running setup.py install for mozrunner 02:48:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:48:15 INFO - Running setup.py install for mozscreenshot 02:48:15 INFO - Running setup.py install for moztest 02:48:15 INFO - Running setup.py install for mozversion 02:48:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:48:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:48:15 INFO - Cleaning up... 02:48:15 INFO - Return code: 0 02:48:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:48:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:48:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:48:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:15 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 0x109b21a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b31420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd27257e930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099c0e00>, '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 0x109829db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1099bbb20>, '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.RS0Vpt80Di/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.9HdcxFTHdx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 02:48:15 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 02:48:15 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 02:48:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RS0Vpt80Di/Render', 02:48:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:15 INFO - 'HOME': '/Users/cltbld', 02:48:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:15 INFO - 'LOGNAME': 'cltbld', 02:48:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:15 INFO - 'MOZ_NO_REMOTE': '1', 02:48:15 INFO - 'NO_EM_RESTART': '1', 02:48:15 INFO - 'PAGER': '/bin/cat', 02:48:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:15 INFO - 'PWD': '/builds/slave/test', 02:48:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:15 INFO - 'SHELL': '/bin/bash', 02:48:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9HdcxFTHdx/Listeners', 02:48:15 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 02:48:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:15 INFO - 'USER': 'cltbld', 02:48:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:15 INFO - 'XPC_FLAGS': '0x0', 02:48:15 INFO - 'XPC_SERVICE_NAME': '0', 02:48:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:48:16 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-sDPWBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:48:16 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)) 02:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:48:16 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-bjRFi8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:48:16 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)) 02:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:48:16 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-p8ILQ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:48:16 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)) 02:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:48:16 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-_D57rt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:48:16 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)) 02:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:48:16 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-HVmkOU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:48:16 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)) 02:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:48:16 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-Rtu8le-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:48:16 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)) 02:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:48:16 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-KuCMLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:48:16 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)) 02:48:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:48:16 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-bsMuxQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:48:17 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)) 02:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:48:17 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-Pja5iL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:48:17 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)) 02:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:48:17 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-TwoGwb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:48:17 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)) 02:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:48:17 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-Ne8YxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:48:17 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)) 02:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:48:17 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-CIwPNL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:48:17 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)) 02:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:48:17 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-gfhQuQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:48:17 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)) 02:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:48:17 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-BqT3zw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:48:17 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)) 02:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:48:17 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-Q8Z_f9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:48:17 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)) 02:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:48:17 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-l79U4B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:48:17 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)) 02:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:48:17 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-hYchek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:48:18 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)) 02:48:18 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)) 02:48:18 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)) 02:48:18 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)) 02:48:18 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)) 02:48:18 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)) 02:48:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:48:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:20 INFO - Downloading blessings-1.6.tar.gz 02:48:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:48:20 INFO - Installing collected packages: blessings 02:48:20 INFO - Running setup.py install for blessings 02:48:20 INFO - Successfully installed blessings 02:48:20 INFO - Cleaning up... 02:48:20 INFO - Return code: 0 02:48:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:48:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:48:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:48:21 INFO - Reading from file tmpfile_stdout 02:48:21 INFO - Current package versions: 02:48:21 INFO - blessings == 1.6 02:48:21 INFO - blobuploader == 1.2.4 02:48:21 INFO - docopt == 0.6.1 02:48:21 INFO - manifestparser == 1.1 02:48:21 INFO - mozInstall == 1.12 02:48:21 INFO - mozcrash == 0.17 02:48:21 INFO - mozdebug == 0.1 02:48:21 INFO - mozdevice == 0.48 02:48:21 INFO - mozfile == 1.2 02:48:21 INFO - mozhttpd == 0.7 02:48:21 INFO - mozinfo == 0.9 02:48:21 INFO - mozleak == 0.1 02:48:21 INFO - mozlog == 3.1 02:48:21 INFO - moznetwork == 0.27 02:48:21 INFO - mozprocess == 0.22 02:48:21 INFO - mozprofile == 0.28 02:48:21 INFO - mozrunner == 6.11 02:48:21 INFO - mozscreenshot == 0.1 02:48:21 INFO - mozsystemmonitor == 0.0 02:48:21 INFO - moztest == 0.7 02:48:21 INFO - mozversion == 1.4 02:48:21 INFO - psutil == 3.1.1 02:48:21 INFO - requests == 1.2.3 02:48:21 INFO - wsgiref == 0.1.2 02:48:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:48: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')]} 02:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48: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')]} 02:48:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:21 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 0x109b21a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b31420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd27257e930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099c0e00>, '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 0x109829db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1099bbb20>, '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.RS0Vpt80Di/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.9HdcxFTHdx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 02:48:21 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 02:48:21 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 02:48:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RS0Vpt80Di/Render', 02:48:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:21 INFO - 'HOME': '/Users/cltbld', 02:48:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:21 INFO - 'LOGNAME': 'cltbld', 02:48:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:21 INFO - 'MOZ_NO_REMOTE': '1', 02:48:21 INFO - 'NO_EM_RESTART': '1', 02:48:21 INFO - 'PAGER': '/bin/cat', 02:48:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:21 INFO - 'PWD': '/builds/slave/test', 02:48:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:21 INFO - 'SHELL': '/bin/bash', 02:48:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9HdcxFTHdx/Listeners', 02:48:21 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 02:48:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:21 INFO - 'USER': 'cltbld', 02:48:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:21 INFO - 'XPC_FLAGS': '0x0', 02:48:21 INFO - 'XPC_SERVICE_NAME': '0', 02:48:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:21 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)) 02:48:21 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)) 02:48:21 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)) 02:48:21 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)) 02:48:21 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)) 02:48:21 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)) 02:48:21 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)) 02:48:21 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)) 02:48:21 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)) 02:48:21 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)) 02:48:21 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)) 02:48:21 INFO - Cleaning up... 02:48:21 INFO - Return code: 0 02:48:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:48: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')]} 02:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48: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')]} 02:48:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109b21a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b31420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd27257e930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099c0e00>, '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 0x109829db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1099bbb20>, '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.RS0Vpt80Di/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.9HdcxFTHdx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 02:48: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', 'jsonschema'] in /builds/slave/test/build 02:48: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 jsonschema 02:48:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RS0Vpt80Di/Render', 02:48:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:21 INFO - 'HOME': '/Users/cltbld', 02:48:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:21 INFO - 'LOGNAME': 'cltbld', 02:48:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:21 INFO - 'MOZ_NO_REMOTE': '1', 02:48:21 INFO - 'NO_EM_RESTART': '1', 02:48:21 INFO - 'PAGER': '/bin/cat', 02:48:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:21 INFO - 'PWD': '/builds/slave/test', 02:48:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:21 INFO - 'SHELL': '/bin/bash', 02:48:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9HdcxFTHdx/Listeners', 02:48:21 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 02:48:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:21 INFO - 'USER': 'cltbld', 02:48:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:21 INFO - 'XPC_FLAGS': '0x0', 02:48:21 INFO - 'XPC_SERVICE_NAME': '0', 02:48:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:21 INFO - Downloading/unpacking jsonschema 02:48: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 02:48: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 02:48: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 02:48: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 02:48: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 02:48: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 02:48:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:48:24 INFO - Downloading/unpacking functools32 (from jsonschema) 02:48: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 02:48: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 02:48: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 02:48: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 02:48: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 02:48: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 02:48:24 INFO - Downloading functools32-3.2.3-2.tar.gz 02:48:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:48:24 INFO - warning: no files found matching '*.txt' 02:48:24 INFO - no previously-included directories found matching 'build' 02:48:24 INFO - no previously-included directories found matching 'dist' 02:48:24 INFO - no previously-included directories found matching '.git*' 02:48:24 INFO - Installing collected packages: jsonschema, functools32 02:48:24 INFO - Running setup.py install for functools32 02:48:24 INFO - warning: no files found matching '*.txt' 02:48:24 INFO - no previously-included directories found matching 'build' 02:48:24 INFO - no previously-included directories found matching 'dist' 02:48:24 INFO - no previously-included directories found matching '.git*' 02:48:25 INFO - Successfully installed jsonschema functools32 02:48:25 INFO - Cleaning up... 02:48:25 INFO - Return code: 0 02:48:25 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:48:25 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')]} 02:48:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:25 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')]} 02:48:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:25 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 0x109b21a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b31420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd27257e930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099c0e00>, '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 0x109829db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1099bbb20>, '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.RS0Vpt80Di/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.9HdcxFTHdx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 02:48:25 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 02:48:25 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 02:48:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RS0Vpt80Di/Render', 02:48:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:25 INFO - 'HOME': '/Users/cltbld', 02:48:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:25 INFO - 'LOGNAME': 'cltbld', 02:48:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:25 INFO - 'MOZ_NO_REMOTE': '1', 02:48:25 INFO - 'NO_EM_RESTART': '1', 02:48:25 INFO - 'PAGER': '/bin/cat', 02:48:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:25 INFO - 'PWD': '/builds/slave/test', 02:48:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:25 INFO - 'SHELL': '/bin/bash', 02:48:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9HdcxFTHdx/Listeners', 02:48:25 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 02:48:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:25 INFO - 'USER': 'cltbld', 02:48:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:25 INFO - 'XPC_FLAGS': '0x0', 02:48:25 INFO - 'XPC_SERVICE_NAME': '0', 02:48:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:25 INFO - Downloading/unpacking flake8 02:48:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:27 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:48:27 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:48:27 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 02:48:27 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 02:48:27 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 02:48:27 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 02:48:27 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 02:48:27 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 02:48:28 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:48:28 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:48:28 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 02:48:28 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 02:48:28 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 02:48:28 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 02:48:28 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 02:48:28 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 02:48:28 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:48:28 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 02:48:28 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 02:48:28 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 02:48:28 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 02:48:28 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 02:48:28 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 02:48:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:48:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:48:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:48:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:48:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:48:29 INFO - no previously-included directories found matching 'docs/_build' 02:48:29 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:48:29 INFO - Running setup.py install for pep8 02:48:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:48:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:48:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:48:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:48:29 INFO - no previously-included directories found matching 'docs/_build' 02:48:29 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:48:29 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:48:29 INFO - Cleaning up... 02:48:29 INFO - Return code: 0 02:48:29 INFO - Running post-action listener: _resource_record_post_action 02:48:29 INFO - Running post-action listener: _start_resource_monitoring 02:48:29 INFO - Starting resource monitoring. 02:48:29 INFO - ##### 02:48:29 INFO - ##### Running install step. 02:48:29 INFO - ##### 02:48:29 INFO - Running pre-action listener: _resource_record_pre_action 02:48:29 INFO - Running main action method: install 02:48:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:48:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:48:29 INFO - Reading from file tmpfile_stdout 02:48:29 INFO - Detecting whether we're running mozinstall >=1.0... 02:48:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:48:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:48:29 INFO - Reading from file tmpfile_stdout 02:48:29 INFO - Output received: 02:48:29 INFO - Usage: mozinstall [options] installer 02:48:29 INFO - Options: 02:48:29 INFO - -h, --help show this help message and exit 02:48:29 INFO - -d DEST, --destination=DEST 02:48:29 INFO - Directory to install application into. [default: 02:48:29 INFO - "/builds/slave/test"] 02:48:29 INFO - --app=APP Application being installed. [default: firefox] 02:48:29 INFO - mkdir: /builds/slave/test/build/application 02:48:29 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'] 02:48:29 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 02:49:01 INFO - Reading from file tmpfile_stdout 02:49:01 INFO - Output received: 02:49:01 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:49:01 INFO - Running post-action listener: _resource_record_post_action 02:49:01 INFO - ##### 02:49:01 INFO - ##### Running run-tests step. 02:49:01 INFO - ##### 02:49:01 INFO - Running pre-action listener: _resource_record_pre_action 02:49:01 INFO - Running main action method: run_tests 02:49:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:49:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:49:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:49:01 INFO - 2016-04-27 02:49:01.501 screenresolution[1642:7617] starting screenresolution argv=screenresolution get 02:49:01 INFO - 2016-04-27 02:49:01.504 screenresolution[1642:7617] Display 0: 1600x1200x32@60 02:49:01 INFO - 2016-04-27 02:49:01.509 screenresolution[1643:7619] starting screenresolution argv=screenresolution list 02:49:01 INFO - Available Modes on Display 0 02:49:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:49:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:49:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:49:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:49:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:49:01 INFO - Intel Iris: 02:49:01 INFO - Chipset Model: Intel Iris 02:49:01 INFO - Type: GPU 02:49:01 INFO - Bus: Built-In 02:49:01 INFO - VRAM (Dynamic, Max): 1536 MB 02:49:01 INFO - Vendor: Intel (0x8086) 02:49:01 INFO - Device ID: 0x0a2e 02:49:01 INFO - Revision ID: 0x0009 02:49:01 INFO - Displays: 02:49:01 INFO - Display: 02:49:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:49:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:49:01 INFO - Display Serial Number: 1600x1200 60 02:49:01 INFO - Main Display: Yes 02:49:01 INFO - Mirror: Off 02:49:01 INFO - Online: Yes 02:49:01 INFO - Rotation: Supported 02:49:01 INFO - Return code: 0 02:49:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:49:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:49:01 INFO - Python 2.7.3 02:49:01 INFO - Return code: 0 02:49:01 INFO - grabbing minidump binary from tooltool 02:49:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:49:01 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 0x1099c0e00>, '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 0x109829db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1099bbb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:49:01 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 02:49:01 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 02:49:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:49:01 INFO - Return code: 0 02:49:01 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:49:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:49:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:49:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:49:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:49:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:49:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:49:02 INFO - Return code: 0 02:49:02 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-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RYa29tVaSb6PZcdrKHbVmw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0141', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 02:49:02 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-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RYa29tVaSb6PZcdrKHbVmw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0141 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 02:49:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.RS0Vpt80Di/Render', 02:49:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:49:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:49:02 INFO - 'HOME': '/Users/cltbld', 02:49:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:49:02 INFO - 'LOGNAME': 'cltbld', 02:49:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:49:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:49:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:02 INFO - 'MOZ_NO_REMOTE': '1', 02:49:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:49:02 INFO - 'NO_EM_RESTART': '1', 02:49:02 INFO - 'PAGER': '/bin/cat', 02:49:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:49:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:02 INFO - 'PWD': '/builds/slave/test', 02:49:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:49:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:49:02 INFO - 'SHELL': '/bin/bash', 02:49:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9HdcxFTHdx/Listeners', 02:49:02 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 02:49:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:49:02 INFO - 'USER': 'cltbld', 02:49:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:49:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:49:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:02 INFO - 'XPC_FLAGS': '0x0', 02:49:02 INFO - 'XPC_SERVICE_NAME': '0', 02:49:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:49:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RYa29tVaSb6PZcdrKHbVmw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0141', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 02:49:03 INFO - mozversion application_buildid: 20160427004105 02:49:03 INFO - mozversion application_changeset: 3b60d399d4eef579688a499675c337a252a7bcac 02:49:03 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:49:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:49:03 INFO - mozversion application_name: Firefox 02:49:03 INFO - mozversion application_remotingname: firefox-dev 02:49:03 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:49:03 INFO - mozversion application_vendor: Mozilla 02:49:03 INFO - mozversion application_version: 48.0a2 02:49:03 INFO - mozversion platform_buildid: 20160427004105 02:49:03 INFO - mozversion platform_changeset: 3b60d399d4eef579688a499675c337a252a7bcac 02:49:03 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:49:03 INFO - mozversion platform_version: 48.0a2 02:49:03 INFO - using testdate: 1461750543 02:49:03 INFO - actual date: 1461750543 02:49:03 INFO - starting webserver on 'localhost:49270' 02:49:03 INFO - SUITE-START | Running 2 tests 02:49:03 INFO - TEST-START | dromaeo_css 02:49:03 INFO - operating with platform_type : mac_ 02:49:03 INFO - Initialising browser for dromaeo_css test... 02:49:03 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmpMbsyki/profile http://localhost:49270/getInfo.html) 02:49:06 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 02:49:06 INFO - PROCESS | 1661 | colorDepth:24 02:49:06 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 02:49:06 INFO - PROCESS | 1661 | __metrics 02:49:06 ERROR - PROCESS | 1661 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 02:49:06 INFO - TEST-INFO | 1661: exit 0 02:49:06 INFO - Browser initialized. 02:49:06 INFO - Running cycle 1/1 for dromaeo_css test... 02:49:06 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmpMbsyki/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:49:18 INFO - PROCESS | 1665 | RSS: Main: 221593600 02:49:18 INFO - PROCESS | 1665 | 02:50:08 INFO - PROCESS | 1665 | 1461750608630 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] 02:52:19 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 02:52:20 INFO - PROCESS | 1665 | RSS: Main: 233959424 02:52:20 INFO - PROCESS | 1665 | 02:55:22 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 02:55:23 INFO - PROCESS | 1665 | RSS: Main: 243712000 02:55:23 INFO - PROCESS | 1665 | 02:58:25 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 02:58:25 INFO - PROCESS | 1665 | RSS: Main: 245481472 02:58:25 INFO - PROCESS | 1665 | 03:01:27 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 03:01:28 INFO - PROCESS | 1665 | RSS: Main: 247205888 03:01:28 INFO - PROCESS | 1665 | 03:04:30 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 03:04:31 INFO - PROCESS | 1665 | RSS: Main: 250228736 03:04:31 INFO - PROCESS | 1665 | 03:07:33 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 03:07:34 INFO - PROCESS | 1665 | RSS: Main: 251469824 03:07:34 INFO - PROCESS | 1665 | 03:07:34 INFO - PROCESS | 1665 | __start_tp_report 03:07:34 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 03:07:34 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 03:07:34 INFO - PROCESS | 1665 | |i|pagename|runs| 03:07:34 INFO - PROCESS | 1665 | |0;dojo.html;2952.380000199958;3494.7418929185505;3502.282032183403;3536;3572.4641303804424;4452.821887124511;5678.034734095203;5739.311282646083;5739.655620662753;5759.82720518384;5753.424657534244;5808.767649294034;5825.912611310823;5827.067669172933;5849.795257166;4626.537346265381;4944.357233559632;5191.039657663335;5233.607479439038;5260;5157.7936882524655;5251.133562962125;5285.286486324355;5372.9462705373035;5407.270018547505;4307.849225277116;5251.054810134194;5256.448072952342;5262.473752624726;5271.341082364705;5229.581633469333;5232.450592687789;5237.8428647140645;5326.121190003645;5378.058839703075;5145.228215767627;5232.816851410183;5253.947460525423;5269.235960785703;5317.069512835238;5677.432256774331;5683.318001839806;5685.829425117253;5703.3441154267375;5744.770209191627;6105.206323178003;6119.326874043852;6185.597936134137;6232.252129744415;6285.497160227216;6207.317195108534;6262.091996660459;6277.246730392382;6294.590851594632;6301.464375528086;5882.382349853289;5903.616264942765;5908.911366329508;5926.111083337448;5926.259217597843;6230.844228894314;6291.993281075006;6331.113644089831;6353.872922541524;6359.904601430982;4788.281757736368;5077.949220507748;6181.81454556355;6227.06594010893;6272.749090036347;6113.938860611337;6263.279722831841;6267.4045965633195;6277.623342599459;6295.087212354183;6107.694615269219;6187.319394866561;6241.750329986749;6241.938870392044;6281.120643109968;5764.501229680313;5947.256592925884;6159.384061593805;6169.5064394848305;6208.6096852711125;4225.3873188387515;4813.879653008703;4897.1674815281485;4913.263010548446;4922.556969872729;4194.979025104855;4356.215881141425;4395.516493185747;4464.308032255006;4481.498697936163;4719.433667959798;5735.598508104393;5778.191053252548;5800.564957628195;5831.79588714393;6232.189815323979;6263.154474145932;6269.498440124779;6269.498440124779;6295.401936815995;5640.746166422521;5642.492175704124;5745.052066409079;5767.769289228384;5844.707764611752;5387.46125387466;5391;5417.295751552273;5419.945800542023;5431.918521222185;5277.762500687479;6322.0516922462;6337.7781777637565;6338.619682819046;6366;5219.556337711337;6116.518381401779;6141.977005061692;6225.3774622537385;6274.968625156845;6388.329225431263;6391.041343798467;6395.104685343963;6403.455706264834;6487.253965793994;6188.3502232266765;6239.563230573816;6275.058741188858;6290.654014029273;6290.968545157245;6153.569250152446;6200.224971878515;6238.376162383907;6243.531735119794;6254.8123556293385;5178.741062946913;5725.799597014251;6157.199564056644;6186.690665466619;6221.564490485623;6035.69821508914;6172.444479996821;6176.567640265138;6185.536084793568;6202.782902598387;4763.976180119077;5978.8206353809455;5999.250093738283;6023.488003519575;6086.391360863879;6112.113743507255;6113.938860611337;6130.264368275835;6145;6165.660888651168;5748.080307150836;5926.081457374114;5990;6028.065649824284;6028.668423236764 03:07:34 INFO - PROCESS | 1665 | |1;ext.html;517.5652451940372;538.5583821266562;564.6460749381566;580.8518827698936;595.9213822980405;38965.61034389655;40317.79841100798;40318.798406008005;40333.1933361333;40562.18875622486;43523.3471497927;44149;44475.77762111185;44525.77371131447;44699.55300447003;9602.08780165883;9826.656067037655;9832.26258030647;9865.556049977733;9886.258530610197;9770;9861.06319899612;9863.161631261315;9901.752456188617;9913.256505762096;9851.261155413336;9881.16010139139;9902;9909.851352229687;9963.850542241873;9790;9828.262880284008;9866.65466708669;9882.80234395308;9920.751981200528;9618.470984095871;9714.90285097147;9722.270829687732;9763.90236097637;9774.413535187912;39309.80345098285;39469;39688.80155599204;39761.80119099415;40241.59758402408;3144.261098641817;3342.4819153031317;3476.7392445566684;3659.743817932746;3872.1287710265246;27866;27977.860110699316;28797.712022879714;29625.851870740724;30337.544936825965;2804.6353973983455;2807.2560771395597;2841.849051134294;2848.1313199474152;2883.8413887236193;469412;470409;471586;473162;474228;34199.658003419645;35188.47217291692;64578.67710661417;64867;65013.67493162504;32978.17554561155;34036;34749.47875781865;35054.824725876206;35108.29783404369;13531.797023044663;15773.921130394274;15803;15808;15829.050256984618;12873.970082393385;13075.476980920657;13221.338933053501;13289.534866279633;13328.200307981358;7476.028116344841;7912.208779122157;8185.672573097009;8299.875501867476;8456.577171141542;3427.9828600856836;3445.4831775233415;3458.878939237165;3462.532558104674;3489.842957066938;1001.2190491416707;1050;1058.9841152382721;1089.5042755546208;1149.5172027748365;30693.23266918345;30745.538816917317;30824.075277741704;30956.535651965238;31311.21721956969;1091.6779550032759;1099.9505022274018;1134.5348407153028;1154.099802154321;1179.4515550269166;12944.805827912589;15978;16044.75932861008;16096.839031609768;16133.596660083593;629860;633347;634721;645171;645415;61992.69003655043;62761.68619156875;62869.685651571446;62930.68534657389;62976;1465.3259500629829;1498.730228558848;1507.2463768115942;1512.1154124836874;1522.1704171226709;30357.544636830906;30646.08061758151;30661.846690766404;30818.229544260677;30893.53659695151;55188.44811551833;55236.44763552474;55575.444245557024;55616.72191639016;55848.44151558432;603207;611244;611892;613584;614693;27332.316692082855;27627.86186069057;27948.580771288853;28028.71971280261;28300.292492687848;47878;47922.76038619784;48700.75649621729;49449.75275123745;49565;37626.43560346542;38255.04362390851;39687.60312396839;40636.59363406328;40689.1862162761;38833.41749873698;39252.214955700154;39476.80261598674;40083.198336033696;40365;37888.242235155696;38658.42012369761;39132.413013804246;39249.215015700094;39325.60674393334;31491;34083.65916340805;34571.96284111481;34804;34827.82586087054;21881;25629;25736.356591085372;26003.82982765818;26196.21411357662 03:07:34 INFO - PROCESS | 1665 | |2;jquery.html;348.47206482179496;395.23521984959126;401.392666526684;402.3542214745333;407.8674430809987;14137.57587272381;19666.90166549167;20504.974751262434;20560;21107;20183.09176087076;20584.279550215746;20963.16147354104;21147.154113835466;21180.682289765668;5946.256717910262;6004.549658775588;6103.938960610381;6209;6234.033724772645;5141.408737995141;6095.268567771874;6151.292601350857;6165.383461653843;6196.504279657639;5942.673152976585;6123.142760013601;6160.969195154041;6181.0110382338835;6189.876202475948;5262.684238945656;6038.728257228435;6051.546134039942;6117.66352850593;6129.049997250433;5359.705216213107;5369.48989845964;5371;5372.221027950925;5385.919211211835;26189.73810261911;26682.5997610036;27002.864985675144;27562.862185689144;27586.724132758816;2179.052112331141;2540.0982651158756;2613.3074735195187;2624.173385383617;2913.6066631004755;14637.341319640642;15021.248937553079;15183.240837958057;15213.61965950849;15688;1709.7093494106032;1732.8527075198624;1737.5569229846308;1775.6715007723612;1858.60040091381;89551.20897582012;113376.43311783388;113800;133792;134580;11389.430528473544;11742.060635149168;11923.284602923852;11969.581064662694;12027.45876435545;14901.925490372696;15071.321790519452;15149.697006059818;15213.467528636445;15222.695546089017;8947;9022.819543609092;9103.271738260923;9169.037251088674;9247.72256832296;6885.724571017204;9084.727458176265;9089.77275568116;9144.634214631473;9179.541022948826;4659;4773.427188737373;4775.641826863;4801.759912004386;4847.127517046895;1993.9302124425576;1999.6800511918022;2032.1972820735728;2078.210280093555;2085.9269925552835;819.7663665855202;891.9955400222957;922.7877588154762;930.3348106104168;976.3165783951263;17482.475525733993;19813.504662383268;20336.796632033787;20914.790852091286;21064.368068957956;932.7761337279004;960.6349587156835;984.8473486609444;992.965246216379;1006.5269323417982;10790.67627971162;11076;11092.909426548076;11166.497507612177;11190.608328708619;593417;603343;614188;614487;616098;81179.18820811834;117038;122308.38845805892;129745;139399.6060039407;871.7471933139318;939.196986576481;974.6004138303404;976.0678551982982;1083.2742062817774;18299.908500457947;18467.81532184661;18498.16758245909;18654.626907462047;18716.906415467834;24263.514729704955;38724.41913371245;50886.74556627193;51337;52517;60338.69830650819;70211;73231;73532.52934941379;73910;7314.780556583311;8031.393721255889;8070.717524886601;8324.875126872981;8656.48061116335;9419;11703.2978021319;12510.87489125097;12812.55156069533;12896.548620798227;5512;7848.411369147223;11031.503582338653;11079.83380249313;11311.095112390823;10769.569217231223;10862.891371086187;11042.171837112088;11110.666679999613;11233.606823761129;6917.308269173042;9633.759156021155;11099.278546894426;11359.261647992855;11899.167058305835;8430.45202061864;8455.408121431441;8526;8607.741767746978;8636.26591739684;10657.20070994663;11404.885951140714;11405.828912566483;11637.243579167327;11729.35488548138 03:07:34 INFO - PROCESS | 1665 | |3;mootools.html;1171.6836454157378;1176.8293597428374;1208.3897631695993;1528.7248295306847;1584.833592472791;7814.531128132309;8058.5970701464985;8115.14790946951;8118.715844945428;8653.524056176908;8721.34589905758;8805.95597022014;8807.735767926957;8819.867701984474;8845.469271843695;2457.840240384374;2482.7020757509126;2488.1167185649106;2491;2491.20281509916;2427.8057755379527;2480.9255722328267;2482.987585062072;2485.2047344849657;2488.4401009772746;2433.1605596069326;2441.890114944823;2509.623556466526;2510.57320255557;2511.7739403453684;2460.692413448319;2470.789982851451;2481.677381940345;2489.2158969924417;2494.6133349330703;2460.2865169100937;2465.790407815338;2480.516299321633;2486.3411196033067;2486.9751302486925;8885.200331970089;8964.8655270171;8969.372143949926;8972.910270897339;8985.46087234768;5319.813806516792;5521.089020311644;5525.723713814334;5551.639143455663;5556.638818476786;3577.172425504711;3585.569731632195;3586.9282614347567;3644.8177591120602;3667.688246499051;1025.892281310459;1034.1313296830624;1046.4140081554353;1048.816457120001;1067.8505009298703;11790.410479475991;11803.940980295129;11927.94036029823;11940;11993.880061199363;4933.136701077369;4955.6531042826655;5055.368078990126;5060.620453466005;5085.059264036166;10185;10207.897921020844;10235.48822558884;10238.33450825694;10251.077403033763;5462.617616766788;5479.369872464637;5532.917006244909;5535.391106978229;5554.638948468337;4482.170798402306;4850.296706977469;4968.22992436173;5005.34930459038;5030.84907452777;4133.607307305801;4140.979295103505;4174.3947127666315;4198.475190601175;4245.724027938174;1162.976740465186;1172.6364826903687;1183.260462211118;1353.6954185308227;1391.4851504943235;748.6631016042802;939.3049143633798;954.9140577348072;959.3907868503411;964.2237998411347;5960.195373624592;5968.850778730566;6125.387461253839;6211.534134939898;6255.906161407583;980.9411435313904;1022.5091955861228;1046.3774054437597;1065.898739619735;1090.2422816142864;3336.4995250712586;3498.3353162898966;3970.900727481836;4009.6190861868076;4163.2298024865495;10219.7445063874;10344.741381465523;10693.518791654395;10775.892241077645;10784.892151078546;10675.46622668879;10704.625338113128;10708.03627673513;10768.976947190004;10789.622363217406;8003.359731221488;8056.033276006917;8190.058143313595;8283.58582070894;8284.958575207205;5945.286565612154;5993.760249590142;5994.250718660168;6111.113888486056;6217.378262173746;9774.511274436392;9950.402975821184;10112.646057387956;10208.642697505551;10220.897791021995;9998.700038998843;10010.899891000996;10065;10251.179905607683;10342.5345859435;6968;7604.885926710993;7723.304902558797;8065.758027259191;8419.158084191531;8396.496210227406;8447;8484.19400156984;8507.532085735344;8513.021002584534;6320.715567799368;6475.838104047437;7369.77890663281;7602.885956710765;7633.931249624946;6506.707198175999;7444.627768611656;7446;7472.215417381314;7473.439492038227;7011.614361209979;7123.608201548965;7149.571025738473;7149.678264478007;7172.0676312079095;4669.60308373792;4712.363830882787;4954.727489988085;4995;5114.181730923035;3859.6140385962303;4957.677750946177;5095;5112.923306150336;5353.812616558402 03:07:34 INFO - PROCESS | 1665 | |4;prototype.html;304.3426199409276;325.042749103889;329.31831109603115;333.42983498218047;334.64861895010233;5062.8228012019545;5333.959643003484;5456.945430545693;5500.752466139023;5532.612717109804;4359.738415695053;5080.415752188499;5372.788395256604;5415.539679127279;5481.5340696040785;3997.9800100999455;4111.773852438115;4184.456020717303;4193.140406216734;4208.705390622657;3329.433996220648;4145.378193271018;4215.241256573816;4249.936250956238;4277.807498662568;4153.833846646131;4185.183889141618;4196.601322874338;4215.367694845782;4215.662746980234;3576.03318175004;3892.936743525936;4173.812178451962;4193.182329445759;4263.4031235627035;3385.2213990782257;3616.72874534411;3619.3847046002243;3641.4509843109927;3665.4135338346005;3971.4042893565907;4707.7175369477945;5174.2238664200295;5222.92165617516;5227.823739658585;3700.666939975415;4244.363345498169;4701.871550827811;4836.443808961979;4866.58634016109;5028.698278103289;5114.514121158484;5779.797707080232;5805.883882322372;5806.025951176578;4052.230076285496;4059.939100913489;4080.7143499955014;4088.693347998882;4099.795010249476;5296.92054619181;5612.803551875706;5781.942180578182;6070.514358851281;6114.266288045463;3615.2769446110883;4103.317639767681;4104.240715467648;4155.168966206771;4171.895702607399;4197.286461301586;4200.15996800635;4202.453681021509;4215.78921053946;4232.343986682007;4604.746738929324;4699.154152252559;4782.665213435026;4842.443119041355;4868.88015756378;5441.395362776347;5472.370677372073;5561.360443549043;5824.3302020267365;5830.62100963436;1345.8721421464945;1375.7661197596433;1446.797448357231;1486.9925650371895;1542.1672296959516;925.8194652042922;954.1317401164906;1004.6082028009082;1029.1869423155624;1041.677070120942;4820.783064762094;5856.531477481792;5868.23712917318;5879.2944846618675;5924.14099955504;6459.418652321219;6489.351064893567;6543.247526534415;6546.70539825709;6585.209774827052;5218.843434696965;5441.591880608891;5508.944910550923;5518.420565840609;5534.727012787082;3079.291762894502;3463.5843698755807;3473.635268296843;3484.9303013940084;3489.546358973317;6602.3397660233595;6889.483288753364;6899.93100069003;6925.58446493212;6929.896051559231;6330.7338532293725;6598.307177746267;6630.370114839083;6771.729130834711;6826.214985276657;5694.800681976111;6320.399562041599;6710.228323742735;6884.586924784529;6936.34104760047;5824.679642619527;5826.300843898759;5876.3536011039605;6203.2245969253845;6568.507361947969;6116.143739876332;6291.427143214197;6390.872182556416;6430.324815894451;6505.577137486048;5530.861728456821;6305.432511074026;6647.169103862017;6838.93996430554;6923.6192009439965;3712.997490677448;4186.476690413699;4217.114405974779;4292.205941900758;4300.827966881289;4743.976280118577;5747.626404283708;6086.756529738761;6579.80260592183;6595.373439523238;4925.950740492549;4976.954839483749;5048.015636950906;5696.344920334214;5861.267341582302;4467.508574056917;5821.301443826768;5847.619904706181;5848.736806843617;5891.970540147272;5342.519173274424;5461.945380546303;5533.699580598475;5684.346300175367;5762.567807414544;4618.953810461852;5180.896382072262;5366.597505187048;5784.363719990882;5862.94137058624;5071.54356107952;5593.356763972195;5774.653520788766;5798.420157984167;5831.387704291158 03:07:34 INFO - PROCESS | 1665 | |5;yui.html;594.3729365519376;735.7461675721875;747.7756672998099;749.2320371619093;764.1899586438376;14591.854081459196;14797.778033329496;14824;14921.4777482788;14966.251687415614;12567.308798016105;13692.452301907912;13732.450701971935;13772.311384430788;14942.850571494282;896.9013408525057;960.0783248081815;963.8746962894813;966.3766870368643;970.5438443931376;669.7622344067859;920.6869664314132;934.7849994501235;939.7791518993065;943.2124176312788;947.2800671489683;1002.5688953749884;1005.3967619428321;1010.4089107871904;1011.4386515483903;963.6434519227895;984.4585477987114;991.0436428846198;997.8652881860964;1000.2698030437748;737.9372753315921;744.7058411927312;746.9141048779405;749.1197842535054;749.62144117221;13998.930005349908;14071.71856562863;14149.151050936875;14153.150810951272;14157.85842141586;12019.51921923122;12132;12204.572839950559;12232.510699572007;12471.189372690746;7458.477906546544;10120.392776433437;10156.593736250541;10165.644202452879;10406;3445.3970555152746;3480.6519348065244;3491.6508349165133;3493.109257139439;3568.2506673598314;65202;67400.9999999995;67576;67592.66203668999;73962.26037739607;8306.792330191673;9338.906610933804;9457.43255404678;9544.809103817885;9634.03659634031;16760.832391676173;20178.697319540217;20310.08604612796;20379.388618341472;20425.69361459579;6255.624662520263;7220.880763481564;7392.926070739224;7524.05949256343;7627.961860190663;3302.438585440481;3497.510348551205;3917.764934103963;3988.541317748438;4010.939835902464;3049.4663433898977;3493.475978603179;3556.928861422757;3570.821458927095;3577.4454959481322;521.9951593183107;613.2885852411271;642.996785016072;665.364576829127;667.1960287853223;524.2634099090784;572.4275724275724;590.8581940334288;591.8342863998088;600.7747094839435;6423.96788016057;6744.123263975652;7148.106486689164;8140.959295203486;8212.78535193406;577.9913301300484;601.6781567039528;609.7073404765737;610.3896103896104;620.7144713431871;3646.58064322601;3841.615838416136;3888.4556162137324;3917.4319723639915;4015.3374693175297;98366;98412.01587984028;98977.50511247398;100318;101068;78259.60870195612;78364.82452763212;78535.60732196417;78576.60711696405;79780.39074888531;70969.6451517739;71758.64120679363;71940.64029679923;72447.63776181085;72823.63588182026;6120.54095942815;7108.218096009331;7137.929310603451;7417.258274172539;7615.885761713468;34718;44559.88600285019;44950.87622809456;45077.77461112804;45860.08279834451;62357;62417;62628.68685656542;62715.68642156942;63164;9667.371620844622;9904.851427228454;9985;10018.398896065968;10024.448655324028;10807.891921081004;10952;11863.525458981889;11987.160898737353;12014.219075760047;10845.566177352817;10849.67450976472;10902.727431814268;10937.890621093688;11185.161112916727;10807.35155890649;10968.616098436516;11102.056325212125;11383.317000979967;11995.340256285988;9156.450612963243;10828.837567436338;10844.45777711127;11079.113670906469;11086.445677716243;9374.531273436438;9380.718578442658;9398.248140148908;9715.659951901649;9726.902730972883;9486.810263794823;9542.856857147288;9648.469334186688;9675.516224188621;9742.61029558838 03:07:34 INFO - PROCESS | 1665 | __end_tp_report 03:07:34 INFO - PROCESS | 1665 | __start_cc_report 03:07:34 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,421 03:07:34 INFO - PROCESS | 1665 | __end_cc_report 03:07:34 INFO - PROCESS | 1665 | __startTimestamp1461751654022__endTimestamp 03:07:34 INFO - PROCESS | 1665 | 03:07:34 INFO - PROCESS | 1665 | ------- Summary: start ------- 03:07:34 INFO - PROCESS | 1665 | Number of tests: 6 03:07:34 INFO - PROCESS | 1665 | 03:07:34 INFO - PROCESS | 1665 | [#0] dojo.html Cycles:165 Average:5704.49 Median:5926.08 stddev:682.39 (11.5%) stddev-sans-first:649.44 03:07:34 INFO - PROCESS | 1665 | Values: 2952.4 3494.7 3502.3 3536.0 3572.5 4452.8 5678.0 5739.3 5739.7 5759.8 5753.4 5808.8 5825.9 5827.1 5849.8 4626.5 4944.4 5191.0 5233.6 5260.0 5157.8 5251.1 5285.3 5372.9 5407.3 4307.8 5251.1 5256.4 5262.5 5271.3 5229.6 5232.5 5237.8 5326.1 5378.1 5145.2 5232.8 5253.9 5269.2 5317.1 5677.4 5683.3 5685.8 5703.3 5744.8 6105.2 6119.3 6185.6 6232.3 6285.5 6207.3 6262.1 6277.2 6294.6 6301.5 5882.4 5903.6 5908.9 5926.1 5926.3 6230.8 6292.0 6331.1 6353.9 6359.9 4788.3 5077.9 6181.8 6227.1 6272.7 6113.9 6263.3 6267.4 6277.6 6295.1 6107.7 6187.3 6241.8 6241.9 6281.1 5764.5 5947.3 6159.4 6169.5 6208.6 4225.4 4813.9 4897.2 4913.3 4922.6 4195.0 4356.2 4395.5 4464.3 4481.5 4719.4 5735.6 5778.2 5800.6 5831.8 6232.2 6263.2 6269.5 6269.5 6295.4 5640.7 5642.5 5745.1 5767.8 5844.7 5387.5 5391.0 5417.3 5419.9 5431.9 5277.8 6322.1 6337.8 6338.6 6366.0 5219.6 6116.5 6142.0 6225.4 6275.0 6388.3 6391.0 6395.1 6403.5 6487.3 6188.4 6239.6 6275.1 6290.7 6291.0 6153.6 6200.2 6238.4 6243.5 6254.8 5178.7 5725.8 6157.2 6186.7 6221.6 6035.7 6172.4 6176.6 6185.5 6202.8 4764.0 5978.8 5999.3 6023.5 6086.4 6112.1 6113.9 6130.3 6145.0 6165.7 5748.1 5926.1 5990.0 6028.1 6028.7 03:07:34 INFO - PROCESS | 1665 | 03:07:34 INFO - PROCESS | 1665 | [#1] ext.html Cycles:180 Average:69693.04 Median:39281.01 stddev:154768.68 (394.0%) stddev-sans-first:155115.69 03:07:34 INFO - PROCESS | 1665 | Values: 517.6 538.6 564.6 580.9 595.9 38965.6 40317.8 40318.8 40333.2 40562.2 43523.3 44149.0 44475.8 44525.8 44699.6 9602.1 9826.7 9832.3 9865.6 9886.3 9770.0 9861.1 9863.2 9901.8 9913.3 9851.3 9881.2 9902.0 9909.9 9963.9 9790.0 9828.3 9866.7 9882.8 9920.8 9618.5 9714.9 9722.3 9763.9 9774.4 39309.8 39469.0 39688.8 39761.8 40241.6 3144.3 3342.5 3476.7 3659.7 3872.1 27866.0 27977.9 28797.7 29625.9 30337.5 2804.6 2807.3 2841.8 2848.1 2883.8 469412.0 470409.0 471586.0 473162.0 474228.0 34199.7 35188.5 64578.7 64867.0 65013.7 32978.2 34036.0 34749.5 35054.8 35108.3 13531.8 15773.9 15803.0 15808.0 15829.1 12874.0 13075.5 13221.3 13289.5 13328.2 7476.0 7912.2 8185.7 8299.9 8456.6 3428.0 3445.5 3458.9 3462.5 3489.8 1001.2 1050.0 1059.0 1089.5 1149.5 30693.2 30745.5 30824.1 30956.5 31311.2 1091.7 1100.0 1134.5 1154.1 1179.5 12944.8 15978.0 16044.8 16096.8 16133.6 629860.0 633347.0 634721.0 645171.0 645415.0 61992.7 62761.7 62869.7 62930.7 62976.0 1465.3 1498.7 1507.2 1512.1 1522.2 30357.5 30646.1 30661.8 30818.2 30893.5 55188.4 55236.4 55575.4 55616.7 55848.4 603207.0 611244.0 611892.0 613584.0 614693.0 27332.3 27627.9 27948.6 28028.7 28300.3 47878.0 47922.8 48700.8 49449.8 49565.0 37626.4 38255.0 39687.6 40636.6 40689.2 38833.4 39252.2 39476.8 40083.2 40365.0 37888.2 38658.4 39132.4 39249.2 39325.6 31491.0 34083.7 34572.0 34804.0 34827.8 21881.0 25629.0 25736.4 26003.8 26196.2 03:07:34 INFO - PROCESS | 1665 | 03:07:34 INFO - PROCESS | 1665 | [#2] jquery.html Cycles:180 Average:34657.25 Median:1631.04 stddev:101141.61 (6201.1%) stddev-sans-first:101392.53 03:07:34 INFO - PROCESS | 1665 | Values: 348.5 395.2 401.4 402.4 407.9 14137.6 19666.9 20505.0 20560.0 21107.0 20183.1 20584.3 20963.2 21147.2 21180.7 5946.3 6004.5 6103.9 6209.0 6234.0 5141.4 6095.3 6151.3 6165.4 6196.5 5942.7 6123.1 6161.0 6181.0 6189.9 5262.7 6038.7 6051.5 6117.7 6129.0 5359.7 5369.5 5371.0 5372.2 5385.9 26189.7 26682.6 27002.9 27562.9 27586.7 2179.1 2540.1 2613.3 2624.2 2913.6 14637.3 15021.2 15183.2 15213.6 15688.0 1709.7 1732.9 1737.6 1775.7 1858.6 89551.2 113376.4 113800.0 133792.0 134580.0 11389.4 11742.1 11923.3 11969.6 12027.5 14901.9 15071.3 15149.7 15213.5 15222.7 8947.0 9022.8 9103.3 9169.0 9247.7 6885.7 9084.7 9089.8 9144.6 9179.5 4659.0 4773.4 4775.6 4801.8 4847.1 1993.9 1999.7 2032.2 2078.2 2085.9 819.8 892.0 922.8 930.3 976.3 17482.5 19813.5 20336.8 20914.8 21064.4 932.8 960.6 984.8 993.0 1006.5 10790.7 11076.0 11092.9 11166.5 11190.6 593417.0 603343.0 614188.0 614487.0 616098.0 81179.2 117038.0 122308.4 129745.0 139399.6 871.7 939.2 974.6 976.1 1083.3 18299.9 18467.8 18498.2 18654.6 18716.9 24263.5 38724.4 50886.7 51337.0 52517.0 60338.7 70211.0 73231.0 73532.5 73910.0 7314.8 8031.4 8070.7 8324.9 8656.5 9419.0 11703.3 12510.9 12812.6 12896.5 5512.0 7848.4 11031.5 11079.8 11311.1 10769.6 10862.9 11042.2 11110.7 11233.6 6917.3 9633.8 11099.3 11359.3 11899.2 8430.5 8455.4 8526.0 8607.7 8636.3 10657.2 11404.9 11405.8 11637.2 11729.4 03:07:34 INFO - PROCESS | 1665 | 03:07:34 INFO - PROCESS | 1665 | [#3] mootools.html Cycles:180 Average:5770.63 Median:4891.72 stddev:3207.81 (65.6%) stddev-sans-first:3198.19 03:07:34 INFO - PROCESS | 1665 | Values: 1171.7 1176.8 1208.4 1528.7 1584.8 7814.5 8058.6 8115.1 8118.7 8653.5 8721.3 8806.0 8807.7 8819.9 8845.5 2457.8 2482.7 2488.1 2491.0 2491.2 2427.8 2480.9 2483.0 2485.2 2488.4 2433.2 2441.9 2509.6 2510.6 2511.8 2460.7 2470.8 2481.7 2489.2 2494.6 2460.3 2465.8 2480.5 2486.3 2487.0 8885.2 8964.9 8969.4 8972.9 8985.5 5319.8 5521.1 5525.7 5551.6 5556.6 3577.2 3585.6 3586.9 3644.8 3667.7 1025.9 1034.1 1046.4 1048.8 1067.9 11790.4 11803.9 11927.9 11940.0 11993.9 4933.1 4955.7 5055.4 5060.6 5085.1 10185.0 10207.9 10235.5 10238.3 10251.1 5462.6 5479.4 5532.9 5535.4 5554.6 4482.2 4850.3 4968.2 5005.3 5030.8 4133.6 4141.0 4174.4 4198.5 4245.7 1163.0 1172.6 1183.3 1353.7 1391.5 748.7 939.3 954.9 959.4 964.2 5960.2 5968.9 6125.4 6211.5 6255.9 980.9 1022.5 1046.4 1065.9 1090.2 3336.5 3498.3 3970.9 4009.6 4163.2 10219.7 10344.7 10693.5 10775.9 10784.9 10675.5 10704.6 10708.0 10769.0 10789.6 8003.4 8056.0 8190.1 8283.6 8285.0 5945.3 5993.8 5994.3 6111.1 6217.4 9774.5 9950.4 10112.6 10208.6 10220.9 9998.7 10010.9 10065.0 10251.2 10342.5 6968.0 7604.9 7723.3 8065.8 8419.2 8396.5 8447.0 8484.2 8507.5 8513.0 6320.7 6475.8 7369.8 7602.9 7633.9 6506.7 7444.6 7446.0 7472.2 7473.4 7011.6 7123.6 7149.6 7149.7 7172.1 4669.6 4712.4 4954.7 4995.0 5114.2 3859.6 4957.7 5095.0 5112.9 5353.8 03:07:34 INFO - PROCESS | 1665 | 03:07:34 INFO - PROCESS | 1665 | [#4] prototype.html Cycles:180 Average:4832.17 Median:5220.88 stddev:1533.01 (29.4%) stddev-sans-first:1499.17 03:07:34 INFO - PROCESS | 1665 | Values: 304.3 325.0 329.3 333.4 334.6 5062.8 5334.0 5456.9 5500.8 5532.6 4359.7 5080.4 5372.8 5415.5 5481.5 3998.0 4111.8 4184.5 4193.1 4208.7 3329.4 4145.4 4215.2 4249.9 4277.8 4153.8 4185.2 4196.6 4215.4 4215.7 3576.0 3892.9 4173.8 4193.2 4263.4 3385.2 3616.7 3619.4 3641.5 3665.4 3971.4 4707.7 5174.2 5222.9 5227.8 3700.7 4244.4 4701.9 4836.4 4866.6 5028.7 5114.5 5779.8 5805.9 5806.0 4052.2 4059.9 4080.7 4088.7 4099.8 5296.9 5612.8 5781.9 6070.5 6114.3 3615.3 4103.3 4104.2 4155.2 4171.9 4197.3 4200.2 4202.5 4215.8 4232.3 4604.7 4699.2 4782.7 4842.4 4868.9 5441.4 5472.4 5561.4 5824.3 5830.6 1345.9 1375.8 1446.8 1487.0 1542.2 925.8 954.1 1004.6 1029.2 1041.7 4820.8 5856.5 5868.2 5879.3 5924.1 6459.4 6489.4 6543.2 6546.7 6585.2 5218.8 5441.6 5508.9 5518.4 5534.7 3079.3 3463.6 3473.6 3484.9 3489.5 6602.3 6889.5 6899.9 6925.6 6929.9 6330.7 6598.3 6630.4 6771.7 6826.2 5694.8 6320.4 6710.2 6884.6 6936.3 5824.7 5826.3 5876.4 6203.2 6568.5 6116.1 6291.4 6390.9 6430.3 6505.6 5530.9 6305.4 6647.2 6838.9 6923.6 3713.0 4186.5 4217.1 4292.2 4300.8 4744.0 5747.6 6086.8 6579.8 6595.4 4926.0 4977.0 5048.0 5696.3 5861.3 4467.5 5821.3 5847.6 5848.7 5892.0 5342.5 5461.9 5533.7 5684.3 5762.6 4619.0 5180.9 5366.6 5784.4 5862.9 5071.5 5593.4 5774.7 5798.4 5831.4 03:07:34 INFO - PROCESS | 1665 | 03:07:34 INFO - PROCESS | 1665 | [#5] yui.html Cycles:180 Average:17616.51 Median:605.69 stddev:25281.44 (4174.0%) stddev-sans-first:25320.05 03:07:34 INFO - PROCESS | 1665 | Values: 594.4 735.7 747.8 749.2 764.2 14591.9 14797.8 14824.0 14921.5 14966.3 12567.3 13692.5 13732.5 13772.3 14942.9 896.9 960.1 963.9 966.4 970.5 669.8 920.7 934.8 939.8 943.2 947.3 1002.6 1005.4 1010.4 1011.4 963.6 984.5 991.0 997.9 1000.3 737.9 744.7 746.9 749.1 749.6 13998.9 14071.7 14149.2 14153.2 14157.9 12019.5 12132.0 12204.6 12232.5 12471.2 7458.5 10120.4 10156.6 10165.6 10406.0 3445.4 3480.7 3491.7 3493.1 3568.3 65202.0 67401.0 67576.0 67592.7 73962.3 8306.8 9338.9 9457.4 9544.8 9634.0 16760.8 20178.7 20310.1 20379.4 20425.7 6255.6 7220.9 7392.9 7524.1 7628.0 3302.4 3497.5 3917.8 3988.5 4010.9 3049.5 3493.5 3556.9 3570.8 3577.4 522.0 613.3 643.0 665.4 667.2 524.3 572.4 590.9 591.8 600.8 6424.0 6744.1 7148.1 8141.0 8212.8 578.0 601.7 609.7 610.4 620.7 3646.6 3841.6 3888.5 3917.4 4015.3 98366.0 98412.0 98977.5 100318.0 101068.0 78259.6 78364.8 78535.6 78576.6 79780.4 70969.6 71758.6 71940.6 72447.6 72823.6 6120.5 7108.2 7137.9 7417.3 7615.9 34718.0 44559.9 44950.9 45077.8 45860.1 62357.0 62417.0 62628.7 62715.7 63164.0 9667.4 9904.9 9985.0 10018.4 10024.4 10807.9 10952.0 11863.5 11987.2 12014.2 10845.6 10849.7 10902.7 10937.9 11185.2 10807.4 10968.6 11102.1 11383.3 11995.3 9156.5 10828.8 10844.5 11079.1 11086.4 9374.5 9380.7 9398.2 9715.7 9726.9 9486.8 9542.9 9648.5 9675.5 9742.6 03:07:34 INFO - PROCESS | 1665 | -------- Summary: end -------- 03:07:34 INFO - PROCESS | 1665 | 03:07:34 INFO - TEST-INFO | 1665: exit 0 03:07:34 INFO - TEST-OK | dromaeo_css | took 1111457ms 03:07:34 INFO - TEST-START | kraken 03:07:34 INFO - operating with platform_type : mac_ 03:07:34 INFO - Initialising browser for kraken test... 03:07:34 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmpyPOOh8/profile http://localhost:49270/getInfo.html) 03:07:37 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 03:07:37 INFO - PROCESS | 1673 | colorDepth:24 03:07:37 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 03:07:37 INFO - PROCESS | 1673 | __metrics 03:07:37 ERROR - PROCESS | 1673 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 03:07:38 INFO - TEST-INFO | 1673: exit 0 03:07:38 INFO - Browser initialized. 03:07:38 INFO - Running cycle 1/1 for kraken test... 03:07:38 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmpyPOOh8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:07:50 INFO - PROCESS | 1677 | RSS: Main: 221745152 03:07:50 INFO - PROCESS | 1677 | 03:07:52 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 03:07:53 INFO - PROCESS | 1677 | RSS: Main: 233713664 03:07:53 INFO - PROCESS | 1677 | 03:07:56 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 03:07:57 INFO - PROCESS | 1677 | RSS: Main: 242565120 03:07:57 INFO - PROCESS | 1677 | 03:08:00 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 03:08:01 INFO - PROCESS | 1677 | RSS: Main: 245067776 03:08:01 INFO - PROCESS | 1677 | 03:08:03 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 03:08:04 INFO - PROCESS | 1677 | RSS: Main: 246124544 03:08:04 INFO - PROCESS | 1677 | 03:08:06 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:08:07 INFO - PROCESS | 1677 | RSS: Main: 247943168 03:08:07 INFO - PROCESS | 1677 | 03:08:10 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 03:08:10 INFO - PROCESS | 1677 | RSS: Main: 248356864 03:08:10 INFO - PROCESS | 1677 | 03:08:13 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 03:08:14 INFO - PROCESS | 1677 | RSS: Main: 248627200 03:08:14 INFO - PROCESS | 1677 | 03:08:17 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 03:08:17 INFO - PROCESS | 1677 | RSS: Main: 248459264 03:08:17 INFO - PROCESS | 1677 | 03:08:19 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:08:20 INFO - PROCESS | 1677 | RSS: Main: 248233984 03:08:20 INFO - PROCESS | 1677 | 03:08:22 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:08:22 INFO - PROCESS | 1677 | RSS: Main: 248147968 03:08:22 INFO - PROCESS | 1677 | 03:08:24 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:08:25 INFO - PROCESS | 1677 | RSS: Main: 248266752 03:08:25 INFO - PROCESS | 1677 | 03:08:28 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:08:29 INFO - PROCESS | 1677 | RSS: Main: 248352768 03:08:29 INFO - PROCESS | 1677 | 03:08:32 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:08:32 INFO - PROCESS | 1677 | RSS: Main: 248279040 03:08:32 INFO - PROCESS | 1677 | 03:08:34 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 03:08:35 INFO - PROCESS | 1677 | RSS: Main: 250011648 03:08:35 INFO - PROCESS | 1677 | 03:08:35 INFO - PROCESS | 1677 | __start_tp_report 03:08:35 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 03:08:35 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 03:08:35 INFO - PROCESS | 1677 | |i|pagename|runs| 03:08:35 INFO - PROCESS | 1677 | |0;ai-astar;118;112;116;118;172;181;115;117;117;116 03:08:35 INFO - PROCESS | 1677 | |1;audio-beat-detection;152;127;164;152;121;142;124;155;159;123 03:08:35 INFO - PROCESS | 1677 | |2;audio-dft;280;239;245;196;275;275;193;280;276;190 03:08:35 INFO - PROCESS | 1677 | |3;audio-fft;71;108;105;160;48;67;96;90;90;56 03:08:35 INFO - PROCESS | 1677 | |4;audio-oscillator;80;80;129;115;65;112;160;117;119;119 03:08:35 INFO - PROCESS | 1677 | |5;imaging-gaussian-blur;111;131;88;86;97;130;134;86;88;86 03:08:35 INFO - PROCESS | 1677 | |6;imaging-darkroom;121;94;95;97;152;95;95;94;94;95 03:08:35 INFO - PROCESS | 1677 | |7;imaging-desaturate;90;102;88;75;150;140;89;90;98;100 03:08:35 INFO - PROCESS | 1677 | |8;json-parse-financial;61;65;67;69;66;66;65;83;74;96 03:08:35 INFO - PROCESS | 1677 | |9;json-stringify-tinderbox;63;64;73;64;74;78;71;69;70;69 03:08:35 INFO - PROCESS | 1677 | |10;stanford-crypto-aes;84;83;83;91;101;105;111;106;86;86 03:08:35 INFO - PROCESS | 1677 | |11;stanford-crypto-ccm;166;142;153;155;144;136;137;134;166;146 03:08:35 INFO - PROCESS | 1677 | |12;stanford-crypto-pbkdf2;171;173;217;241;218;174;168;232;202;170 03:08:35 INFO - PROCESS | 1677 | |13;stanford-crypto-sha256-iterative;72;68;63;62;104;99;101;101;80;72 03:08:35 INFO - PROCESS | 1677 | __end_tp_report 03:08:35 INFO - PROCESS | 1677 | __start_cc_report 03:08:35 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,1097 03:08:35 INFO - PROCESS | 1677 | __end_cc_report 03:08:35 INFO - PROCESS | 1677 | __startTimestamp1461751715037__endTimestamp 03:08:35 INFO - PROCESS | 1677 | 03:08:35 INFO - PROCESS | 1677 | ------- Summary: start ------- 03:08:35 INFO - PROCESS | 1677 | Number of tests: 14 03:08:35 INFO - PROCESS | 1677 | 03:08:35 INFO - PROCESS | 1677 | [#0] ai-astar Cycles:10 Average:128.20 Median:117.50 stddev:25.60 (21.8%) stddev-sans-first:26.89 03:08:35 INFO - PROCESS | 1677 | Values: 118.0 112.0 116.0 118.0 172.0 181.0 115.0 117.0 117.0 116.0 03:08:35 INFO - PROCESS | 1677 | 03:08:35 INFO - PROCESS | 1677 | [#1] audio-beat-detection Cycles:10 Average:141.90 Median:152.00 stddev:16.64 (10.9%) stddev-sans-first:17.25 03:08:35 INFO - PROCESS | 1677 | Values: 152.0 127.0 164.0 152.0 121.0 142.0 124.0 155.0 159.0 123.0 03:08:35 INFO - PROCESS | 1677 | 03:08:35 INFO - PROCESS | 1677 | [#2] audio-dft Cycles:10 Average:244.90 Median:275.00 stddev:38.55 (14.0%) stddev-sans-first:38.74 03:08:35 INFO - PROCESS | 1677 | Values: 280.0 239.0 245.0 196.0 275.0 275.0 193.0 280.0 276.0 190.0 03:08:35 INFO - PROCESS | 1677 | 03:08:35 INFO - PROCESS | 1677 | [#3] audio-fft Cycles:10 Average:89.10 Median:69.00 stddev:32.12 (46.6%) stddev-sans-first:33.40 03:08:35 INFO - PROCESS | 1677 | Values: 71.0 108.0 105.0 160.0 48.0 67.0 96.0 90.0 90.0 56.0 03:08:35 INFO - PROCESS | 1677 | 03:08:35 INFO - PROCESS | 1677 | [#4] audio-oscillator Cycles:10 Average:109.60 Median:144.50 stddev:27.74 (19.2%) stddev-sans-first:27.27 03:08:35 INFO - PROCESS | 1677 | Values: 80.0 80.0 129.0 115.0 65.0 112.0 160.0 117.0 119.0 119.0 03:08:35 INFO - PROCESS | 1677 | 03:08:35 INFO - PROCESS | 1677 | [#5] imaging-gaussian-blur Cycles:10 Average:103.70 Median:86.00 stddev:20.78 (24.2%) stddev-sans-first:21.87 03:08:35 INFO - PROCESS | 1677 | Values: 111.0 131.0 88.0 86.0 97.0 130.0 134.0 86.0 88.0 86.0 03:08:35 INFO - PROCESS | 1677 | 03:08:35 INFO - PROCESS | 1677 | [#6] imaging-darkroom Cycles:10 Average:103.20 Median:95.00 stddev:19.03 (20.0%) stddev-sans-first:19.06 03:08:35 INFO - PROCESS | 1677 | Values: 121.0 94.0 95.0 97.0 152.0 95.0 95.0 94.0 94.0 95.0 03:08:35 INFO - PROCESS | 1677 | 03:08:35 INFO - PROCESS | 1677 | [#7] imaging-desaturate Cycles:10 Average:102.20 Median:88.50 stddev:23.92 (27.0%) stddev-sans-first:24.96 03:08:35 INFO - PROCESS | 1677 | Values: 90.0 102.0 88.0 75.0 150.0 140.0 89.0 90.0 98.0 100.0 03:08:35 INFO - PROCESS | 1677 | 03:08:35 INFO - PROCESS | 1677 | [#8] json-parse-financial Cycles:10 Average:71.20 Median:68.00 stddev:10.64 (15.7%) stddev-sans-first:10.63 03:08:35 INFO - PROCESS | 1677 | Values: 61.0 65.0 67.0 69.0 66.0 66.0 65.0 83.0 74.0 96.0 03:08:35 INFO - PROCESS | 1677 | 03:08:35 INFO - PROCESS | 1677 | [#9] json-stringify-tinderbox Cycles:10 Average:69.50 Median:70.50 stddev:4.84 (6.9%) stddev-sans-first:4.52 03:08:35 INFO - PROCESS | 1677 | Values: 63.0 64.0 73.0 64.0 74.0 78.0 71.0 69.0 70.0 69.0 03:08:35 INFO - PROCESS | 1677 | 03:08:35 INFO - PROCESS | 1677 | [#10] stanford-crypto-aes Cycles:10 Average:93.60 Median:83.50 stddev:10.96 (13.1%) stddev-sans-first:11.06 03:08:35 INFO - PROCESS | 1677 | Values: 84.0 83.0 83.0 91.0 101.0 105.0 111.0 106.0 86.0 86.0 03:08:35 INFO - PROCESS | 1677 | 03:08:35 INFO - PROCESS | 1677 | [#11] stanford-crypto-ccm Cycles:10 Average:147.90 Median:149.50 stddev:11.73 (7.8%) stddev-sans-first:10.46 03:08:35 INFO - PROCESS | 1677 | Values: 166.0 142.0 153.0 155.0 144.0 136.0 137.0 134.0 166.0 146.0 03:08:35 INFO - PROCESS | 1677 | 03:08:35 INFO - PROCESS | 1677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:196.60 Median:209.50 stddev:28.63 (13.7%) stddev-sans-first:28.83 03:08:35 INFO - PROCESS | 1677 | Values: 171.0 173.0 217.0 241.0 218.0 174.0 168.0 232.0 202.0 170.0 03:08:35 INFO - PROCESS | 1677 | 03:08:35 INFO - PROCESS | 1677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:82.20 Median:70.00 stddev:17.18 (24.5%) stddev-sans-first:17.82 03:08:35 INFO - PROCESS | 1677 | Values: 72.0 68.0 63.0 62.0 104.0 99.0 101.0 101.0 80.0 72.0 03:08:35 INFO - PROCESS | 1677 | -------- Summary: end -------- 03:08:35 INFO - PROCESS | 1677 | 03:08:35 INFO - TEST-INFO | 1677: exit 0 03:08:35 INFO - TEST-OK | kraken | took 61428ms 03:08:35 INFO - SUITE-END | took 1172s 03:08:36 INFO - Completed test suite (00:19:33) 03:08:36 INFO - javascript benchmark 03:08:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2952.380000199958, 3494.7418929185505, 3502.282032183403, 3536.0, 3572.4641303804424, 4452.821887124511, 5678.034734095203, 5739.311282646083, 5739.655620662753, 5759.82720518384, 5753.424657534244, 5808.767649294034, 5825.912611310823, 5827.067669172933, 5849.795257166, 4626.537346265381, 4944.357233559632, 5191.039657663335, 5233.607479439038, 5260.0, 5157.7936882524655, 5251.133562962125, 5285.286486324355, 5372.9462705373035, 5407.270018547505, 4307.849225277116, 5251.054810134194, 5256.448072952342, 5262.473752624726, 5271.341082364705, 5229.581633469333, 5232.450592687789, 5237.8428647140645, 5326.121190003645, 5378.058839703075, 5145.228215767627, 5232.816851410183, 5253.947460525423, 5269.235960785703, 5317.069512835238, 5677.432256774331, 5683.318001839806, 5685.829425117253, 5703.3441154267375, 5744.770209191627, 6105.206323178003, 6119.326874043852, 6185.597936134137, 6232.252129744415, 6285.497160227216, 6207.317195108534, 6262.091996660459, 6277.246730392382, 6294.590851594632, 6301.464375528086, 5882.382349853289, 5903.616264942765, 5908.911366329508, 5926.111083337448, 5926.259217597843, 6230.844228894314, 6291.993281075006, 6331.113644089831, 6353.872922541524, 6359.904601430982, 4788.281757736368, 5077.949220507748, 6181.81454556355, 6227.06594010893, 6272.749090036347, 6113.938860611337, 6263.279722831841, 6267.4045965633195, 6277.623342599459, 6295.087212354183, 6107.694615269219, 6187.319394866561, 6241.750329986749, 6241.938870392044, 6281.120643109968, 5764.501229680313, 5947.256592925884, 6159.384061593805, 6169.5064394848305, 6208.6096852711125, 4225.3873188387515, 4813.879653008703, 4897.1674815281485, 4913.263010548446, 4922.556969872729, 4194.979025104855, 4356.215881141425, 4395.516493185747, 4464.308032255006, 4481.498697936163, 4719.433667959798, 5735.598508104393, 5778.191053252548, 5800.564957628195, 5831.79588714393, 6232.189815323979, 6263.154474145932, 6269.498440124779, 6269.498440124779, 6295.401936815995, 5640.746166422521, 5642.492175704124, 5745.052066409079, 5767.769289228384, 5844.707764611752, 5387.46125387466, 5391.0, 5417.295751552273, 5419.945800542023, 5431.918521222185, 5277.762500687479, 6322.0516922462, 6337.7781777637565, 6338.619682819046, 6366.0, 5219.556337711337, 6116.518381401779, 6141.977005061692, 6225.3774622537385, 6274.968625156845, 6388.329225431263, 6391.041343798467, 6395.104685343963, 6403.455706264834, 6487.253965793994, 6188.3502232266765, 6239.563230573816, 6275.058741188858, 6290.654014029273, 6290.968545157245, 6153.569250152446, 6200.224971878515, 6238.376162383907, 6243.531735119794, 6254.8123556293385, 5178.741062946913, 5725.799597014251, 6157.199564056644, 6186.690665466619, 6221.564490485623, 6035.69821508914, 6172.444479996821, 6176.567640265138, 6185.536084793568, 6202.782902598387, 4763.976180119077, 5978.8206353809455, 5999.250093738283, 6023.488003519575, 6086.391360863879, 6112.113743507255, 6113.938860611337, 6130.264368275835, 6145.0, 6165.660888651168, 5748.080307150836, 5926.081457374114, 5990.0, 6028.065649824284, 6028.668423236764], "value": 5663.173741563214, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [517.5652451940372, 538.5583821266562, 564.6460749381566, 580.8518827698936, 595.9213822980405, 38965.61034389655, 40317.79841100798, 40318.798406008005, 40333.1933361333, 40562.18875622486, 43523.3471497927, 44149.0, 44475.77762111185, 44525.77371131447, 44699.55300447003, 9602.08780165883, 9826.656067037655, 9832.26258030647, 9865.556049977733, 9886.258530610197, 9770.0, 9861.06319899612, 9863.161631261315, 9901.752456188617, 9913.256505762096, 9851.261155413336, 9881.16010139139, 9902.0, 9909.851352229687, 9963.850542241873, 9790.0, 9828.262880284008, 9866.65466708669, 9882.80234395308, 9920.751981200528, 9618.470984095871, 9714.90285097147, 9722.270829687732, 9763.90236097637, 9774.413535187912, 39309.80345098285, 39469.0, 39688.80155599204, 39761.80119099415, 40241.59758402408, 3144.261098641817, 3342.4819153031317, 3476.7392445566684, 3659.743817932746, 3872.1287710265246, 27866.0, 27977.860110699316, 28797.712022879714, 29625.851870740724, 30337.544936825965, 2804.6353973983455, 2807.2560771395597, 2841.849051134294, 2848.1313199474152, 2883.8413887236193, 469412.0, 470409.0, 471586.0, 473162.0, 474228.0, 34199.658003419645, 35188.47217291692, 64578.67710661417, 64867.0, 65013.67493162504, 32978.17554561155, 34036.0, 34749.47875781865, 35054.824725876206, 35108.29783404369, 13531.797023044663, 15773.921130394274, 15803.0, 15808.0, 15829.050256984618, 12873.970082393385, 13075.476980920657, 13221.338933053501, 13289.534866279633, 13328.200307981358, 7476.028116344841, 7912.208779122157, 8185.672573097009, 8299.875501867476, 8456.577171141542, 3427.9828600856836, 3445.4831775233415, 3458.878939237165, 3462.532558104674, 3489.842957066938, 1001.2190491416707, 1050.0, 1058.9841152382721, 1089.5042755546208, 1149.5172027748365, 30693.23266918345, 30745.538816917317, 30824.075277741704, 30956.535651965238, 31311.21721956969, 1091.6779550032759, 1099.9505022274018, 1134.5348407153028, 1154.099802154321, 1179.4515550269166, 12944.805827912589, 15978.0, 16044.75932861008, 16096.839031609768, 16133.596660083593, 629860.0, 633347.0, 634721.0, 645171.0, 645415.0, 61992.69003655043, 62761.68619156875, 62869.685651571446, 62930.68534657389, 62976.0, 1465.3259500629829, 1498.730228558848, 1507.2463768115942, 1512.1154124836874, 1522.1704171226709, 30357.544636830906, 30646.08061758151, 30661.846690766404, 30818.229544260677, 30893.53659695151, 55188.44811551833, 55236.44763552474, 55575.444245557024, 55616.72191639016, 55848.44151558432, 603207.0, 611244.0, 611892.0, 613584.0, 614693.0, 27332.316692082855, 27627.86186069057, 27948.580771288853, 28028.71971280261, 28300.292492687848, 47878.0, 47922.76038619784, 48700.75649621729, 49449.75275123745, 49565.0, 37626.43560346542, 38255.04362390851, 39687.60312396839, 40636.59363406328, 40689.1862162761, 38833.41749873698, 39252.214955700154, 39476.80261598674, 40083.198336033696, 40365.0, 37888.242235155696, 38658.42012369761, 39132.413013804246, 39249.215015700094, 39325.60674393334, 31491.0, 34083.65916340805, 34571.96284111481, 34804.0, 34827.82586087054, 21881.0, 25629.0, 25736.356591085372, 26003.82982765818, 26196.21411357662], "value": 19083.48962156757, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.47206482179496, 395.23521984959126, 401.392666526684, 402.3542214745333, 407.8674430809987, 14137.57587272381, 19666.90166549167, 20504.974751262434, 20560.0, 21107.0, 20183.09176087076, 20584.279550215746, 20963.16147354104, 21147.154113835466, 21180.682289765668, 5946.256717910262, 6004.549658775588, 6103.938960610381, 6209.0, 6234.033724772645, 5141.408737995141, 6095.268567771874, 6151.292601350857, 6165.383461653843, 6196.504279657639, 5942.673152976585, 6123.142760013601, 6160.969195154041, 6181.0110382338835, 6189.876202475948, 5262.684238945656, 6038.728257228435, 6051.546134039942, 6117.66352850593, 6129.049997250433, 5359.705216213107, 5369.48989845964, 5371.0, 5372.221027950925, 5385.919211211835, 26189.73810261911, 26682.5997610036, 27002.864985675144, 27562.862185689144, 27586.724132758816, 2179.052112331141, 2540.0982651158756, 2613.3074735195187, 2624.173385383617, 2913.6066631004755, 14637.341319640642, 15021.248937553079, 15183.240837958057, 15213.61965950849, 15688.0, 1709.7093494106032, 1732.8527075198624, 1737.5569229846308, 1775.6715007723612, 1858.60040091381, 89551.20897582012, 113376.43311783388, 113800.0, 133792.0, 134580.0, 11389.430528473544, 11742.060635149168, 11923.284602923852, 11969.581064662694, 12027.45876435545, 14901.925490372696, 15071.321790519452, 15149.697006059818, 15213.467528636445, 15222.695546089017, 8947.0, 9022.819543609092, 9103.271738260923, 9169.037251088674, 9247.72256832296, 6885.724571017204, 9084.727458176265, 9089.77275568116, 9144.634214631473, 9179.541022948826, 4659.0, 4773.427188737373, 4775.641826863, 4801.759912004386, 4847.127517046895, 1993.9302124425576, 1999.6800511918022, 2032.1972820735728, 2078.210280093555, 2085.9269925552835, 819.7663665855202, 891.9955400222957, 922.7877588154762, 930.3348106104168, 976.3165783951263, 17482.475525733993, 19813.504662383268, 20336.796632033787, 20914.790852091286, 21064.368068957956, 932.7761337279004, 960.6349587156835, 984.8473486609444, 992.965246216379, 1006.5269323417982, 10790.67627971162, 11076.0, 11092.909426548076, 11166.497507612177, 11190.608328708619, 593417.0, 603343.0, 614188.0, 614487.0, 616098.0, 81179.18820811834, 117038.0, 122308.38845805892, 129745.0, 139399.6060039407, 871.7471933139318, 939.196986576481, 974.6004138303404, 976.0678551982982, 1083.2742062817774, 18299.908500457947, 18467.81532184661, 18498.16758245909, 18654.626907462047, 18716.906415467834, 24263.514729704955, 38724.41913371245, 50886.74556627193, 51337.0, 52517.0, 60338.69830650819, 70211.0, 73231.0, 73532.52934941379, 73910.0, 7314.780556583311, 8031.393721255889, 8070.717524886601, 8324.875126872981, 8656.48061116335, 9419.0, 11703.2978021319, 12510.87489125097, 12812.55156069533, 12896.548620798227, 5512.0, 7848.411369147223, 11031.503582338653, 11079.83380249313, 11311.095112390823, 10769.569217231223, 10862.891371086187, 11042.171837112088, 11110.666679999613, 11233.606823761129, 6917.308269173042, 9633.759156021155, 11099.278546894426, 11359.261647992855, 11899.167058305835, 8430.45202061864, 8455.408121431441, 8526.0, 8607.741767746978, 8636.26591739684, 10657.20070994663, 11404.885951140714, 11405.828912566483, 11637.243579167327, 11729.35488548138], "value": 9619.82516392203, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1171.6836454157378, 1176.8293597428374, 1208.3897631695993, 1528.7248295306847, 1584.833592472791, 7814.531128132309, 8058.5970701464985, 8115.14790946951, 8118.715844945428, 8653.524056176908, 8721.34589905758, 8805.95597022014, 8807.735767926957, 8819.867701984474, 8845.469271843695, 2457.840240384374, 2482.7020757509126, 2488.1167185649106, 2491.0, 2491.20281509916, 2427.8057755379527, 2480.9255722328267, 2482.987585062072, 2485.2047344849657, 2488.4401009772746, 2433.1605596069326, 2441.890114944823, 2509.623556466526, 2510.57320255557, 2511.7739403453684, 2460.692413448319, 2470.789982851451, 2481.677381940345, 2489.2158969924417, 2494.6133349330703, 2460.2865169100937, 2465.790407815338, 2480.516299321633, 2486.3411196033067, 2486.9751302486925, 8885.200331970089, 8964.8655270171, 8969.372143949926, 8972.910270897339, 8985.46087234768, 5319.813806516792, 5521.089020311644, 5525.723713814334, 5551.639143455663, 5556.638818476786, 3577.172425504711, 3585.569731632195, 3586.9282614347567, 3644.8177591120602, 3667.688246499051, 1025.892281310459, 1034.1313296830624, 1046.4140081554353, 1048.816457120001, 1067.8505009298703, 11790.410479475991, 11803.940980295129, 11927.94036029823, 11940.0, 11993.880061199363, 4933.136701077369, 4955.6531042826655, 5055.368078990126, 5060.620453466005, 5085.059264036166, 10185.0, 10207.897921020844, 10235.48822558884, 10238.33450825694, 10251.077403033763, 5462.617616766788, 5479.369872464637, 5532.917006244909, 5535.391106978229, 5554.638948468337, 4482.170798402306, 4850.296706977469, 4968.22992436173, 5005.34930459038, 5030.84907452777, 4133.607307305801, 4140.979295103505, 4174.3947127666315, 4198.475190601175, 4245.724027938174, 1162.976740465186, 1172.6364826903687, 1183.260462211118, 1353.6954185308227, 1391.4851504943235, 748.6631016042802, 939.3049143633798, 954.9140577348072, 959.3907868503411, 964.2237998411347, 5960.195373624592, 5968.850778730566, 6125.387461253839, 6211.534134939898, 6255.906161407583, 980.9411435313904, 1022.5091955861228, 1046.3774054437597, 1065.898739619735, 1090.2422816142864, 3336.4995250712586, 3498.3353162898966, 3970.900727481836, 4009.6190861868076, 4163.2298024865495, 10219.7445063874, 10344.741381465523, 10693.518791654395, 10775.892241077645, 10784.892151078546, 10675.46622668879, 10704.625338113128, 10708.03627673513, 10768.976947190004, 10789.622363217406, 8003.359731221488, 8056.033276006917, 8190.058143313595, 8283.58582070894, 8284.958575207205, 5945.286565612154, 5993.760249590142, 5994.250718660168, 6111.113888486056, 6217.378262173746, 9774.511274436392, 9950.402975821184, 10112.646057387956, 10208.642697505551, 10220.897791021995, 9998.700038998843, 10010.899891000996, 10065.0, 10251.179905607683, 10342.5345859435, 6968.0, 7604.885926710993, 7723.304902558797, 8065.758027259191, 8419.158084191531, 8396.496210227406, 8447.0, 8484.19400156984, 8507.532085735344, 8513.021002584534, 6320.715567799368, 6475.838104047437, 7369.77890663281, 7602.885956710765, 7633.931249624946, 6506.707198175999, 7444.627768611656, 7446.0, 7472.215417381314, 7473.439492038227, 7011.614361209979, 7123.608201548965, 7149.571025738473, 7149.678264478007, 7172.0676312079095, 4669.60308373792, 4712.363830882787, 4954.727489988085, 4995.0, 5114.181730923035, 3859.6140385962303, 4957.677750946177, 5095.0, 5112.923306150336, 5353.812616558402], "value": 4645.461814762539, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.3426199409276, 325.042749103889, 329.31831109603115, 333.42983498218047, 334.64861895010233, 5062.8228012019545, 5333.959643003484, 5456.945430545693, 5500.752466139023, 5532.612717109804, 4359.738415695053, 5080.415752188499, 5372.788395256604, 5415.539679127279, 5481.5340696040785, 3997.9800100999455, 4111.773852438115, 4184.456020717303, 4193.140406216734, 4208.705390622657, 3329.433996220648, 4145.378193271018, 4215.241256573816, 4249.936250956238, 4277.807498662568, 4153.833846646131, 4185.183889141618, 4196.601322874338, 4215.367694845782, 4215.662746980234, 3576.03318175004, 3892.936743525936, 4173.812178451962, 4193.182329445759, 4263.4031235627035, 3385.2213990782257, 3616.72874534411, 3619.3847046002243, 3641.4509843109927, 3665.4135338346005, 3971.4042893565907, 4707.7175369477945, 5174.2238664200295, 5222.92165617516, 5227.823739658585, 3700.666939975415, 4244.363345498169, 4701.871550827811, 4836.443808961979, 4866.58634016109, 5028.698278103289, 5114.514121158484, 5779.797707080232, 5805.883882322372, 5806.025951176578, 4052.230076285496, 4059.939100913489, 4080.7143499955014, 4088.693347998882, 4099.795010249476, 5296.92054619181, 5612.803551875706, 5781.942180578182, 6070.514358851281, 6114.266288045463, 3615.2769446110883, 4103.317639767681, 4104.240715467648, 4155.168966206771, 4171.895702607399, 4197.286461301586, 4200.15996800635, 4202.453681021509, 4215.78921053946, 4232.343986682007, 4604.746738929324, 4699.154152252559, 4782.665213435026, 4842.443119041355, 4868.88015756378, 5441.395362776347, 5472.370677372073, 5561.360443549043, 5824.3302020267365, 5830.62100963436, 1345.8721421464945, 1375.7661197596433, 1446.797448357231, 1486.9925650371895, 1542.1672296959516, 925.8194652042922, 954.1317401164906, 1004.6082028009082, 1029.1869423155624, 1041.677070120942, 4820.783064762094, 5856.531477481792, 5868.23712917318, 5879.2944846618675, 5924.14099955504, 6459.418652321219, 6489.351064893567, 6543.247526534415, 6546.70539825709, 6585.209774827052, 5218.843434696965, 5441.591880608891, 5508.944910550923, 5518.420565840609, 5534.727012787082, 3079.291762894502, 3463.5843698755807, 3473.635268296843, 3484.9303013940084, 3489.546358973317, 6602.3397660233595, 6889.483288753364, 6899.93100069003, 6925.58446493212, 6929.896051559231, 6330.7338532293725, 6598.307177746267, 6630.370114839083, 6771.729130834711, 6826.214985276657, 5694.800681976111, 6320.399562041599, 6710.228323742735, 6884.586924784529, 6936.34104760047, 5824.679642619527, 5826.300843898759, 5876.3536011039605, 6203.2245969253845, 6568.507361947969, 6116.143739876332, 6291.427143214197, 6390.872182556416, 6430.324815894451, 6505.577137486048, 5530.861728456821, 6305.432511074026, 6647.169103862017, 6838.93996430554, 6923.6192009439965, 3712.997490677448, 4186.476690413699, 4217.114405974779, 4292.205941900758, 4300.827966881289, 4743.976280118577, 5747.626404283708, 6086.756529738761, 6579.80260592183, 6595.373439523238, 4925.950740492549, 4976.954839483749, 5048.015636950906, 5696.344920334214, 5861.267341582302, 4467.508574056917, 5821.301443826768, 5847.619904706181, 5848.736806843617, 5891.970540147272, 5342.519173274424, 5461.945380546303, 5533.699580598475, 5684.346300175367, 5762.567807414544, 4618.953810461852, 5180.896382072262, 5366.597505187048, 5784.363719990882, 5862.94137058624, 5071.54356107952, 5593.356763972195, 5774.653520788766, 5798.420157984167, 5831.387704291158], "value": 4356.361776717708, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [594.3729365519376, 735.7461675721875, 747.7756672998099, 749.2320371619093, 764.1899586438376, 14591.854081459196, 14797.778033329496, 14824.0, 14921.4777482788, 14966.251687415614, 12567.308798016105, 13692.452301907912, 13732.450701971935, 13772.311384430788, 14942.850571494282, 896.9013408525057, 960.0783248081815, 963.8746962894813, 966.3766870368643, 970.5438443931376, 669.7622344067859, 920.6869664314132, 934.7849994501235, 939.7791518993065, 943.2124176312788, 947.2800671489683, 1002.5688953749884, 1005.3967619428321, 1010.4089107871904, 1011.4386515483903, 963.6434519227895, 984.4585477987114, 991.0436428846198, 997.8652881860964, 1000.2698030437748, 737.9372753315921, 744.7058411927312, 746.9141048779405, 749.1197842535054, 749.62144117221, 13998.930005349908, 14071.71856562863, 14149.151050936875, 14153.150810951272, 14157.85842141586, 12019.51921923122, 12132.0, 12204.572839950559, 12232.510699572007, 12471.189372690746, 7458.477906546544, 10120.392776433437, 10156.593736250541, 10165.644202452879, 10406.0, 3445.3970555152746, 3480.6519348065244, 3491.6508349165133, 3493.109257139439, 3568.2506673598314, 65202.0, 67400.9999999995, 67576.0, 67592.66203668999, 73962.26037739607, 8306.792330191673, 9338.906610933804, 9457.43255404678, 9544.809103817885, 9634.03659634031, 16760.832391676173, 20178.697319540217, 20310.08604612796, 20379.388618341472, 20425.69361459579, 6255.624662520263, 7220.880763481564, 7392.926070739224, 7524.05949256343, 7627.961860190663, 3302.438585440481, 3497.510348551205, 3917.764934103963, 3988.541317748438, 4010.939835902464, 3049.4663433898977, 3493.475978603179, 3556.928861422757, 3570.821458927095, 3577.4454959481322, 521.9951593183107, 613.2885852411271, 642.996785016072, 665.364576829127, 667.1960287853223, 524.2634099090784, 572.4275724275724, 590.8581940334288, 591.8342863998088, 600.7747094839435, 6423.96788016057, 6744.123263975652, 7148.106486689164, 8140.959295203486, 8212.78535193406, 577.9913301300484, 601.6781567039528, 609.7073404765737, 610.3896103896104, 620.7144713431871, 3646.58064322601, 3841.615838416136, 3888.4556162137324, 3917.4319723639915, 4015.3374693175297, 98366.0, 98412.01587984028, 98977.50511247398, 100318.0, 101068.0, 78259.60870195612, 78364.82452763212, 78535.60732196417, 78576.60711696405, 79780.39074888531, 70969.6451517739, 71758.64120679363, 71940.64029679923, 72447.63776181085, 72823.63588182026, 6120.54095942815, 7108.218096009331, 7137.929310603451, 7417.258274172539, 7615.885761713468, 34718.0, 44559.88600285019, 44950.87622809456, 45077.77461112804, 45860.08279834451, 62357.0, 62417.0, 62628.68685656542, 62715.68642156942, 63164.0, 9667.371620844622, 9904.851427228454, 9985.0, 10018.398896065968, 10024.448655324028, 10807.891921081004, 10952.0, 11863.525458981889, 11987.160898737353, 12014.219075760047, 10845.566177352817, 10849.67450976472, 10902.727431814268, 10937.890621093688, 11185.161112916727, 10807.35155890649, 10968.616098436516, 11102.056325212125, 11383.317000979967, 11995.340256285988, 9156.450612963243, 10828.837567436338, 10844.45777711127, 11079.113670906469, 11086.445677716243, 9374.531273436438, 9380.718578442658, 9398.248140148908, 9715.659951901649, 9726.902730972883, 9486.810263794823, 9542.856857147288, 9648.469334186688, 9675.516224188621, 9742.61029558838], "value": 6700.489108542554, "unit": "score"}], "name": "dromaeo_css", "value": 7214.356455494256, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 112.0, 116.0, 118.0, 172.0, 181.0, 115.0, 117.0, 117.0, 116.0], "value": 128.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 127.0, 164.0, 152.0, 121.0, 142.0, 124.0, 155.0, 159.0, 123.0], "value": 141.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [280.0, 239.0, 245.0, 196.0, 275.0, 275.0, 193.0, 280.0, 276.0, 190.0], "value": 244.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 108.0, 105.0, 160.0, 48.0, 67.0, 96.0, 90.0, 90.0, 56.0], "value": 89.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 129.0, 115.0, 65.0, 112.0, 160.0, 117.0, 119.0, 119.0], "value": 109.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 131.0, 88.0, 86.0, 97.0, 130.0, 134.0, 86.0, 88.0, 86.0], "value": 103.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 94.0, 95.0, 97.0, 152.0, 95.0, 95.0, 94.0, 94.0, 95.0], "value": 103.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 102.0, 88.0, 75.0, 150.0, 140.0, 89.0, 90.0, 98.0, 100.0], "value": 102.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 65.0, 67.0, 69.0, 66.0, 66.0, 65.0, 83.0, 74.0, 96.0], "value": 71.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 73.0, 64.0, 74.0, 78.0, 71.0, 69.0, 70.0, 69.0], "value": 69.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 83.0, 91.0, 101.0, 105.0, 111.0, 106.0, 86.0, 86.0], "value": 93.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 142.0, 153.0, 155.0, 144.0, 136.0, 137.0, 134.0, 166.0, 146.0], "value": 147.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 173.0, 217.0, 241.0, 218.0, 174.0, 168.0, 232.0, 202.0, 170.0], "value": 196.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 68.0, 63.0, 62.0, 104.0, 99.0, 101.0, 101.0, 80.0, 72.0], "value": 82.2, "unit": "score"}], "name": "kraken", "value": 1683.8000000000002, "alertThreshold": 2.0}]} 03:08:36 INFO - Return code: 0 03:08:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:08:36 ERROR - # TBPL SUCCESS # 03:08:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:08:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:08:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:08:36 INFO - 2016-04-27 03:08:36.337 screenresolution[1682:10680] starting screenresolution argv=screenresolution get 03:08:36 INFO - 2016-04-27 03:08:36.340 screenresolution[1682:10680] Display 0: 1600x1200x32@60 03:08:36 INFO - 2016-04-27 03:08:36.347 screenresolution[1683:10683] starting screenresolution argv=screenresolution list 03:08:36 INFO - Available Modes on Display 0 03:08:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:08:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:08:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:08:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:08:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:08:36 INFO - Intel Iris: 03:08:36 INFO - Chipset Model: Intel Iris 03:08:36 INFO - Type: GPU 03:08:36 INFO - Bus: Built-In 03:08:36 INFO - VRAM (Dynamic, Max): 1536 MB 03:08:36 INFO - Vendor: Intel (0x8086) 03:08:36 INFO - Device ID: 0x0a2e 03:08:36 INFO - Revision ID: 0x0009 03:08:36 INFO - Displays: 03:08:36 INFO - Display: 03:08:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:08:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:08:36 INFO - Display Serial Number: 1600x1200 60 03:08:36 INFO - Main Display: Yes 03:08:36 INFO - Mirror: Off 03:08:36 INFO - Online: Yes 03:08:36 INFO - Rotation: Supported 03:08:36 INFO - Return code: 0 03:08:36 INFO - Running post-action listener: _resource_record_post_action 03:08:36 INFO - Running post-run listener: _resource_record_post_run 03:08:37 INFO - Total resource usage - Wall time: 1206s; CPU: 54.0%; Read bytes: 32407040; Write bytes: 655383040; Read time: 339; Write time: 2548 03:08:37 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 342637056; Write bytes: 336582144; Read time: 26689; Write time: 623 03:08:37 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 31481344; Write bytes: 299467776; Read time: 325; Write time: 1862 03:08:37 INFO - Running post-run listener: _upload_blobber_files 03:08:37 INFO - Blob upload gear active. 03:08:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:08:37 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 03:08:37 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'] 03:08:37 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 03:08:38 INFO - (blobuploader) - INFO - Open directory for files ... 03:08:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:08:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:08:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:08:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:08:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:08:39 INFO - (blobuploader) - INFO - Done attempting. 03:08:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:08:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:08:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:08:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:08:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:08:40 INFO - (blobuploader) - INFO - Done attempting. 03:08:40 INFO - (blobuploader) - INFO - Iteration through files over. 03:08:40 INFO - Return code: 0 03:08:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:08:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:08:40 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/85f29cd17a1cf16c2ae885499d2e8b55282d5fe9b7da64089d49ea08d7b04c5ee0889602f86f4070e1e292a08761856113ea516d1c42a81d5407dda8c6113e39", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f403a965672625bcec40b56cf839516d622a893906449be3949ff11ff9a88ced4c431bca6267bc055a11eb67454aa934ac7cc2998dda3710e46f5fdbcaf3f55d"} 03:08:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:08:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:08:40 INFO - Contents: 03:08:40 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/85f29cd17a1cf16c2ae885499d2e8b55282d5fe9b7da64089d49ea08d7b04c5ee0889602f86f4070e1e292a08761856113ea516d1c42a81d5407dda8c6113e39", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f403a965672625bcec40b56cf839516d622a893906449be3949ff11ff9a88ced4c431bca6267bc055a11eb67454aa934ac7cc2998dda3710e46f5fdbcaf3f55d"} 03:08:40 INFO - Running post-run listener: copy_logs_to_upload_dir 03:08:40 INFO - Copying logs to upload dir... 03:08:40 INFO - mkdir: /builds/slave/test/build/upload/logs 03:08:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.703483 ========= master_lag: 1.84 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-04-27 03:08:42.315723) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-27 03:08:42.323524) ========= 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.RS0Vpt80Di/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.9HdcxFTHdx/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/85f29cd17a1cf16c2ae885499d2e8b55282d5fe9b7da64089d49ea08d7b04c5ee0889602f86f4070e1e292a08761856113ea516d1c42a81d5407dda8c6113e39", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f403a965672625bcec40b56cf839516d622a893906449be3949ff11ff9a88ced4c431bca6267bc055a11eb67454aa934ac7cc2998dda3710e46f5fdbcaf3f55d"} build_url:https://queue.taskcluster.net/v1/task/RYa29tVaSb6PZcdrKHbVmw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011208 build_url: 'https://queue.taskcluster.net/v1/task/RYa29tVaSb6PZcdrKHbVmw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/85f29cd17a1cf16c2ae885499d2e8b55282d5fe9b7da64089d49ea08d7b04c5ee0889602f86f4070e1e292a08761856113ea516d1c42a81d5407dda8c6113e39", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f403a965672625bcec40b56cf839516d622a893906449be3949ff11ff9a88ced4c431bca6267bc055a11eb67454aa934ac7cc2998dda3710e46f5fdbcaf3f55d"}' ========= master_lag: 0.11 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-27 03:08:42.446773) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 03:08:42.447112) ========= 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.RS0Vpt80Di/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.9HdcxFTHdx/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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.005165 ========= master_lag: 0.16 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 03:08:42.612626) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-27 03:08:42.613020) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-27 03:08:42.624323) ========= ========= Total master_lag: 2.19 =========