builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0225 starttime: 1464648644.23 results: success (0) buildid: 20160530120549 builduid: 7997d47d310b490c9d491deb39fe4327 revision: 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:44.229558) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:44.229894) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:44.230371) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:44.341613) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:44.341868) ========= 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.M4ICUpDxpj/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.ehpildmjMm/Listeners TMPDIR=/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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-05-30 15:50:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 53.6M=0s 2016-05-30 15:50:44 (53.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.610765 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:44.974585) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:44.974943) ========= 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.M4ICUpDxpj/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.ehpildmjMm/Listeners TMPDIR=/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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.025452 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:45.023721) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:45.024031) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 --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 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.M4ICUpDxpj/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.ehpildmjMm/Listeners TMPDIR=/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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-05-30 15:50:45,100 truncating revision to first 12 chars 2016-05-30 15:50:45,101 Setting DEBUG logging. 2016-05-30 15:50:45,101 attempt 1/10 2016-05-30 15:50:45,101 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3c7a33fe0b64?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 15:50:45,740 unpacking tar archive at: mozilla-aurora-3c7a33fe0b64/testing/mozharness/ program finished with exit code 0 elapsedTime=0.924083 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:45.973659) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:45.973931) ========= script_repo_revision: 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:45.974283) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:45.974563) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 15:50:45.990383) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 52 secs) (at 2016-05-30 15:50:45.990745) ========= /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.M4ICUpDxpj/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.ehpildmjMm/Listeners TMPDIR=/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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 15:50:46 INFO - MultiFileLogger online at 20160530 15:50:46 in /builds/slave/test 15:50:46 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 15:50:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:50:46 INFO - {'append_to_log': False, 15:50:46 INFO - 'base_work_dir': '/builds/slave/test', 15:50:46 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:50:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:50:46 INFO - 'branch': 'Mozilla-Aurora', 15:50:46 INFO - 'buildbot_json_path': 'buildprops.json', 15:50:46 INFO - 'config_files': ('talos/mac_config.py',), 15:50:46 INFO - 'default_actions': ('clobber', 15:50:46 INFO - 'read-buildbot-config', 15:50:46 INFO - 'download-and-extract', 15:50:46 INFO - 'populate-webroot', 15:50:46 INFO - 'create-virtualenv', 15:50:46 INFO - 'install', 15:50:46 INFO - 'run-tests'), 15:50:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:50:46 INFO - 'download_minidump_stackwalk': True, 15:50:46 INFO - 'download_symbols': 'ondemand', 15:50:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:50:46 INFO - 'tooltool.py': '/tools/tooltool.py', 15:50:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:50:46 INFO - '/tools/misc-python/virtualenv.py')}, 15:50:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:50:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:50:46 INFO - 'installer_path': 'installer.exe', 15:50:46 INFO - 'log_level': 'info', 15:50:46 INFO - 'log_name': 'talos', 15:50:46 INFO - 'log_to_console': True, 15:50:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:50:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:50:46 INFO - 'opt_config_files': (), 15:50:46 INFO - 'pip_index': False, 15:50:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:50:46 INFO - 'cmd': ('bash', 15:50:46 INFO - '-c', 15:50:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:50:46 INFO - 'enabled': True, 15:50:46 INFO - 'halt_on_failure': False, 15:50:46 INFO - 'name': 'check_screen_resolution'},), 15:50:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:50:46 INFO - 'cmd': ('bash', 15:50:46 INFO - '-c', 15:50:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:50:46 INFO - 'enabled': True, 15:50:46 INFO - 'halt_on_failure': False, 15:50:46 INFO - 'name': 'check_screen_resolution'},), 15:50:46 INFO - 'run_cmd_checks_enabled': True, 15:50:46 INFO - 'sps_profile': False, 15:50:46 INFO - 'sps_profile_interval': 0, 15:50:46 INFO - 'suite': 'dromaeojs-e10s', 15:50:46 INFO - 'system_bits': '32', 15:50:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:50:46 INFO - 'title': 't-yosemite-r7-0225', 15:50:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:50:46 INFO - 'use_talos_json': True, 15:50:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:50:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:50:46 INFO - 'work_dir': 'build'} 15:50:46 INFO - ##### 15:50:46 INFO - ##### Running clobber step. 15:50:46 INFO - ##### 15:50:46 INFO - Running pre-action listener: _resource_record_pre_action 15:50:46 INFO - Running main action method: clobber 15:50:46 INFO - rmtree: /builds/slave/test/build 15:50:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:50:47 INFO - Running post-action listener: _resource_record_post_action 15:50:47 INFO - ##### 15:50:47 INFO - ##### Running read-buildbot-config step. 15:50:47 INFO - ##### 15:50:47 INFO - Running pre-action listener: _resource_record_pre_action 15:50:47 INFO - Running main action method: read_buildbot_config 15:50:47 INFO - Using buildbot properties: 15:50:47 INFO - { 15:50:47 INFO - "project": "", 15:50:47 INFO - "product": "firefox", 15:50:47 INFO - "script_repo_revision": "production", 15:50:47 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 15:50:47 INFO - "repository": "", 15:50:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 15:50:47 INFO - "buildid": "20160530120549", 15:50:47 INFO - "pgo_build": "False", 15:50:47 INFO - "basedir": "/builds/slave/test", 15:50:47 INFO - "buildnumber": 307, 15:50:47 INFO - "slavename": "t-yosemite-r7-0225", 15:50:47 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 15:50:47 INFO - "platform": "yosemite_r7", 15:50:47 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 15:50:47 INFO - "branch": "mozilla-aurora", 15:50:47 INFO - "repo_path": "releases/mozilla-aurora", 15:50:47 INFO - "revision": "3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4", 15:50:47 INFO - "stage_platform": "macosx64", 15:50:47 INFO - "builduid": "7997d47d310b490c9d491deb39fe4327", 15:50:47 INFO - "slavebuilddir": "test" 15:50:47 INFO - } 15:50:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 15:50:47 INFO - Running post-action listener: _resource_record_post_action 15:50:47 INFO - ##### 15:50:47 INFO - ##### Running download-and-extract step. 15:50:47 INFO - ##### 15:50:47 INFO - Running pre-action listener: _resource_record_pre_action 15:50:47 INFO - Running main action method: download_and_extract 15:50:47 INFO - mkdir: /builds/slave/test/build/tests 15:50:47 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')]} 15:50:47 INFO - https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:50:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 15:50:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 15:50:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/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 15:50:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/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 15:51:08 INFO - Downloaded 1199 bytes. 15:51:08 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 15:51:08 INFO - Using the following test package requirements: 15:51:08 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 15:51:08 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:51:08 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 15:51:08 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:51:08 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 15:51:08 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:51:08 INFO - u'jsshell-mac.zip'], 15:51:08 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:51:08 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 15:51:08 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 15:51:08 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:51:08 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 15:51:08 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:51:08 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 15:51:08 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:51:08 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 15:51:08 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:51:08 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 15:51:08 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 15:51:08 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')]} 15:51:08 INFO - https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:51:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:51:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:51:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/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 15:51:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/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 15:51:19 INFO - Downloaded 18463801 bytes. 15:51:19 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'] 15:51:19 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 15:51:23 INFO - Return code: 0 15:51:23 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 15:51:23 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')]} 15:51:23 INFO - https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:51:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:51:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:51:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/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 15:51:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/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 15:51:45 INFO - Downloaded 18463801 bytes. 15:51:45 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'] 15:51:45 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 15:51:49 INFO - Return code: 0 15:51: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')]} 15:51:49 INFO - https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:51:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 15:51:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 15:51:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/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 15:51:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/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 15:51:50 INFO - Downloaded 10840145 bytes. 15:51:50 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'] 15:51:50 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 15:51:50 INFO - Return code: 0 15:51:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:51:50 INFO - https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 15:51:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:51:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:51:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 15:51:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/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 15:51:53 INFO - Downloaded 114159654 bytes. 15:51:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:51:53 INFO - mkdir: /builds/slave/test/properties 15:51:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:51:53 INFO - Writing to file /builds/slave/test/properties/build_url 15:51:53 INFO - Contents: 15:51:53 INFO - build_url:https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:51:54 INFO - Running post-action listener: _resource_record_post_action 15:51:54 INFO - Running post-action listener: set_extra_try_arguments 15:51:54 INFO - ##### 15:51:54 INFO - ##### Running populate-webroot step. 15:51:54 INFO - ##### 15:51:54 INFO - Running pre-action listener: _resource_record_pre_action 15:51:54 INFO - Running main action method: populate_webroot 15:51:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:51:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:51:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:51:54 INFO - '--tppagecycles', 15:51:54 INFO - '7'], 15:51:54 INFO - 'tests': ['tsvgm']}}, 15:51:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:51:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:51:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:51:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:51:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:51:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:51:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:54 INFO - 'talos_options': ['--disable-e10s'], 15:51:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:51:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:51:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:54 INFO - 'talos_options': ['--disable-e10s'], 15:51:54 INFO - 'tests': ['damp', 'tps']}, 15:51:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:54 INFO - 'tests': ['damp', 'tps']}, 15:51:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:51:54 INFO - 'tests': ['dromaeo_dom']}, 15:51:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:51:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:51:54 INFO - 'tests': ['a11yr', 15:51:54 INFO - 'ts_paint', 15:51:54 INFO - 'tpaint', 15:51:54 INFO - 'sessionrestore', 15:51:54 INFO - 'sessionrestore_no_auto_restore', 15:51:54 INFO - 'tabpaint']}, 15:51:54 INFO - 'other-e10s': {'tests': ['a11yr', 15:51:54 INFO - 'ts_paint', 15:51:54 INFO - 'tpaint', 15:51:54 INFO - 'sessionrestore', 15:51:54 INFO - 'sessionrestore_no_auto_restore', 15:51:54 INFO - 'tabpaint']}, 15:51:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:51:54 INFO - 'ts_paint', 15:51:54 INFO - 'tpaint', 15:51:54 INFO - 'sessionrestore', 15:51:54 INFO - 'sessionrestore_no_auto_restore', 15:51:54 INFO - 'tabpaint']}, 15:51:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:51:54 INFO - 'ts_paint', 15:51:54 INFO - 'tpaint', 15:51:54 INFO - 'sessionrestore', 15:51:54 INFO - 'sessionrestore_no_auto_restore', 15:51:54 INFO - 'tabpaint']}, 15:51:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:51:54 INFO - 'tests': ['a11yr', 15:51:54 INFO - 'ts_paint', 15:51:54 INFO - 'tpaint', 15:51:54 INFO - 'sessionrestore', 15:51:54 INFO - 'sessionrestore_no_auto_restore', 15:51:54 INFO - 'tabpaint']}, 15:51:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:51:54 INFO - 'tests': ['a11yr', 15:51:54 INFO - 'ts_paint', 15:51:54 INFO - 'tpaint', 15:51:54 INFO - 'sessionrestore', 15:51:54 INFO - 'sessionrestore_no_auto_restore', 15:51:54 INFO - 'tabpaint']}, 15:51:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:51:54 INFO - 'tests': ['tsvgx', 15:51:54 INFO - 'tsvgr_opacity', 15:51:54 INFO - 'tart', 15:51:54 INFO - 'tscrollx', 15:51:54 INFO - 'cart']}, 15:51:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:51:54 INFO - 'tsvgr_opacity', 15:51:54 INFO - 'tart', 15:51:54 INFO - 'tscrollx', 15:51:54 INFO - 'cart']}, 15:51:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:54 INFO - 'talos_options': ['--disable-e10s'], 15:51:54 INFO - 'tests': ['tp5o']}, 15:51:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:54 INFO - 'tests': ['tp5o']}, 15:51:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:54 INFO - 'talos_options': ['--disable-e10s', 15:51:54 INFO - '--xperf_path', 15:51:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:51:54 INFO - 'tests': ['tp5n']}, 15:51:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:54 INFO - 'talos_options': ['--xperf_path', 15:51:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:51:54 INFO - 'tests': ['tp5n']}}, 15:51:54 INFO - 'talos.zip': {'path': '', 15:51:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:51:54 INFO - Running post-action listener: _resource_record_post_action 15:51:54 INFO - ##### 15:51:54 INFO - ##### Running create-virtualenv step. 15:51:54 INFO - ##### 15:51:54 INFO - Running pre-action listener: _resource_record_pre_action 15:51:54 INFO - Running main action method: create_virtualenv 15:51:54 INFO - Creating virtualenv /builds/slave/test/build/venv 15:51: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 15:51:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:51:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:51:54 INFO - Using real prefix '/tools/python27' 15:51:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:51:55 INFO - Installing distribute.............................................................................................................................................................................................done. 15:51:58 INFO - Installing pip.................done. 15:51:58 INFO - Return code: 0 15:51:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:51: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')]} 15:51:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51: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')]} 15:51:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51: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 0x10c073a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c083420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc28692390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf12e00>, '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 0x10bd7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf0db20>, '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.M4ICUpDxpj/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.ehpildmjMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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 15:51: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 15:51: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 15:51:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M4ICUpDxpj/Render', 15:51:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:58 INFO - 'HOME': '/Users/cltbld', 15:51:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:58 INFO - 'LOGNAME': 'cltbld', 15:51:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:58 INFO - 'MOZ_NO_REMOTE': '1', 15:51:58 INFO - 'NO_EM_RESTART': '1', 15:51:58 INFO - 'PAGER': '/bin/cat', 15:51:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:58 INFO - 'PWD': '/builds/slave/test', 15:51:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:58 INFO - 'SHELL': '/bin/bash', 15:51:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ehpildmjMm/Listeners', 15:51:58 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 15:51:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:58 INFO - 'USER': 'cltbld', 15:51:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:58 INFO - 'XPC_FLAGS': '0x0', 15:51:58 INFO - 'XPC_SERVICE_NAME': '0', 15:51:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51: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 15:51:58 INFO - Cleaning up... 15:51:58 INFO - Return code: 0 15:51:58 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:51: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')]} 15:51:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51: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')]} 15:51:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51: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 0x10c073a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c083420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc28692390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf12e00>, '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 0x10bd7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf0db20>, '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.M4ICUpDxpj/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.ehpildmjMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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 15:51: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 15:51: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 15:51:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M4ICUpDxpj/Render', 15:51:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:58 INFO - 'HOME': '/Users/cltbld', 15:51:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:58 INFO - 'LOGNAME': 'cltbld', 15:51:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:58 INFO - 'MOZ_NO_REMOTE': '1', 15:51:58 INFO - 'NO_EM_RESTART': '1', 15:51:58 INFO - 'PAGER': '/bin/cat', 15:51:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:58 INFO - 'PWD': '/builds/slave/test', 15:51:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:58 INFO - 'SHELL': '/bin/bash', 15:51:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ehpildmjMm/Listeners', 15:51:58 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 15:51:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:58 INFO - 'USER': 'cltbld', 15:51:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:58 INFO - 'XPC_FLAGS': '0x0', 15:51:58 INFO - 'XPC_SERVICE_NAME': '0', 15:51:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:58 INFO - Downloading/unpacking psutil>=0.7.1 15:51:58 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 15:51:58 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 15:51:58 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 15:51:58 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 15:51:58 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 15:51:58 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 15:52:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:52:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:52:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:52:02 INFO - Installing collected packages: psutil 15:52:02 INFO - Running setup.py install for psutil 15:52:02 INFO - building 'psutil._psutil_osx' extension 15:52: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 15:52: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 15:52: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 15:52:03 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 15:52:03 INFO - building 'psutil._psutil_posix' extension 15:52: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 15:52:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:52:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:52:03 INFO - ^ 15:52:03 INFO - 1 warning generated. 15:52: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 15:52:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:52:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:52:03 INFO - Successfully installed psutil 15:52:03 INFO - Cleaning up... 15:52:03 INFO - Return code: 0 15:52:03 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:52: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')]} 15:52:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:52:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52: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')]} 15:52:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:52:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52: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 0x10c073a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c083420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc28692390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf12e00>, '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 0x10bd7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf0db20>, '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.M4ICUpDxpj/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.ehpildmjMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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 15:52: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 15:52: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 15:52:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M4ICUpDxpj/Render', 15:52:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:52:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:52:03 INFO - 'HOME': '/Users/cltbld', 15:52:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:52:03 INFO - 'LOGNAME': 'cltbld', 15:52:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:52:03 INFO - 'MOZ_NO_REMOTE': '1', 15:52:03 INFO - 'NO_EM_RESTART': '1', 15:52:03 INFO - 'PAGER': '/bin/cat', 15:52:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:52:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:52:03 INFO - 'PWD': '/builds/slave/test', 15:52:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:52:03 INFO - 'SHELL': '/bin/bash', 15:52:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ehpildmjMm/Listeners', 15:52:03 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 15:52:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:52:03 INFO - 'USER': 'cltbld', 15:52:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:52:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:52:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:52:03 INFO - 'XPC_FLAGS': '0x0', 15:52:03 INFO - 'XPC_SERVICE_NAME': '0', 15:52:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:52:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:52:03 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:52: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 15:52: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 15:52: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 15:52: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 15:52: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 15:52: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 15:52:06 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:52:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:52: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) 15:52:06 INFO - Installing collected packages: mozsystemmonitor 15:52:06 INFO - Running setup.py install for mozsystemmonitor 15:52:06 INFO - Successfully installed mozsystemmonitor 15:52:06 INFO - Cleaning up... 15:52:06 INFO - Return code: 0 15:52:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:52: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')]} 15:52:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:52:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52: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')]} 15:52:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:52:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52: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 0x10c073a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c083420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc28692390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf12e00>, '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 0x10bd7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf0db20>, '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.M4ICUpDxpj/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.ehpildmjMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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 15:52: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 15:52: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 15:52:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M4ICUpDxpj/Render', 15:52:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:52:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:52:06 INFO - 'HOME': '/Users/cltbld', 15:52:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:52:06 INFO - 'LOGNAME': 'cltbld', 15:52:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:52:06 INFO - 'MOZ_NO_REMOTE': '1', 15:52:06 INFO - 'NO_EM_RESTART': '1', 15:52:06 INFO - 'PAGER': '/bin/cat', 15:52:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:52:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:52:06 INFO - 'PWD': '/builds/slave/test', 15:52:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:52:06 INFO - 'SHELL': '/bin/bash', 15:52:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ehpildmjMm/Listeners', 15:52:06 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 15:52:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:52:06 INFO - 'USER': 'cltbld', 15:52:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:52:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:52:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:52:06 INFO - 'XPC_FLAGS': '0x0', 15:52:06 INFO - 'XPC_SERVICE_NAME': '0', 15:52:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:52:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:52:06 INFO - Downloading/unpacking blobuploader==1.2.4 15:52: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 15:52: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 15:52: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 15:52: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 15:52: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 15:52: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 15:52:09 INFO - Downloading blobuploader-1.2.4.tar.gz 15:52:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:52:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:52: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 15:52: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 15:52: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 15:52: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 15:52: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 15:52: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 15:52:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:52:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:52: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 15:52: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 15:52: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 15:52: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 15:52: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 15:52: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 15:52:10 INFO - Downloading docopt-0.6.1.tar.gz 15:52:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:52:10 INFO - Installing collected packages: blobuploader, requests, docopt 15:52:10 INFO - Running setup.py install for blobuploader 15:52:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:52:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:52:10 INFO - Running setup.py install for requests 15:52:11 INFO - Running setup.py install for docopt 15:52:11 INFO - Successfully installed blobuploader requests docopt 15:52:11 INFO - Cleaning up... 15:52:11 INFO - Return code: 0 15:52:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:52: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')]} 15:52:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:52:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52: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')]} 15:52:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:52:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52: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 0x10c073a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c083420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc28692390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf12e00>, '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 0x10bd7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf0db20>, '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.M4ICUpDxpj/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.ehpildmjMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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 15:52: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 15:52: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 15:52:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M4ICUpDxpj/Render', 15:52:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:52:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:52:11 INFO - 'HOME': '/Users/cltbld', 15:52:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:52:11 INFO - 'LOGNAME': 'cltbld', 15:52:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:52:11 INFO - 'MOZ_NO_REMOTE': '1', 15:52:11 INFO - 'NO_EM_RESTART': '1', 15:52:11 INFO - 'PAGER': '/bin/cat', 15:52:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:52:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:52:11 INFO - 'PWD': '/builds/slave/test', 15:52:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:52:11 INFO - 'SHELL': '/bin/bash', 15:52:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ehpildmjMm/Listeners', 15:52:11 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 15:52:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:52:11 INFO - 'USER': 'cltbld', 15:52:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:52:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:52:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:52:11 INFO - 'XPC_FLAGS': '0x0', 15:52:11 INFO - 'XPC_SERVICE_NAME': '0', 15:52:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:52:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:52:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:52:11 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-6ktzhK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:52:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:52:11 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-WHWlbL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:52:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:52:11 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-j5pI_S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:52:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:52:11 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-LXf85b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:52:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:52:11 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-ht5ecg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:52:12 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-l5LD_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:52:12 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-qCaxlb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:52:12 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-ytFUdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:52:12 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-E8t2al-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:52:12 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-gLt_7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:52:12 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-1Tyi1L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:52:12 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-xXKw9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:52:12 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-iu1ZxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:52:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:52:12 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-u8TJtu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:52:13 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-Sz27ue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:52:13 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-m6uCEV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:52:13 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-Jm4ri8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:52:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:52:13 INFO - Running setup.py install for manifestparser 15:52:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:52:13 INFO - Running setup.py install for mozcrash 15:52:13 INFO - Running setup.py install for mozdebug 15:52:13 INFO - Running setup.py install for mozdevice 15:52:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:52:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:52:13 INFO - Running setup.py install for mozfile 15:52:14 INFO - Running setup.py install for mozhttpd 15:52:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:52:14 INFO - Running setup.py install for mozinfo 15:52:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:52:14 INFO - Running setup.py install for mozInstall 15:52:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:52:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:52:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:52:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:52:14 INFO - Running setup.py install for mozleak 15:52:14 INFO - Running setup.py install for mozlog 15:52:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:52:14 INFO - Running setup.py install for moznetwork 15:52:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:52:14 INFO - Running setup.py install for mozprocess 15:52:15 INFO - Running setup.py install for mozprofile 15:52:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:52:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:52:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:52:15 INFO - Running setup.py install for mozrunner 15:52:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:52:15 INFO - Running setup.py install for mozscreenshot 15:52:15 INFO - Running setup.py install for moztest 15:52:15 INFO - Running setup.py install for mozversion 15:52:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:52:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:52:15 INFO - Cleaning up... 15:52:15 INFO - Return code: 0 15:52:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:52: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')]} 15:52:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:52:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52: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')]} 15:52:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:52:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52: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 0x10c073a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c083420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc28692390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf12e00>, '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 0x10bd7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf0db20>, '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.M4ICUpDxpj/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.ehpildmjMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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 15:52: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 15:52: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 15:52:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M4ICUpDxpj/Render', 15:52:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:52:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:52:15 INFO - 'HOME': '/Users/cltbld', 15:52:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:52:15 INFO - 'LOGNAME': 'cltbld', 15:52:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:52:15 INFO - 'MOZ_NO_REMOTE': '1', 15:52:15 INFO - 'NO_EM_RESTART': '1', 15:52:15 INFO - 'PAGER': '/bin/cat', 15:52:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:52:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:52:15 INFO - 'PWD': '/builds/slave/test', 15:52:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:52:15 INFO - 'SHELL': '/bin/bash', 15:52:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ehpildmjMm/Listeners', 15:52:15 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 15:52:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:52:15 INFO - 'USER': 'cltbld', 15:52:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:52:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:52:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:52:15 INFO - 'XPC_FLAGS': '0x0', 15:52:15 INFO - 'XPC_SERVICE_NAME': '0', 15:52:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:52:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:52:16 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-Wb9mBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:52: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)) 15:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:52:16 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-VLrEoA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:52: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)) 15:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:52:16 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-_PWLsN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:52: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)) 15:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:52:16 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-as4jlA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:52: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)) 15:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:52:16 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-DFLv27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:52: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)) 15:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:52:16 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-wd_8nx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:52: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)) 15:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:52:16 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-iQzLuw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:52: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)) 15:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:52:16 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-Oy8Ye9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:52:16 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)) 15:52:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:52:16 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-x89ZGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:52: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)) 15:52:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:52:17 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-94qMT7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:52: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)) 15:52:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:52:17 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-suAZlH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:52: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)) 15:52:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:52:17 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-bwAFKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:52: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)) 15:52:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:52:17 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-3XqXge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:52: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)) 15:52:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:52:17 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-eYwbIX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:52: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)) 15:52:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:52:17 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-FEETe0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:52: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)) 15:52:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:52:17 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-owAqDZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:52: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)) 15:52:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:52:17 INFO - Running setup.py (path:/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/pip-l0jeGw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:52:17 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)) 15:52:17 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)) 15:52:17 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)) 15:52:17 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)) 15:52:17 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)) 15:52:17 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)) 15:52:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:52:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:52:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:52:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:52:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:52:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:52:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:52:20 INFO - Downloading blessings-1.6.tar.gz 15:52:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:52:20 INFO - Installing collected packages: blessings 15:52:20 INFO - Running setup.py install for blessings 15:52:20 INFO - Successfully installed blessings 15:52:20 INFO - Cleaning up... 15:52:20 INFO - Return code: 0 15:52:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:52:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:52:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:52:21 INFO - Reading from file tmpfile_stdout 15:52:21 INFO - Current package versions: 15:52:21 INFO - blessings == 1.6 15:52:21 INFO - blobuploader == 1.2.4 15:52:21 INFO - docopt == 0.6.1 15:52:21 INFO - manifestparser == 1.1 15:52:21 INFO - mozInstall == 1.12 15:52:21 INFO - mozcrash == 0.17 15:52:21 INFO - mozdebug == 0.1 15:52:21 INFO - mozdevice == 0.48 15:52:21 INFO - mozfile == 1.2 15:52:21 INFO - mozhttpd == 0.7 15:52:21 INFO - mozinfo == 0.9 15:52:21 INFO - mozleak == 0.1 15:52:21 INFO - mozlog == 3.1 15:52:21 INFO - moznetwork == 0.27 15:52:21 INFO - mozprocess == 0.22 15:52:21 INFO - mozprofile == 0.28 15:52:21 INFO - mozrunner == 6.11 15:52:21 INFO - mozscreenshot == 0.1 15:52:21 INFO - mozsystemmonitor == 0.0 15:52:21 INFO - moztest == 0.7 15:52:21 INFO - mozversion == 1.4 15:52:21 INFO - psutil == 3.1.1 15:52:21 INFO - requests == 1.2.3 15:52:21 INFO - wsgiref == 0.1.2 15:52:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:52: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')]} 15:52:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:52:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52: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')]} 15:52:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:52:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52: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 0x10c073a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c083420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc28692390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf12e00>, '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 0x10bd7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf0db20>, '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.M4ICUpDxpj/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.ehpildmjMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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 15:52: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 15:52: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 15:52:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M4ICUpDxpj/Render', 15:52:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:52:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:52:21 INFO - 'HOME': '/Users/cltbld', 15:52:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:52:21 INFO - 'LOGNAME': 'cltbld', 15:52:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:52:21 INFO - 'MOZ_NO_REMOTE': '1', 15:52:21 INFO - 'NO_EM_RESTART': '1', 15:52:21 INFO - 'PAGER': '/bin/cat', 15:52:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:52:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:52:21 INFO - 'PWD': '/builds/slave/test', 15:52:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:52:21 INFO - 'SHELL': '/bin/bash', 15:52:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ehpildmjMm/Listeners', 15:52:21 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 15:52:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:52:21 INFO - 'USER': 'cltbld', 15:52:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:52:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:52:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:52:21 INFO - 'XPC_FLAGS': '0x0', 15:52:21 INFO - 'XPC_SERVICE_NAME': '0', 15:52:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:52:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:52: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)) 15:52: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)) 15:52: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)) 15:52: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)) 15:52: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)) 15:52: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)) 15:52: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)) 15:52: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)) 15:52: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)) 15:52: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)) 15:52: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)) 15:52:21 INFO - Cleaning up... 15:52:21 INFO - Return code: 0 15:52:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:52: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')]} 15:52:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:52:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52: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')]} 15:52:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:52:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52: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 0x10c073a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c083420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc28692390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf12e00>, '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 0x10bd7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf0db20>, '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.M4ICUpDxpj/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.ehpildmjMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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 15:52: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 15:52: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 15:52:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M4ICUpDxpj/Render', 15:52:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:52:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:52:21 INFO - 'HOME': '/Users/cltbld', 15:52:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:52:21 INFO - 'LOGNAME': 'cltbld', 15:52:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:52:21 INFO - 'MOZ_NO_REMOTE': '1', 15:52:21 INFO - 'NO_EM_RESTART': '1', 15:52:21 INFO - 'PAGER': '/bin/cat', 15:52:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:52:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:52:21 INFO - 'PWD': '/builds/slave/test', 15:52:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:52:21 INFO - 'SHELL': '/bin/bash', 15:52:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ehpildmjMm/Listeners', 15:52:21 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 15:52:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:52:21 INFO - 'USER': 'cltbld', 15:52:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:52:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:52:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:52:21 INFO - 'XPC_FLAGS': '0x0', 15:52:21 INFO - 'XPC_SERVICE_NAME': '0', 15:52:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:52:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:52:21 INFO - Downloading/unpacking jsonschema 15:52: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 15:52: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 15:52: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 15:52: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 15:52: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 15:52: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 15:52:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:52:23 INFO - Downloading/unpacking functools32 (from jsonschema) 15:52:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:52:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:52:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:52:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:52:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:52:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:52:24 INFO - Downloading functools32-3.2.3-2.tar.gz 15:52:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:52:24 INFO - warning: no files found matching '*.txt' 15:52:24 INFO - no previously-included directories found matching 'build' 15:52:24 INFO - no previously-included directories found matching 'dist' 15:52:24 INFO - no previously-included directories found matching '.git*' 15:52:24 INFO - Installing collected packages: jsonschema, functools32 15:52:24 INFO - Running setup.py install for functools32 15:52:24 INFO - warning: no files found matching '*.txt' 15:52:24 INFO - no previously-included directories found matching 'build' 15:52:24 INFO - no previously-included directories found matching 'dist' 15:52:24 INFO - no previously-included directories found matching '.git*' 15:52:24 INFO - Successfully installed jsonschema functools32 15:52:24 INFO - Cleaning up... 15:52:24 INFO - Return code: 0 15:52:24 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:52:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:52:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:52:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 15:52:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:52:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:52:24 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 0x10c073a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c083420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc28692390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf12e00>, '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 0x10bd7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf0db20>, '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.M4ICUpDxpj/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.ehpildmjMm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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 15:52:24 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 15:52:24 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 15:52:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M4ICUpDxpj/Render', 15:52:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:52:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:52:24 INFO - 'HOME': '/Users/cltbld', 15:52:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:52:24 INFO - 'LOGNAME': 'cltbld', 15:52:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:52:24 INFO - 'MOZ_NO_REMOTE': '1', 15:52:24 INFO - 'NO_EM_RESTART': '1', 15:52:24 INFO - 'PAGER': '/bin/cat', 15:52:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:52:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:52:24 INFO - 'PWD': '/builds/slave/test', 15:52:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:52:24 INFO - 'SHELL': '/bin/bash', 15:52:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ehpildmjMm/Listeners', 15:52:24 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 15:52:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:52:24 INFO - 'USER': 'cltbld', 15:52:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:52:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:52:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:52:24 INFO - 'XPC_FLAGS': '0x0', 15:52:24 INFO - 'XPC_SERVICE_NAME': '0', 15:52:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:52:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:52:25 INFO - Downloading/unpacking flake8 15:52: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 15:52: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 15:52: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 15:52: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 15:52: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 15:52: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 15:52:27 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:52:27 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:52: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 15:52: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 15:52: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 15:52: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 15:52: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 15:52: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 15:52:27 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:52:27 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:52: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 15:52: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 15:52: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 15:52: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 15:52: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 15:52: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 15:52:28 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:52: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 15:52: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 15:52: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 15:52: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 15:52: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 15:52: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 15:52:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:52:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:52:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:52:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:52:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:52:28 INFO - no previously-included directories found matching 'docs/_build' 15:52:28 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:52:28 INFO - Running setup.py install for pep8 15:52:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:52:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:52:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:52:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:52:28 INFO - no previously-included directories found matching 'docs/_build' 15:52:28 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:52:28 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:52:28 INFO - Cleaning up... 15:52:29 INFO - Return code: 0 15:52:29 INFO - Running post-action listener: _resource_record_post_action 15:52:29 INFO - Running post-action listener: _start_resource_monitoring 15:52:29 INFO - Starting resource monitoring. 15:52:29 INFO - ##### 15:52:29 INFO - ##### Running install step. 15:52:29 INFO - ##### 15:52:29 INFO - Running pre-action listener: _resource_record_pre_action 15:52:29 INFO - Running main action method: install 15:52:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:52:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:52:29 INFO - Reading from file tmpfile_stdout 15:52:29 INFO - Detecting whether we're running mozinstall >=1.0... 15:52:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:52:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:52:29 INFO - Reading from file tmpfile_stdout 15:52:29 INFO - Output received: 15:52:29 INFO - Usage: mozinstall [options] installer 15:52:29 INFO - Options: 15:52:29 INFO - -h, --help show this help message and exit 15:52:29 INFO - -d DEST, --destination=DEST 15:52:29 INFO - Directory to install application into. [default: 15:52:29 INFO - "/builds/slave/test"] 15:52:29 INFO - --app=APP Application being installed. [default: firefox] 15:52:29 INFO - mkdir: /builds/slave/test/build/application 15:52: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'] 15:52: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 15:53:00 INFO - Reading from file tmpfile_stdout 15:53:00 INFO - Output received: 15:53:00 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 15:53:00 INFO - Running post-action listener: _resource_record_post_action 15:53:00 INFO - ##### 15:53:00 INFO - ##### Running run-tests step. 15:53:00 INFO - ##### 15:53:00 INFO - Running pre-action listener: _resource_record_pre_action 15:53:00 INFO - Running main action method: run_tests 15:53:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:53:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:53:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:53:00 INFO - 2016-05-30 15:53:00.850 screenresolution[1644:8231] starting screenresolution argv=screenresolution get 15:53:00 INFO - 2016-05-30 15:53:00.853 screenresolution[1644:8231] Display 0: 1600x1200x32@60 15:53:00 INFO - 2016-05-30 15:53:00.859 screenresolution[1645:8234] starting screenresolution argv=screenresolution list 15:53:00 INFO - Available Modes on Display 0 15:53:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:53:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:53:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:53:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:53:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:53:01 INFO - Intel Iris: 15:53:01 INFO - Chipset Model: Intel Iris 15:53:01 INFO - Type: GPU 15:53:01 INFO - Bus: Built-In 15:53:01 INFO - VRAM (Dynamic, Max): 1536 MB 15:53:01 INFO - Vendor: Intel (0x8086) 15:53:01 INFO - Device ID: 0x0a2e 15:53:01 INFO - Revision ID: 0x0009 15:53:01 INFO - Displays: 15:53:01 INFO - Display: 15:53:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:53:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:53:01 INFO - Display Serial Number: 1600x1200 60 15:53:01 INFO - Main Display: Yes 15:53:01 INFO - Mirror: Off 15:53:01 INFO - Online: Yes 15:53:01 INFO - Rotation: Supported 15:53:01 INFO - Return code: 0 15:53:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:53:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:53:01 INFO - Python 2.7.3 15:53:01 INFO - Return code: 0 15:53:01 INFO - grabbing minidump binary from tooltool 15:53: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')]} 15:53: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 0x10bf12e00>, '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 0x10bd7bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf0db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:53: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 15:53: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 15:53:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:53:01 INFO - Return code: 0 15:53:01 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:53:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:53:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:53:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:53:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:53:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:53:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:53:02 INFO - Return code: 0 15:53: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/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0225', '--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 15:53: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/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0225 --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 15:53:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M4ICUpDxpj/Render', 15:53:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:53:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:53:02 INFO - 'HOME': '/Users/cltbld', 15:53:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:53:02 INFO - 'LOGNAME': 'cltbld', 15:53:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:53:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:53:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:53:02 INFO - 'MOZ_NO_REMOTE': '1', 15:53:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:53:02 INFO - 'NO_EM_RESTART': '1', 15:53:02 INFO - 'PAGER': '/bin/cat', 15:53:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:53:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:53:02 INFO - 'PWD': '/builds/slave/test', 15:53:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:53:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:53:02 INFO - 'SHELL': '/bin/bash', 15:53:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ehpildmjMm/Listeners', 15:53:02 INFO - 'TMPDIR': '/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/', 15:53:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:53:02 INFO - 'USER': 'cltbld', 15:53:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:53:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:53:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:53:02 INFO - 'XPC_FLAGS': '0x0', 15:53:02 INFO - 'XPC_SERVICE_NAME': '0', 15:53:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:53: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/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0225', '--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 15:53:02 INFO - mozversion application_buildid: 20160530120549 15:53:02 INFO - mozversion application_changeset: 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 15:53:02 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:53:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:53:02 INFO - mozversion application_name: Firefox 15:53:02 INFO - mozversion application_remotingname: firefox-dev 15:53:02 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:53:02 INFO - mozversion application_vendor: Mozilla 15:53:02 INFO - mozversion application_version: 48.0a2 15:53:02 INFO - mozversion platform_buildid: 20160530120549 15:53:02 INFO - mozversion platform_changeset: 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 15:53:02 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:53:02 INFO - mozversion platform_version: 48.0a2 15:53:02 INFO - using testdate: 1464648782 15:53:02 INFO - actual date: 1464648782 15:53:02 INFO - starting webserver on 'localhost:49270' 15:53:02 INFO - SUITE-START | Running 2 tests 15:53:02 INFO - TEST-START | dromaeo_css 15:53:02 INFO - operating with platform_type : mac_ 15:53:02 INFO - Initialising browser for dromaeo_css test... 15:53:02 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/tmp5XXb7N/profile http://localhost:49270/getInfo.html) 15:53:05 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 15:53:05 INFO - PROCESS | 1663 | colorDepth:24 15:53:05 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 15:53:05 INFO - PROCESS | 1663 | __metrics 15:53:05 ERROR - PROCESS | 1663 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2940: TypeError: this.tabs is undefined 15:53:06 INFO - TEST-INFO | 1663: exit 0 15:53:06 INFO - Browser initialized. 15:53:06 INFO - Running cycle 1/1 for dromaeo_css test... 15:53:06 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/tmp5XXb7N/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:53:18 INFO - PROCESS | 1667 | RSS: Main: 220798976 15:53:18 INFO - PROCESS | 1667 | 15:54:07 INFO - PROCESS | 1667 | 1464648847805 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] 15:56:18 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 15:56:20 INFO - PROCESS | 1667 | RSS: Main: 231718912 15:56:20 INFO - PROCESS | 1667 | 15:59:22 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 15:59:22 INFO - PROCESS | 1667 | RSS: Main: 243855360 15:59:22 INFO - PROCESS | 1667 | 16:02:24 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 16:02:25 INFO - PROCESS | 1667 | RSS: Main: 245391360 16:02:25 INFO - PROCESS | 1667 | 16:05:27 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 16:05:27 INFO - PROCESS | 1667 | RSS: Main: 247119872 16:05:27 INFO - PROCESS | 1667 | 16:08:30 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 16:08:30 INFO - PROCESS | 1667 | RSS: Main: 249958400 16:08:30 INFO - PROCESS | 1667 | 16:11:33 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 16:11:33 INFO - PROCESS | 1667 | RSS: Main: 251154432 16:11:33 INFO - PROCESS | 1667 | 16:11:33 INFO - PROCESS | 1667 | __start_tp_report 16:11:33 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 16:11:33 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 16:11:33 INFO - PROCESS | 1667 | |i|pagename|runs| 16:11:33 INFO - PROCESS | 1667 | |0;dojo.html;2982.5973493578367;3260.826102603064;3426.9205200361866;3467.133216695826;3518.4898189762484;4640.257558790594;5348.598855085869;5629.60592758507;5635.971820140904;5640.774369025234;5670.659760414368;5703.800366987156;5706.885862282752;5711.514521265697;5715.485606295443;4467.017256203646;5098.056859480989;5222.399424066224;5288.206768984644;5289.735513224343;4470.932936005963;5020.468757029104;5115.7442127893455;5280.8910128872985;5415.48552887475;4681.812727490897;4934.4572097069295;5300.151975683892;5312.6281160318795;5313.282706834567;4307.686155722504;5119.257707632372;5275.6043296752705;5311.681299122046;5323.733813309319;5182.455842136559;5227.79088836446;5250.396204436501;5260.368755749297;5277.102892508283;5705;5714.542836573064;5749.885002299972;5762.366139724627;5796.13058041293;5169.069567477853;6026.0960855871535;6165.630062196237;6214.937850621481;6272.87454250917;6216.378362163793;6264.404881536247;6288.842778930518;6295.874082518324;6305.527085468562;5811.82564523065;5833.912491312633;5837.562182836219;5851.239338885917;5865.794697185621;4737.431508218967;6227.439530442282;6267.2479302484;6324.936750632435;6340.587861788969;6209.720562574695;6212.4408803207925;6238.189035425366;6245.594036387621;6247.562670613104;6124.142620033199;6309.305976342599;6310.6213627182515;6328.905066424008;6345.238571371465;6093.6039157454625;6132.018876979661;6191.164192834;6198.566100372931;6214.627122372672;5045.823396181116;6193.411625895623;6244.219472565929;6262.436380725757;6284.842878927972;4872.3665923430435;4879.194932836042;4908.190148625436;4953.23224900141;4966.602671786249;3900.687944964396;4419.911601767946;4452.933206001912;4460.1525710114975;4474.932876006797;5766.423357664284;5833.270841144857;5840.620359676607;5845.035569131046;5845.386234445322;6069.484093851984;6145.893739127;6210.968945155336;6288.056791481315;6311.305756366796;5822.534197264209;5831.329397119301;5863.589548731633;5876.764929402776;5882.3529411764675;5445.37378201504;5445.673259604437;5446.1558458439;5454.836354909359;5457.154141108134;6204.13142160107;6301.590396624205;6346.555741098079;6374.298827129012;6388.936110638927;6242.406971337716;6257.217847769029;6271.498280137669;6282.874342513215;6282.947498863299;6397.168368112116;6418.454431373386;6425.7750978717395;6432.710528026344;6442.09810626522;5172.431032586337;6010.639361638316;6126.761967422209;6137.867325793431;6247.625142491465;6241.3134555197985;6284.717187726655;6302.401271879165;6304.495640348853;6308.873822523432;4959.156943319716;6163.075538669235;6166.013437849922;6167.753289868535;6174.6912654368;5953.851153721192;6076.270847498328;6128.295246046761;6166.229221347332;6180.289266734203;4843.491433399583;5896.970515147397;5898.439648233411;6030.728617212324;6034.668093254913;5691.146328050825;6157.907631385622;6166.876662466815;6170.290416602148;6185.072239164161;5925.644461332334;5945.227120474311;6004.42957918997;6036.849078773066;6071.848203794941 16:11:33 INFO - PROCESS | 1667 | |1;ext.html;495.9156943319633;548.8518100112972;550.1307933465124;559.9272094627697;604.6644112517552;38254.80872595637;39059;39138.80430597847;39185.412218816666;39199.21601567974;39967.80016099923;40585;40658.79670601643;40789.184216315654;40936.77189684307;9380.718578442658;9600.855987160197;9608.183304419133;9618.855717164248;9644.75888102796;9464.81070378592;9707.320487565874;9732.65935692251;9760.31677782552;9764.21886249102;9543.236541076732;9583.185429238523;9653.903460965406;9681.273904457159;9731.562079706395;9627.13355797978;9635.325527213097;9682.854757178648;9688.176504997013;9742.1719153872;9566.330356875022;9674;9698.9515052425;9703.514824258758;9708.17480514157;38847.61152388468;38935.41596876049;39326;39389.60610393888;39399.40900886489;2846.9430611387866;3325.5344251804527;3360.865565377382;3363.865445382182;3400.523926650271;27750.444991100267;27760.722392776017;28098.719012809815;28579.428411431654;28606.856965715247;2736.7263273672675;2775.5836624506283;2824.4068745563454;2837.1914004508617;2893.9131826045254;376349.1182544097;448577;452779;455984;456014;51562.74218628883;59282;64428.67785661042;70953.93569096467;72345.27654723385;70139.64930175316;70339.29660703328;70565;70731.64634176796;70866.64566677134;17848.286068557372;18070.638587228183;18124.909375453037;18147.364842230458;18214.544636384195;12205;13634.727305453835;13820.37808298629;13820.654483637789;13884.889208863267;21384.679229811565;21474.355769326947;21709.457263568536;21719;21801;8642.536876990154;8827.45519534079;8870.004249043914;8878.28973682104;8887.377883548219;967.8548217767251;970.369259981018;998.8962196772607;1037.4138611684525;1055.2929537210089;29592.852035739685;29839.701602984125;30078.097657070324;30254.84872575623;30427.087187384415;955.6607404371487;1181.2812358019062;1188.3879801902028;1208.172401904698;1286.9832832062752;12754.298513581842;12839.935800321125;13393;15406.537803865902;15576.376944922076;581059;638044;638931;644721;645609;58798.41201588015;58810.41189588135;59439.70280148571;59699.701501492214;59913;1207.9335636540075;1474.8156480439945;1478.0245038274686;1492.8731057860205;1493.8506149384975;24315;24882;27902.86048569744;29463.558046629747;29986.550201746555;44230.55769442264;49115.01769964652;50073.499265008344;50713.73215669638;50838;542100;548718;558366;559507;560620;41094.1781164381;42533.78733106315;42561.574384257;42986.14027719491;43220.78389608032;45321.77339113283;46197.307040393745;46650.76674616605;46845.765771170925;46903.53096469128;37420.43869341908;37632.81183594064;37686;37958;38386.6161338383;37007.44488832616;37138;37139.44290835586;37596.436053458674;37751;32359.676403235666;36859.262814743015;37491.81254093712;37709.622903771706;38412.80793596014;29712.702872970993;31589.5261571072;31678.683213167573;31759.52360714545;32117;24211.75788242095;24264;24282.392940176636;24299.149529766375;24537.509249815263 16:11:33 INFO - PROCESS | 1667 | |2;jquery.html;347.8973702757687;401.42996944339035;404.108939787768;408.92026054919296;408.9263932492152;15245.847541524581;15818.05091694499;20194;20489.692654610175;20844.895775521138;20487.385378438634;20692.793072069275;20719.689204661943;20912.372628821122;21403.92980350984;4998.975005124988;5646.124850648156;5862.149988251701;5955.612885162473;5982.551308651847;5629.7185140743;5971.761129554812;6018.3981601839905;6019.337872833985;6026.819195424122;5871;5891.440313170264;5925.4370834770625;5927.970360148193;5931.555133365015;5425.674459532421;5765.711714414262;5767.625104368202;5784.855378615547;5871.412858714137;4868.440129385131;5454;5501.174823776426;5505.3393592768725;5593.412691667357;13727.245001524912;13871.722565548633;18822.05889705523;24383.146589869448;25189.62215566768;1777.5111844242808;2152.2682288021992;2328.55757406094;2541.351955251399;2580.974190258092;9590.472524011177;9593.664221752275;9868.062534059252;9996.750081248027;10024.198064154923;1735.7483164941013;1755.332973470086;1755.9122043897883;1785.812489688577;1822.3894995176631;93093.53453232825;109406.45296773466;118308;121342;122326.38836805848;11659.475323610457;11890.227135236182;11902.107341949215;11904.523819047314;12329.568465103679;15011.24943752808;15117.622059448602;15147.848521514643;15150.393984240727;15159.469418570297;7363.410927125817;8934.642614295486;9142.268618510503;9159.129882661136;9175.632974680939;9167.95416022929;9174.633014679472;9180.219681327162;9214.907850921405;9285.62857485693;4587.197240482902;4639.953600463952;4676.3453116563705;4690.929636055462;4741.715497070187;1908.7900330963582;1962.8920409377336;2009.2164056018164;2018.7981201879695;2029.1680411031407;815.7675062607128;960.9855852162223;1022.3354819367471;1057.9788404231872;1080.1466841195524;20109.698354524695;20330.593388132154;20432.89783551073;20666.966651667357;20739.481512961996;973.7516933181993;975.3855071305175;996.6561536269975;1008.5411137932223;1045.2056437107854;10903.34579925207;11189.272697274651;11204.60783872577;11213.551457941754;11233.269837460539;527234;529524;537863;619639;630936;118039;134244;135431;136352;142430.28784856008;1016.6492560066765;1035.337384074193;1041.7083216699336;1048.4390850894888;1065.0680654427376;16357.446042625932;17938.5515362117;18136.274549017893;18255.1785169665;18322.72515912289;38327.616723832405;49688;50574;51595;52661.21008184957;76729;78113.21886781287;78273.60863195648;79677.60161199389;84116.57941710496;7939.126696063544;8182.22268884455;8301.252887240178;8343.707970221256;8362.581870906552;9219.354645174773;10657.20070994663;12447.004239660986;12515.874841251834;12884.871151288742;10853.674389768319;10944.627360069395;11025.393603351571;11125.610603628835;11201.103911686883;10720.249582529148;10821.891781082088;10873.782524349626;11015.504302306255;11176.608818691631;10875.238733288594;11045.447727613426;11356.977871991561;11562.421878906189;11568.363739994382;8090.474119182234;8198.1391953844;8234.094249632415;8265.628046738031;8467.49195048299;9720.41677499324;10948.069414099891;11060.668179954615;11086.390248536409;11165.274257173258 16:11:33 INFO - PROCESS | 1667 | |3;mootools.html;1324.8542660307364;1512.750396184629;1609.8470645288696;1971.4578490914996;1994.3917105282883;7895.447318687694;8666.653333866645;8770.122987701227;8808.854848869654;8845.823083538326;6781.35577120174;8601.311895048397;8721.302295816335;8723.825523489526;8800.943886733601;2449.853008819472;2460.766227208412;2477.9752202478016;2479.5536803375385;2483.1681386735377;2442.426029882985;2489.962650560243;2490.7758301752838;2492.663490428796;2500.624906264066;2476.1333533263396;2492.3270716906513;2506.6490691303234;2520.395105174754;2529.468811549577;2470.703515578124;2472.43134079161;2486.3286912533695;2492.8379655322346;2501.3371456564023;2446.5351583199313;2474.8020158387285;2488.962665560018;2489.2034548944166;2514.88683009265;8937;8985.595648195782;8992.550372481415;8997.235235004968;9003.00966893641;4580.244259697146;5425.511703946624;5556.972215138899;5572.275604171432;5607.775688972436;3019.290466740314;3043.4217498675403;3207.4066297734994;3460.169559305774;3496.2832619313235;1059.8622179116664;1061.389700921973;1064.8881867403888;1085.7114398127428;1089.7657003744232;11496.252743571642;11556.53773849045;11692.415379231004;11701.180917335874;11705.473253703603;4622.976885115553;4637.837675681335;4672.672912896064;4686.039361930796;4737.147313483606;9290;9311.90688093124;9332.113449222314;9340.299477539076;9343.71968840936;5255.264263003182;5273.762680679379;5341.4925582069645;5343.545798607084;5393.164059570773;4088.713790034669;4403.361512580658;4940.75296235194;4956.281339205795;4957.405111386656;3965.325894597925;3969.166475040216;4000.8599699010397;4009.3184158693093;4086.8773936781945;1239.0830785218873;1253.135336617731;1254.6549698832894;1260.4811202500964;1265.4052595280205;859.7377799771004;939.4551160326994;940.2759874896292;949.5584553182665;968.675493709601;5523.763869331189;5980.372060933626;5996;6116.3577824328695;6155.630662160284;466.54511850945056;469.4530871534624;472.1336347801822;487.92193249079975;1037.1754455207977;3444.431668774624;3478.339115568034;3795.886123416247;3919.235749028969;4044.1911617675996;10523.631672891412;10743;10829.891701083046;10894.237403381687;10912.945435272773;10510.054095131474;10561.155107591374;10575.576976920836;10672.466376681135;10676.252662313564;7902.170272121344;8188.385871059694;8198.877016844752;8239.299659528888;8285.8342833143;5643.830685079454;5923.703814809329;5945.583809133319;5948.8102379525135;6049.062395328731;7327.413806895543;8078.515289082674;9710.22318214527;9758.609655613696;9777.657781977596;9636.75908102275;9720.465374404475;9916.801663966537;9917.900820991697;9949.054839790208;6195.411435913581;6994.755183568551;7493.88759168602;7540.924590754023;7682.423818213768;8263;8295.709650162215;8325.95837020811;8370;8432.915670843213;7308.890366644611;7419.258074192538;7438.962805185939;7536.397088233038;7539.660715267936;7275.1997280298065;7280.089988751406;7348.559086454829;7358.411327093927;7452.701891924262;6916.1700595926795;6974.442044636518;6986.860262794818;7015.36861682451;7075;4794.736289504111;4856.223004319433;4883.804647814048;4889.7799599018845;4919;4892.046051020017;4944.085344211332;5008;5029.371328583927;5223.634345595772 16:11:33 INFO - PROCESS | 1667 | |4;prototype.html;286.8809444080706;327.4351743242905;330.68089293831446;331.76444724245783;334.1729220180053;6646.969719693449;6747.257801641816;6759.290274521179;6768.187817461899;6821.556598821079;6625.834354141149;6674.799756007327;6681.59910405376;6686.665666716657;6687.933120668792;4738.455077666061;4773.713577185363;4903.779329930162;4913.901721965576;4939.407271127469;4827.300041493982;4886.584640305578;4903.53416425439;4905.901881962376;4909.852704418855;4850.781714822841;4851.563359297663;4858.931035172256;4866.975665121687;4903.730294833783;4853.878653033667;4869.293952376921;4875.634327425421;4888.535589119028;4918.754062296871;4214.810333534998;4221.97889010553;4222.493300803892;4228.9154216915485;4246.978765106154;6184.907226391562;6366.554341196118;6375.2149398168585;6445.323241059715;6455.4835613150835;5788.479036886701;5851.093080572476;5870.471657550841;5885.79399721012;5907;5808.4772370486435;6974.988626649108;7101.751438699621;7102.218755936843;7166.784996450063;4213;4396.406485124531;4734.597559207472;4745.857624271277;4755.286706993978;7494.812629684247;7529.661165247522;7532.284432978859;7548.9613057014785;7563.848723025509;3963.9405408918888;4641.187792136363;4855.441624213261;4856.611471082376;4865.416150062015;4885.169521181408;4937.629677774181;4940.283658869444;4942.159832828428;4954.826581069645;5812.82561523155;5822.214001109881;5825.592208545361;5837.445442682938;5846.269216347957;5879.911801322984;6086.2087928569;7105.289471052956;7208.207097219301;7214.819629509304;1698.6347935193992;1717.4761697682077;1719.4497760716451;1721.5437908954136;1722.6124122072433;904.6788390121544;1085.8751243606928;1096.1011970184531;1107.6621630402644;1107.9889201107885;5967.836271927019;6309.968450157719;6665.166854143232;6755.392014718699;6763.898541521881;6561.606303621798;7125.1093613298335;7443.255674432622;7453.068366454193;7496.962515187498;6577.112089867902;6665.333466653393;6690.59856408617;6789.490788190807;6800.251972283045;3960.386140148282;3984.183242435294;3989.8005099744896;4002.4996875390575;4008.5390180129657;7893.97378340812;7897.960510197412;7909;7916.485428447132;7925.0489941207425;7520.172780994087;7817.687292508236;7845.0978137513775;7854.960725196337;7867.685292588232;6658.800235992928;7906.644201010969;7926.445148839526;7941.6029198539845;7958.562279074591;7529.698812047676;7553.282438168365;7562.0925488941675;7573;7577.848443031219;7411.740589079356;7422.925770742439;7429.473978847218;7433.776986690408;7449.8882516761205;5920.875033743575;7010.579365238102;7078;7629.771106866803;7903.84192316162;4647.604953578983;4655.767211639337;4689.413823272091;4702.905941881211;4713.882152946204;5930.644161350333;7419.220981796833;7454.030975973089;7460.589667568336;7463.552186868806;7174.426045916419;7182;7191.676374563052;7204.9639751800905;7219.061522002106;5464.972675136758;6710.99335099739;7207.675654595451;7238.6018768968215;7244.094488188976;6965.651717414008;6993.895091573529;6995.685194166173;7006.719731210695;7009.12385951756;7190.280971902768;7207.387372073223;7210.495265331377;7230.6384680766805;7235.131784185932;6370.458511026614;6386.9680651596445;7033.929660703532;7037.542559733601;7079.929200707927 16:11:33 INFO - PROCESS | 1667 | |5;yui.html;685.1675214614244;752.8531936272423;753.077480086894;755.3654929858919;757.7916073079903;15317.157556334398;15426.537203883901;15587.844121558795;15634.921825390857;15645.061296322214;14072.92963535181;14129.222892740896;14145.92927035366;14148.78776818346;14173.858261417408;725.5864157430267;918.8759517465123;926.1063074133443;940.2759874896326;942.0955882352915;913.5614904845645;925.7916968682026;932.3287233192125;934.7943452440486;939.8261321655482;1003.0521157506159;1003.0621369019954;1003.172382784206;1006.4716024087339;1008.7679833638267;940.2524992630875;940.5720397219249;971.621067783565;986.0336869867534;996.3324572536382;668.7258224128084;704.183147548846;712.5582139073807;725.9201487836334;740.3521918321469;14449.855501444956;14489.347979340955;14668;14866.479673211386;14897.180655063967;12690.74618507634;12708.428120734588;12908.677283067904;12932.612021639367;13056;9868.259880508918;9983.700488985269;10144.847827282596;10307;10398.168146548258;3460.2041530448137;3556.3183523588923;3592.6407359263862;3600.891973240807;3624.3657359961903;74903.87644185287;78651;79141;79271;81432.96417589608;9537.856932146024;9559.091886270793;9620.75948101303;9705.369151005161;9741.123298903132;22018.779812201672;22156.55686886253;22193.889030555067;22214.66677999831;22301.888490557445;7478.177400485943;7544.962275188589;7565.167831538526;7619.9238007619215;7645.885311720329;3874.593167717349;4160.188763191149;4162.854300099482;4164.3961625564125;4171.207470580641;3561.8424012195624;3587.515685382492;3589.4077477215965;3608.0979755061235;3619.5475565554307;638.396715104222;649.2598437780933;649.9350064993557;654.4142992022114;655.9278479367266;595.4462350014528;595.4611076975258;598.4583951523878;602.3073465514693;608.4159207161175;8230.506169629842;8248.422610417334;8282.461639993382;8394.958025209835;8421.831563368698;520.2975982423698;530.6206062665212;571.2630706388795;599.8200539838031;616.2635650397822;2747.7939154563487;3556.288742251508;3876.8449262029767;4051.47330846988;4139.834406623761;78927.21072789199;86281;95451.52274238585;98045;98122;73219.53560928858;74087.25912740911;74517;79227;83860;45233;58085.54786130381;66618.33381666218;67048.99426508704;70173.2982670177;7138.536599997092;8111.472754270953;8450.19723126302;8587.398882078194;8647.138090668122;37177.25645487129;43247;46147.84630384267;48472.5152748468;49846;67029.65940681257;68869;69654.30345696681;69934;70029.29970700228;9445.197158241626;10906.50920708554;11055.336679799238;11147.832782508107;11721.945024947796;11925.522979080739;12069.758604827679;12108.334041627795;12167.391630418266;12266.75466490683;11276.718082048015;11400.201985860911;11490.94254528722;11655.950964412905;11845.822312665488;10993.450327483753;11191.832122518006;11258.437078145897;11449.198556101326;11701.414929253333;11105.444727763417;11124.888751112385;11258.605948791754;11262.211645185087;11524;9613.615455381987;9615.855762163714;9726.416415015121;9828.5085745711;10106.646267380607;7623.4663573549315;9628.037196280316;9691.466969316756;9695;9795.167410770164 16:11:33 INFO - PROCESS | 1667 | __end_tp_report 16:11:33 INFO - PROCESS | 1667 | __start_cc_report 16:11:33 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,413 16:11:33 INFO - PROCESS | 1667 | __end_cc_report 16:11:33 INFO - PROCESS | 1667 | __startTimestamp1464649893385__endTimestamp 16:11:33 INFO - PROCESS | 1667 | 16:11:33 INFO - PROCESS | 1667 | ------- Summary: start ------- 16:11:33 INFO - PROCESS | 1667 | Number of tests: 6 16:11:33 INFO - PROCESS | 1667 | 16:11:33 INFO - PROCESS | 1667 | [#0] dojo.html Cycles:165 Average:5700.95 Median:5896.97 stddev:696.59 (11.8%) stddev-sans-first:665.28 16:11:33 INFO - PROCESS | 1667 | Values: 2982.6 3260.8 3426.9 3467.1 3518.5 4640.3 5348.6 5629.6 5636.0 5640.8 5670.7 5703.8 5706.9 5711.5 5715.5 4467.0 5098.1 5222.4 5288.2 5289.7 4470.9 5020.5 5115.7 5280.9 5415.5 4681.8 4934.5 5300.2 5312.6 5313.3 4307.7 5119.3 5275.6 5311.7 5323.7 5182.5 5227.8 5250.4 5260.4 5277.1 5705.0 5714.5 5749.9 5762.4 5796.1 5169.1 6026.1 6165.6 6214.9 6272.9 6216.4 6264.4 6288.8 6295.9 6305.5 5811.8 5833.9 5837.6 5851.2 5865.8 4737.4 6227.4 6267.2 6324.9 6340.6 6209.7 6212.4 6238.2 6245.6 6247.6 6124.1 6309.3 6310.6 6328.9 6345.2 6093.6 6132.0 6191.2 6198.6 6214.6 5045.8 6193.4 6244.2 6262.4 6284.8 4872.4 4879.2 4908.2 4953.2 4966.6 3900.7 4419.9 4452.9 4460.2 4474.9 5766.4 5833.3 5840.6 5845.0 5845.4 6069.5 6145.9 6211.0 6288.1 6311.3 5822.5 5831.3 5863.6 5876.8 5882.4 5445.4 5445.7 5446.2 5454.8 5457.2 6204.1 6301.6 6346.6 6374.3 6388.9 6242.4 6257.2 6271.5 6282.9 6282.9 6397.2 6418.5 6425.8 6432.7 6442.1 5172.4 6010.6 6126.8 6137.9 6247.6 6241.3 6284.7 6302.4 6304.5 6308.9 4959.2 6163.1 6166.0 6167.8 6174.7 5953.9 6076.3 6128.3 6166.2 6180.3 4843.5 5897.0 5898.4 6030.7 6034.7 5691.1 6157.9 6166.9 6170.3 6185.1 5925.6 5945.2 6004.4 6036.8 6071.8 16:11:33 INFO - PROCESS | 1667 | 16:11:33 INFO - PROCESS | 1667 | [#1] ext.html Cycles:180 Average:68305.64 Median:39394.51 stddev:145921.66 (370.4%) stddev-sans-first:146242.19 16:11:33 INFO - PROCESS | 1667 | Values: 495.9 548.9 550.1 559.9 604.7 38254.8 39059.0 39138.8 39185.4 39199.2 39967.8 40585.0 40658.8 40789.2 40936.8 9380.7 9600.9 9608.2 9618.9 9644.8 9464.8 9707.3 9732.7 9760.3 9764.2 9543.2 9583.2 9653.9 9681.3 9731.6 9627.1 9635.3 9682.9 9688.2 9742.2 9566.3 9674.0 9699.0 9703.5 9708.2 38847.6 38935.4 39326.0 39389.6 39399.4 2846.9 3325.5 3360.9 3363.9 3400.5 27750.4 27760.7 28098.7 28579.4 28606.9 2736.7 2775.6 2824.4 2837.2 2893.9 376349.1 448577.0 452779.0 455984.0 456014.0 51562.7 59282.0 64428.7 70953.9 72345.3 70139.6 70339.3 70565.0 70731.6 70866.6 17848.3 18070.6 18124.9 18147.4 18214.5 12205.0 13634.7 13820.4 13820.7 13884.9 21384.7 21474.4 21709.5 21719.0 21801.0 8642.5 8827.5 8870.0 8878.3 8887.4 967.9 970.4 998.9 1037.4 1055.3 29592.9 29839.7 30078.1 30254.8 30427.1 955.7 1181.3 1188.4 1208.2 1287.0 12754.3 12839.9 13393.0 15406.5 15576.4 581059.0 638044.0 638931.0 644721.0 645609.0 58798.4 58810.4 59439.7 59699.7 59913.0 1207.9 1474.8 1478.0 1492.9 1493.9 24315.0 24882.0 27902.9 29463.6 29986.6 44230.6 49115.0 50073.5 50713.7 50838.0 542100.0 548718.0 558366.0 559507.0 560620.0 41094.2 42533.8 42561.6 42986.1 43220.8 45321.8 46197.3 46650.8 46845.8 46903.5 37420.4 37632.8 37686.0 37958.0 38386.6 37007.4 37138.0 37139.4 37596.4 37751.0 32359.7 36859.3 37491.8 37709.6 38412.8 29712.7 31589.5 31678.7 31759.5 32117.0 24211.8 24264.0 24282.4 24299.1 24537.5 16:11:33 INFO - PROCESS | 1667 | 16:11:33 INFO - PROCESS | 1667 | [#2] jquery.html Cycles:180 Average:33910.97 Median:21158.15 stddev:95654.02 (452.1%) stddev-sans-first:95889.15 16:11:33 INFO - PROCESS | 1667 | Values: 347.9 401.4 404.1 408.9 408.9 15245.8 15818.1 20194.0 20489.7 20844.9 20487.4 20692.8 20719.7 20912.4 21403.9 4999.0 5646.1 5862.1 5955.6 5982.6 5629.7 5971.8 6018.4 6019.3 6026.8 5871.0 5891.4 5925.4 5928.0 5931.6 5425.7 5765.7 5767.6 5784.9 5871.4 4868.4 5454.0 5501.2 5505.3 5593.4 13727.2 13871.7 18822.1 24383.1 25189.6 1777.5 2152.3 2328.6 2541.4 2581.0 9590.5 9593.7 9868.1 9996.8 10024.2 1735.7 1755.3 1755.9 1785.8 1822.4 93093.5 109406.5 118308.0 121342.0 122326.4 11659.5 11890.2 11902.1 11904.5 12329.6 15011.2 15117.6 15147.8 15150.4 15159.5 7363.4 8934.6 9142.3 9159.1 9175.6 9168.0 9174.6 9180.2 9214.9 9285.6 4587.2 4640.0 4676.3 4690.9 4741.7 1908.8 1962.9 2009.2 2018.8 2029.2 815.8 961.0 1022.3 1058.0 1080.1 20109.7 20330.6 20432.9 20667.0 20739.5 973.8 975.4 996.7 1008.5 1045.2 10903.3 11189.3 11204.6 11213.6 11233.3 527234.0 529524.0 537863.0 619639.0 630936.0 118039.0 134244.0 135431.0 136352.0 142430.3 1016.6 1035.3 1041.7 1048.4 1065.1 16357.4 17938.6 18136.3 18255.2 18322.7 38327.6 49688.0 50574.0 51595.0 52661.2 76729.0 78113.2 78273.6 79677.6 84116.6 7939.1 8182.2 8301.3 8343.7 8362.6 9219.4 10657.2 12447.0 12515.9 12884.9 10853.7 10944.6 11025.4 11125.6 11201.1 10720.2 10821.9 10873.8 11015.5 11176.6 10875.2 11045.4 11357.0 11562.4 11568.4 8090.5 8198.1 8234.1 8265.6 8467.5 9720.4 10948.1 11060.7 11086.4 11165.3 16:11:33 INFO - PROCESS | 1667 | 16:11:33 INFO - PROCESS | 1667 | [#3] mootools.html Cycles:180 Average:5643.42 Median:4950.18 stddev:3132.22 (63.3%) stddev-sans-first:3124.19 16:11:33 INFO - PROCESS | 1667 | Values: 1324.9 1512.8 1609.8 1971.5 1994.4 7895.4 8666.7 8770.1 8808.9 8845.8 6781.4 8601.3 8721.3 8723.8 8800.9 2449.9 2460.8 2478.0 2479.6 2483.2 2442.4 2490.0 2490.8 2492.7 2500.6 2476.1 2492.3 2506.6 2520.4 2529.5 2470.7 2472.4 2486.3 2492.8 2501.3 2446.5 2474.8 2489.0 2489.2 2514.9 8937.0 8985.6 8992.6 8997.2 9003.0 4580.2 5425.5 5557.0 5572.3 5607.8 3019.3 3043.4 3207.4 3460.2 3496.3 1059.9 1061.4 1064.9 1085.7 1089.8 11496.3 11556.5 11692.4 11701.2 11705.5 4623.0 4637.8 4672.7 4686.0 4737.1 9290.0 9311.9 9332.1 9340.3 9343.7 5255.3 5273.8 5341.5 5343.5 5393.2 4088.7 4403.4 4940.8 4956.3 4957.4 3965.3 3969.2 4000.9 4009.3 4086.9 1239.1 1253.1 1254.7 1260.5 1265.4 859.7 939.5 940.3 949.6 968.7 5523.8 5980.4 5996.0 6116.4 6155.6 466.5 469.5 472.1 487.9 1037.2 3444.4 3478.3 3795.9 3919.2 4044.2 10523.6 10743.0 10829.9 10894.2 10912.9 10510.1 10561.2 10575.6 10672.5 10676.3 7902.2 8188.4 8198.9 8239.3 8285.8 5643.8 5923.7 5945.6 5948.8 6049.1 7327.4 8078.5 9710.2 9758.6 9777.7 9636.8 9720.5 9916.8 9917.9 9949.1 6195.4 6994.8 7493.9 7540.9 7682.4 8263.0 8295.7 8326.0 8370.0 8432.9 7308.9 7419.3 7439.0 7536.4 7539.7 7275.2 7280.1 7348.6 7358.4 7452.7 6916.2 6974.4 6986.9 7015.4 7075.0 4794.7 4856.2 4883.8 4889.8 4919.0 4892.0 4944.1 5008.0 5029.4 5223.6 16:11:33 INFO - PROCESS | 1667 | 16:11:33 INFO - PROCESS | 1667 | [#4] prototype.html Cycles:180 Average:5837.32 Median:6601.47 stddev:1865.01 (28.3%) stddev-sans-first:1823.12 16:11:33 INFO - PROCESS | 1667 | Values: 286.9 327.4 330.7 331.8 334.2 6647.0 6747.3 6759.3 6768.2 6821.6 6625.8 6674.8 6681.6 6686.7 6687.9 4738.5 4773.7 4903.8 4913.9 4939.4 4827.3 4886.6 4903.5 4905.9 4909.9 4850.8 4851.6 4858.9 4867.0 4903.7 4853.9 4869.3 4875.6 4888.5 4918.8 4214.8 4222.0 4222.5 4228.9 4247.0 6184.9 6366.6 6375.2 6445.3 6455.5 5788.5 5851.1 5870.5 5885.8 5907.0 5808.5 6975.0 7101.8 7102.2 7166.8 4213.0 4396.4 4734.6 4745.9 4755.3 7494.8 7529.7 7532.3 7549.0 7563.8 3963.9 4641.2 4855.4 4856.6 4865.4 4885.2 4937.6 4940.3 4942.2 4954.8 5812.8 5822.2 5825.6 5837.4 5846.3 5879.9 6086.2 7105.3 7208.2 7214.8 1698.6 1717.5 1719.4 1721.5 1722.6 904.7 1085.9 1096.1 1107.7 1108.0 5967.8 6310.0 6665.2 6755.4 6763.9 6561.6 7125.1 7443.3 7453.1 7497.0 6577.1 6665.3 6690.6 6789.5 6800.3 3960.4 3984.2 3989.8 4002.5 4008.5 7894.0 7898.0 7909.0 7916.5 7925.0 7520.2 7817.7 7845.1 7855.0 7867.7 6658.8 7906.6 7926.4 7941.6 7958.6 7529.7 7553.3 7562.1 7573.0 7577.8 7411.7 7422.9 7429.5 7433.8 7449.9 5920.9 7010.6 7078.0 7629.8 7903.8 4647.6 4655.8 4689.4 4702.9 4713.9 5930.6 7419.2 7454.0 7460.6 7463.6 7174.4 7182.0 7191.7 7205.0 7219.1 5465.0 6711.0 7207.7 7238.6 7244.1 6965.7 6993.9 6995.7 7006.7 7009.1 7190.3 7207.4 7210.5 7230.6 7235.1 6370.5 6387.0 7033.9 7037.5 7079.9 16:11:33 INFO - PROCESS | 1667 | 16:11:33 INFO - PROCESS | 1667 | [#5] yui.html Cycles:180 Average:17885.87 Median:599.14 stddev:24959.03 (4165.8%) stddev-sans-first:24995.63 16:11:33 INFO - PROCESS | 1667 | Values: 685.2 752.9 753.1 755.4 757.8 15317.2 15426.5 15587.8 15634.9 15645.1 14072.9 14129.2 14145.9 14148.8 14173.9 725.6 918.9 926.1 940.3 942.1 913.6 925.8 932.3 934.8 939.8 1003.1 1003.1 1003.2 1006.5 1008.8 940.3 940.6 971.6 986.0 996.3 668.7 704.2 712.6 725.9 740.4 14449.9 14489.3 14668.0 14866.5 14897.2 12690.7 12708.4 12908.7 12932.6 13056.0 9868.3 9983.7 10144.8 10307.0 10398.2 3460.2 3556.3 3592.6 3600.9 3624.4 74903.9 78651.0 79141.0 79271.0 81433.0 9537.9 9559.1 9620.8 9705.4 9741.1 22018.8 22156.6 22193.9 22214.7 22301.9 7478.2 7545.0 7565.2 7619.9 7645.9 3874.6 4160.2 4162.9 4164.4 4171.2 3561.8 3587.5 3589.4 3608.1 3619.5 638.4 649.3 649.9 654.4 655.9 595.4 595.5 598.5 602.3 608.4 8230.5 8248.4 8282.5 8395.0 8421.8 520.3 530.6 571.3 599.8 616.3 2747.8 3556.3 3876.8 4051.5 4139.8 78927.2 86281.0 95451.5 98045.0 98122.0 73219.5 74087.3 74517.0 79227.0 83860.0 45233.0 58085.5 66618.3 67049.0 70173.3 7138.5 8111.5 8450.2 8587.4 8647.1 37177.3 43247.0 46147.8 48472.5 49846.0 67029.7 68869.0 69654.3 69934.0 70029.3 9445.2 10906.5 11055.3 11147.8 11721.9 11925.5 12069.8 12108.3 12167.4 12266.8 11276.7 11400.2 11490.9 11656.0 11845.8 10993.5 11191.8 11258.4 11449.2 11701.4 11105.4 11124.9 11258.6 11262.2 11524.0 9613.6 9615.9 9726.4 9828.5 10106.6 7623.5 9628.0 9691.5 9695.0 9795.2 16:11:33 INFO - PROCESS | 1667 | -------- Summary: end -------- 16:11:33 INFO - PROCESS | 1667 | 16:11:33 INFO - TEST-INFO | 1667: exit 0 16:11:33 INFO - TEST-OK | dromaeo_css | took 1111444ms 16:11:33 INFO - TEST-START | kraken 16:11:33 INFO - operating with platform_type : mac_ 16:11:33 INFO - Initialising browser for kraken test... 16:11:33 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/tmpU8TrTr/profile http://localhost:49270/getInfo.html) 16:11:36 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 16:11:36 INFO - PROCESS | 1677 | colorDepth:24 16:11:36 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 16:11:36 INFO - PROCESS | 1677 | __metrics 16:11:36 ERROR - PROCESS | 1677 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2940: TypeError: this.tabs is undefined 16:11:37 INFO - TEST-INFO | 1677: exit 0 16:11:37 INFO - Browser initialized. 16:11:37 INFO - Running cycle 1/1 for kraken test... 16:11:37 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/T/tmpU8TrTr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:11:49 INFO - PROCESS | 1680 | RSS: Main: 220901376 16:11:49 INFO - PROCESS | 1680 | 16:11:52 INFO - PROCESS | 1680 | 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) 16:11:53 INFO - PROCESS | 1680 | RSS: Main: 233127936 16:11:53 INFO - PROCESS | 1680 | 16:11:55 INFO - PROCESS | 1680 | 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) 16:11:56 INFO - PROCESS | 1680 | RSS: Main: 241946624 16:11:56 INFO - PROCESS | 1680 | 16:11:59 INFO - PROCESS | 1680 | 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) 16:12:00 INFO - PROCESS | 1680 | RSS: Main: 245563392 16:12:00 INFO - PROCESS | 1680 | 16:12:02 INFO - PROCESS | 1680 | 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) 16:12:02 INFO - PROCESS | 1680 | RSS: Main: 245964800 16:12:02 INFO - PROCESS | 1680 | 16:12:05 INFO - PROCESS | 1680 | 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) 16:12:05 INFO - PROCESS | 1680 | RSS: Main: 248016896 16:12:05 INFO - PROCESS | 1680 | 16:12:08 INFO - PROCESS | 1680 | 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) 16:12:09 INFO - PROCESS | 1680 | RSS: Main: 247627776 16:12:09 INFO - PROCESS | 1680 | 16:12:12 INFO - PROCESS | 1680 | 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) 16:12:12 INFO - PROCESS | 1680 | RSS: Main: 247746560 16:12:12 INFO - PROCESS | 1680 | 16:12:15 INFO - PROCESS | 1680 | 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) 16:12:16 INFO - PROCESS | 1680 | RSS: Main: 247750656 16:12:16 INFO - PROCESS | 1680 | 16:12:18 INFO - PROCESS | 1680 | 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) 16:12:18 INFO - PROCESS | 1680 | RSS: Main: 247562240 16:12:18 INFO - PROCESS | 1680 | 16:12:20 INFO - PROCESS | 1680 | 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) 16:12:21 INFO - PROCESS | 1680 | RSS: Main: 247996416 16:12:21 INFO - PROCESS | 1680 | 16:12:23 INFO - PROCESS | 1680 | 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) 16:12:24 INFO - PROCESS | 1680 | RSS: Main: 248020992 16:12:24 INFO - PROCESS | 1680 | 16:12:27 INFO - PROCESS | 1680 | 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) 16:12:27 INFO - PROCESS | 1680 | RSS: Main: 247803904 16:12:27 INFO - PROCESS | 1680 | 16:12:30 INFO - PROCESS | 1680 | 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) 16:12:31 INFO - PROCESS | 1680 | RSS: Main: 247771136 16:12:31 INFO - PROCESS | 1680 | 16:12:32 INFO - PROCESS | 1680 | 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) 16:12:33 INFO - PROCESS | 1680 | RSS: Main: 249556992 16:12:33 INFO - PROCESS | 1680 | 16:12:33 INFO - PROCESS | 1680 | __start_tp_report 16:12:33 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 16:12:33 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 16:12:33 INFO - PROCESS | 1680 | |i|pagename|runs| 16:12:33 INFO - PROCESS | 1680 | |0;ai-astar;131;170;140;117;118;118;111;170;146;116 16:12:33 INFO - PROCESS | 1680 | |1;audio-beat-detection;152;122;155;161;118;141;141;123;176;125 16:12:33 INFO - PROCESS | 1680 | |2;audio-dft;200;199;225;261;194;201;201;198;205;200 16:12:33 INFO - PROCESS | 1680 | |3;audio-fft;57;96;107;203;86;82;48;61;153;83 16:12:33 INFO - PROCESS | 1680 | |4;audio-oscillator;78;86;135;80;115;88;129;80;82;118 16:12:33 INFO - PROCESS | 1680 | |5;imaging-gaussian-blur;93;89;102;131;76;131;136;93;94;87 16:12:33 INFO - PROCESS | 1680 | |6;imaging-darkroom;94;95;96;95;96;94;126;151;98;96 16:12:33 INFO - PROCESS | 1680 | |7;imaging-desaturate;147;144;90;91;90;91;91;118;142;117 16:12:33 INFO - PROCESS | 1680 | |8;json-parse-financial;60;62;65;65;65;66;65;82;64;62 16:12:33 INFO - PROCESS | 1680 | |9;json-stringify-tinderbox;66;67;89;103;75;73;65;66;66;68 16:12:33 INFO - PROCESS | 1680 | |10;stanford-crypto-aes;82;112;110;106;74;95;109;107;109;73 16:12:33 INFO - PROCESS | 1680 | |11;stanford-crypto-ccm;149;153;149;128;131;133;152;123;168;171 16:12:33 INFO - PROCESS | 1680 | |12;stanford-crypto-pbkdf2;169;169;168;167;169;167;172;170;164;176 16:12:33 INFO - PROCESS | 1680 | |13;stanford-crypto-sha256-iterative;68;67;71;66;69;65;69;70;73;72 16:12:33 INFO - PROCESS | 1680 | __end_tp_report 16:12:33 INFO - PROCESS | 1680 | __start_cc_report 16:12:33 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,1090 16:12:33 INFO - PROCESS | 1680 | __end_cc_report 16:12:33 INFO - PROCESS | 1680 | __startTimestamp1464649953226__endTimestamp 16:12:33 INFO - PROCESS | 1680 | 16:12:33 INFO - PROCESS | 1680 | ------- Summary: start ------- 16:12:33 INFO - PROCESS | 1680 | Number of tests: 14 16:12:33 INFO - PROCESS | 1680 | 16:12:33 INFO - PROCESS | 1680 | [#0] ai-astar Cycles:10 Average:133.70 Median:135.50 stddev:22.20 (16.4%) stddev-sans-first:23.52 16:12:33 INFO - PROCESS | 1680 | Values: 131.0 170.0 140.0 117.0 118.0 118.0 111.0 170.0 146.0 116.0 16:12:33 INFO - PROCESS | 1680 | 16:12:33 INFO - PROCESS | 1680 | [#1] audio-beat-detection Cycles:10 Average:141.40 Median:146.50 stddev:19.47 (13.3%) stddev-sans-first:20.27 16:12:33 INFO - PROCESS | 1680 | Values: 152.0 122.0 155.0 161.0 118.0 141.0 141.0 123.0 176.0 125.0 16:12:33 INFO - PROCESS | 1680 | 16:12:33 INFO - PROCESS | 1680 | [#2] audio-dft Cycles:10 Average:208.40 Median:201.00 stddev:20.30 (10.1%) stddev-sans-first:21.30 16:12:33 INFO - PROCESS | 1680 | Values: 200.0 199.0 225.0 261.0 194.0 201.0 201.0 198.0 205.0 200.0 16:12:33 INFO - PROCESS | 1680 | 16:12:33 INFO - PROCESS | 1680 | [#3] audio-fft Cycles:10 Average:97.60 Median:71.50 stddev:47.50 (66.4%) stddev-sans-first:48.06 16:12:33 INFO - PROCESS | 1680 | Values: 57.0 96.0 107.0 203.0 86.0 82.0 48.0 61.0 153.0 83.0 16:12:33 INFO - PROCESS | 1680 | 16:12:33 INFO - PROCESS | 1680 | [#4] audio-oscillator Cycles:10 Average:99.10 Median:80.00 stddev:22.50 (28.1%) stddev-sans-first:22.53 16:12:33 INFO - PROCESS | 1680 | Values: 78.0 86.0 135.0 80.0 115.0 88.0 129.0 80.0 82.0 118.0 16:12:33 INFO - PROCESS | 1680 | 16:12:33 INFO - PROCESS | 1680 | [#5] imaging-gaussian-blur Cycles:10 Average:103.20 Median:88.00 stddev:21.39 (24.3%) stddev-sans-first:22.37 16:12:33 INFO - PROCESS | 1680 | Values: 93.0 89.0 102.0 131.0 76.0 131.0 136.0 93.0 94.0 87.0 16:12:33 INFO - PROCESS | 1680 | 16:12:33 INFO - PROCESS | 1680 | [#6] imaging-darkroom Cycles:10 Average:104.10 Median:95.50 stddev:19.10 (20.0%) stddev-sans-first:19.90 16:12:33 INFO - PROCESS | 1680 | Values: 94.0 95.0 96.0 95.0 96.0 94.0 126.0 151.0 98.0 96.0 16:12:33 INFO - PROCESS | 1680 | 16:12:33 INFO - PROCESS | 1680 | [#7] imaging-desaturate Cycles:10 Average:112.10 Median:90.00 stddev:24.72 (27.5%) stddev-sans-first:22.77 16:12:33 INFO - PROCESS | 1680 | Values: 147.0 144.0 90.0 91.0 90.0 91.0 91.0 118.0 142.0 117.0 16:12:33 INFO - PROCESS | 1680 | 16:12:33 INFO - PROCESS | 1680 | [#8] json-parse-financial Cycles:10 Average:65.60 Median:65.00 stddev:6.06 (9.3%) stddev-sans-first:6.08 16:12:33 INFO - PROCESS | 1680 | Values: 60.0 62.0 65.0 65.0 65.0 66.0 65.0 82.0 64.0 62.0 16:12:33 INFO - PROCESS | 1680 | 16:12:33 INFO - PROCESS | 1680 | [#9] json-stringify-tinderbox Cycles:10 Average:73.80 Median:67.50 stddev:12.59 (18.6%) stddev-sans-first:13.03 16:12:33 INFO - PROCESS | 1680 | Values: 66.0 67.0 89.0 103.0 75.0 73.0 65.0 66.0 66.0 68.0 16:12:33 INFO - PROCESS | 1680 | 16:12:33 INFO - PROCESS | 1680 | [#10] stanford-crypto-aes Cycles:10 Average:97.70 Median:92.50 stddev:15.61 (16.9%) stddev-sans-first:15.48 16:12:33 INFO - PROCESS | 1680 | Values: 82.0 112.0 110.0 106.0 74.0 95.0 109.0 107.0 109.0 73.0 16:12:33 INFO - PROCESS | 1680 | 16:12:33 INFO - PROCESS | 1680 | [#11] stanford-crypto-ccm Cycles:10 Average:145.70 Median:150.50 stddev:16.53 (11.0%) stddev-sans-first:17.49 16:12:33 INFO - PROCESS | 1680 | Values: 149.0 153.0 149.0 128.0 131.0 133.0 152.0 123.0 168.0 171.0 16:12:33 INFO - PROCESS | 1680 | 16:12:33 INFO - PROCESS | 1680 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.10 Median:169.00 stddev:3.21 (1.9%) stddev-sans-first:3.41 16:12:33 INFO - PROCESS | 1680 | Values: 169.0 169.0 168.0 167.0 169.0 167.0 172.0 170.0 164.0 176.0 16:12:33 INFO - PROCESS | 1680 | 16:12:33 INFO - PROCESS | 1680 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.00 Median:69.50 stddev:2.58 (3.7%) stddev-sans-first:2.71 16:12:33 INFO - PROCESS | 1680 | Values: 68.0 67.0 71.0 66.0 69.0 65.0 69.0 70.0 73.0 72.0 16:12:33 INFO - PROCESS | 1680 | -------- Summary: end -------- 16:12:33 INFO - PROCESS | 1680 | 16:12:33 INFO - TEST-INFO | 1680: exit 0 16:12:34 INFO - TEST-OK | kraken | took 60064ms 16:12:34 INFO - SUITE-END | took 1171s 16:12:34 INFO - Completed test suite (00:19:31) 16:12:34 INFO - javascript benchmark 16:12:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2982.5973493578367, 3260.826102603064, 3426.9205200361866, 3467.133216695826, 3518.4898189762484, 4640.257558790594, 5348.598855085869, 5629.60592758507, 5635.971820140904, 5640.774369025234, 5670.659760414368, 5703.800366987156, 5706.885862282752, 5711.514521265697, 5715.485606295443, 4467.017256203646, 5098.056859480989, 5222.399424066224, 5288.206768984644, 5289.735513224343, 4470.932936005963, 5020.468757029104, 5115.7442127893455, 5280.8910128872985, 5415.48552887475, 4681.812727490897, 4934.4572097069295, 5300.151975683892, 5312.6281160318795, 5313.282706834567, 4307.686155722504, 5119.257707632372, 5275.6043296752705, 5311.681299122046, 5323.733813309319, 5182.455842136559, 5227.79088836446, 5250.396204436501, 5260.368755749297, 5277.102892508283, 5705.0, 5714.542836573064, 5749.885002299972, 5762.366139724627, 5796.13058041293, 5169.069567477853, 6026.0960855871535, 6165.630062196237, 6214.937850621481, 6272.87454250917, 6216.378362163793, 6264.404881536247, 6288.842778930518, 6295.874082518324, 6305.527085468562, 5811.82564523065, 5833.912491312633, 5837.562182836219, 5851.239338885917, 5865.794697185621, 4737.431508218967, 6227.439530442282, 6267.2479302484, 6324.936750632435, 6340.587861788969, 6209.720562574695, 6212.4408803207925, 6238.189035425366, 6245.594036387621, 6247.562670613104, 6124.142620033199, 6309.305976342599, 6310.6213627182515, 6328.905066424008, 6345.238571371465, 6093.6039157454625, 6132.018876979661, 6191.164192834, 6198.566100372931, 6214.627122372672, 5045.823396181116, 6193.411625895623, 6244.219472565929, 6262.436380725757, 6284.842878927972, 4872.3665923430435, 4879.194932836042, 4908.190148625436, 4953.23224900141, 4966.602671786249, 3900.687944964396, 4419.911601767946, 4452.933206001912, 4460.1525710114975, 4474.932876006797, 5766.423357664284, 5833.270841144857, 5840.620359676607, 5845.035569131046, 5845.386234445322, 6069.484093851984, 6145.893739127, 6210.968945155336, 6288.056791481315, 6311.305756366796, 5822.534197264209, 5831.329397119301, 5863.589548731633, 5876.764929402776, 5882.3529411764675, 5445.37378201504, 5445.673259604437, 5446.1558458439, 5454.836354909359, 5457.154141108134, 6204.13142160107, 6301.590396624205, 6346.555741098079, 6374.298827129012, 6388.936110638927, 6242.406971337716, 6257.217847769029, 6271.498280137669, 6282.874342513215, 6282.947498863299, 6397.168368112116, 6418.454431373386, 6425.7750978717395, 6432.710528026344, 6442.09810626522, 5172.431032586337, 6010.639361638316, 6126.761967422209, 6137.867325793431, 6247.625142491465, 6241.3134555197985, 6284.717187726655, 6302.401271879165, 6304.495640348853, 6308.873822523432, 4959.156943319716, 6163.075538669235, 6166.013437849922, 6167.753289868535, 6174.6912654368, 5953.851153721192, 6076.270847498328, 6128.295246046761, 6166.229221347332, 6180.289266734203, 4843.491433399583, 5896.970515147397, 5898.439648233411, 6030.728617212324, 6034.668093254913, 5691.146328050825, 6157.907631385622, 6166.876662466815, 6170.290416602148, 6185.072239164161, 5925.644461332334, 5945.227120474311, 6004.42957918997, 6036.849078773066, 6071.848203794941], "value": 5657.419720186097, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.9156943319633, 548.8518100112972, 550.1307933465124, 559.9272094627697, 604.6644112517552, 38254.80872595637, 39059.0, 39138.80430597847, 39185.412218816666, 39199.21601567974, 39967.80016099923, 40585.0, 40658.79670601643, 40789.184216315654, 40936.77189684307, 9380.718578442658, 9600.855987160197, 9608.183304419133, 9618.855717164248, 9644.75888102796, 9464.81070378592, 9707.320487565874, 9732.65935692251, 9760.31677782552, 9764.21886249102, 9543.236541076732, 9583.185429238523, 9653.903460965406, 9681.273904457159, 9731.562079706395, 9627.13355797978, 9635.325527213097, 9682.854757178648, 9688.176504997013, 9742.1719153872, 9566.330356875022, 9674.0, 9698.9515052425, 9703.514824258758, 9708.17480514157, 38847.61152388468, 38935.41596876049, 39326.0, 39389.60610393888, 39399.40900886489, 2846.9430611387866, 3325.5344251804527, 3360.865565377382, 3363.865445382182, 3400.523926650271, 27750.444991100267, 27760.722392776017, 28098.719012809815, 28579.428411431654, 28606.856965715247, 2736.7263273672675, 2775.5836624506283, 2824.4068745563454, 2837.1914004508617, 2893.9131826045254, 376349.1182544097, 448577.0, 452779.0, 455984.0, 456014.0, 51562.74218628883, 59282.0, 64428.67785661042, 70953.93569096467, 72345.27654723385, 70139.64930175316, 70339.29660703328, 70565.0, 70731.64634176796, 70866.64566677134, 17848.286068557372, 18070.638587228183, 18124.909375453037, 18147.364842230458, 18214.544636384195, 12205.0, 13634.727305453835, 13820.37808298629, 13820.654483637789, 13884.889208863267, 21384.679229811565, 21474.355769326947, 21709.457263568536, 21719.0, 21801.0, 8642.536876990154, 8827.45519534079, 8870.004249043914, 8878.28973682104, 8887.377883548219, 967.8548217767251, 970.369259981018, 998.8962196772607, 1037.4138611684525, 1055.2929537210089, 29592.852035739685, 29839.701602984125, 30078.097657070324, 30254.84872575623, 30427.087187384415, 955.6607404371487, 1181.2812358019062, 1188.3879801902028, 1208.172401904698, 1286.9832832062752, 12754.298513581842, 12839.935800321125, 13393.0, 15406.537803865902, 15576.376944922076, 581059.0, 638044.0, 638931.0, 644721.0, 645609.0, 58798.41201588015, 58810.41189588135, 59439.70280148571, 59699.701501492214, 59913.0, 1207.9335636540075, 1474.8156480439945, 1478.0245038274686, 1492.8731057860205, 1493.8506149384975, 24315.0, 24882.0, 27902.86048569744, 29463.558046629747, 29986.550201746555, 44230.55769442264, 49115.01769964652, 50073.499265008344, 50713.73215669638, 50838.0, 542100.0, 548718.0, 558366.0, 559507.0, 560620.0, 41094.1781164381, 42533.78733106315, 42561.574384257, 42986.14027719491, 43220.78389608032, 45321.77339113283, 46197.307040393745, 46650.76674616605, 46845.765771170925, 46903.53096469128, 37420.43869341908, 37632.81183594064, 37686.0, 37958.0, 38386.6161338383, 37007.44488832616, 37138.0, 37139.44290835586, 37596.436053458674, 37751.0, 32359.676403235666, 36859.262814743015, 37491.81254093712, 37709.622903771706, 38412.80793596014, 29712.702872970993, 31589.5261571072, 31678.683213167573, 31759.52360714545, 32117.0, 24211.75788242095, 24264.0, 24282.392940176636, 24299.149529766375, 24537.509249815263], "value": 20223.375645099077, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.8973702757687, 401.42996944339035, 404.108939787768, 408.92026054919296, 408.9263932492152, 15245.847541524581, 15818.05091694499, 20194.0, 20489.692654610175, 20844.895775521138, 20487.385378438634, 20692.793072069275, 20719.689204661943, 20912.372628821122, 21403.92980350984, 4998.975005124988, 5646.124850648156, 5862.149988251701, 5955.612885162473, 5982.551308651847, 5629.7185140743, 5971.761129554812, 6018.3981601839905, 6019.337872833985, 6026.819195424122, 5871.0, 5891.440313170264, 5925.4370834770625, 5927.970360148193, 5931.555133365015, 5425.674459532421, 5765.711714414262, 5767.625104368202, 5784.855378615547, 5871.412858714137, 4868.440129385131, 5454.0, 5501.174823776426, 5505.3393592768725, 5593.412691667357, 13727.245001524912, 13871.722565548633, 18822.05889705523, 24383.146589869448, 25189.62215566768, 1777.5111844242808, 2152.2682288021992, 2328.55757406094, 2541.351955251399, 2580.974190258092, 9590.472524011177, 9593.664221752275, 9868.062534059252, 9996.750081248027, 10024.198064154923, 1735.7483164941013, 1755.332973470086, 1755.9122043897883, 1785.812489688577, 1822.3894995176631, 93093.53453232825, 109406.45296773466, 118308.0, 121342.0, 122326.38836805848, 11659.475323610457, 11890.227135236182, 11902.107341949215, 11904.523819047314, 12329.568465103679, 15011.24943752808, 15117.622059448602, 15147.848521514643, 15150.393984240727, 15159.469418570297, 7363.410927125817, 8934.642614295486, 9142.268618510503, 9159.129882661136, 9175.632974680939, 9167.95416022929, 9174.633014679472, 9180.219681327162, 9214.907850921405, 9285.62857485693, 4587.197240482902, 4639.953600463952, 4676.3453116563705, 4690.929636055462, 4741.715497070187, 1908.7900330963582, 1962.8920409377336, 2009.2164056018164, 2018.7981201879695, 2029.1680411031407, 815.7675062607128, 960.9855852162223, 1022.3354819367471, 1057.9788404231872, 1080.1466841195524, 20109.698354524695, 20330.593388132154, 20432.89783551073, 20666.966651667357, 20739.481512961996, 973.7516933181993, 975.3855071305175, 996.6561536269975, 1008.5411137932223, 1045.2056437107854, 10903.34579925207, 11189.272697274651, 11204.60783872577, 11213.551457941754, 11233.269837460539, 527234.0, 529524.0, 537863.0, 619639.0, 630936.0, 118039.0, 134244.0, 135431.0, 136352.0, 142430.28784856008, 1016.6492560066765, 1035.337384074193, 1041.7083216699336, 1048.4390850894888, 1065.0680654427376, 16357.446042625932, 17938.5515362117, 18136.274549017893, 18255.1785169665, 18322.72515912289, 38327.616723832405, 49688.0, 50574.0, 51595.0, 52661.21008184957, 76729.0, 78113.21886781287, 78273.60863195648, 79677.60161199389, 84116.57941710496, 7939.126696063544, 8182.22268884455, 8301.252887240178, 8343.707970221256, 8362.581870906552, 9219.354645174773, 10657.20070994663, 12447.004239660986, 12515.874841251834, 12884.871151288742, 10853.674389768319, 10944.627360069395, 11025.393603351571, 11125.610603628835, 11201.103911686883, 10720.249582529148, 10821.891781082088, 10873.782524349626, 11015.504302306255, 11176.608818691631, 10875.238733288594, 11045.447727613426, 11356.977871991561, 11562.421878906189, 11568.363739994382, 8090.474119182234, 8198.1391953844, 8234.094249632415, 8265.628046738031, 8467.49195048299, 9720.41677499324, 10948.069414099891, 11060.668179954615, 11086.390248536409, 11165.274257173258], "value": 9491.28386554181, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1324.8542660307364, 1512.750396184629, 1609.8470645288696, 1971.4578490914996, 1994.3917105282883, 7895.447318687694, 8666.653333866645, 8770.122987701227, 8808.854848869654, 8845.823083538326, 6781.35577120174, 8601.311895048397, 8721.302295816335, 8723.825523489526, 8800.943886733601, 2449.853008819472, 2460.766227208412, 2477.9752202478016, 2479.5536803375385, 2483.1681386735377, 2442.426029882985, 2489.962650560243, 2490.7758301752838, 2492.663490428796, 2500.624906264066, 2476.1333533263396, 2492.3270716906513, 2506.6490691303234, 2520.395105174754, 2529.468811549577, 2470.703515578124, 2472.43134079161, 2486.3286912533695, 2492.8379655322346, 2501.3371456564023, 2446.5351583199313, 2474.8020158387285, 2488.962665560018, 2489.2034548944166, 2514.88683009265, 8937.0, 8985.595648195782, 8992.550372481415, 8997.235235004968, 9003.00966893641, 4580.244259697146, 5425.511703946624, 5556.972215138899, 5572.275604171432, 5607.775688972436, 3019.290466740314, 3043.4217498675403, 3207.4066297734994, 3460.169559305774, 3496.2832619313235, 1059.8622179116664, 1061.389700921973, 1064.8881867403888, 1085.7114398127428, 1089.7657003744232, 11496.252743571642, 11556.53773849045, 11692.415379231004, 11701.180917335874, 11705.473253703603, 4622.976885115553, 4637.837675681335, 4672.672912896064, 4686.039361930796, 4737.147313483606, 9290.0, 9311.90688093124, 9332.113449222314, 9340.299477539076, 9343.71968840936, 5255.264263003182, 5273.762680679379, 5341.4925582069645, 5343.545798607084, 5393.164059570773, 4088.713790034669, 4403.361512580658, 4940.75296235194, 4956.281339205795, 4957.405111386656, 3965.325894597925, 3969.166475040216, 4000.8599699010397, 4009.3184158693093, 4086.8773936781945, 1239.0830785218873, 1253.135336617731, 1254.6549698832894, 1260.4811202500964, 1265.4052595280205, 859.7377799771004, 939.4551160326994, 940.2759874896292, 949.5584553182665, 968.675493709601, 5523.763869331189, 5980.372060933626, 5996.0, 6116.3577824328695, 6155.630662160284, 466.54511850945056, 469.4530871534624, 472.1336347801822, 487.92193249079975, 1037.1754455207977, 3444.431668774624, 3478.339115568034, 3795.886123416247, 3919.235749028969, 4044.1911617675996, 10523.631672891412, 10743.0, 10829.891701083046, 10894.237403381687, 10912.945435272773, 10510.054095131474, 10561.155107591374, 10575.576976920836, 10672.466376681135, 10676.252662313564, 7902.170272121344, 8188.385871059694, 8198.877016844752, 8239.299659528888, 8285.8342833143, 5643.830685079454, 5923.703814809329, 5945.583809133319, 5948.8102379525135, 6049.062395328731, 7327.413806895543, 8078.515289082674, 9710.22318214527, 9758.609655613696, 9777.657781977596, 9636.75908102275, 9720.465374404475, 9916.801663966537, 9917.900820991697, 9949.054839790208, 6195.411435913581, 6994.755183568551, 7493.88759168602, 7540.924590754023, 7682.423818213768, 8263.0, 8295.709650162215, 8325.95837020811, 8370.0, 8432.915670843213, 7308.890366644611, 7419.258074192538, 7438.962805185939, 7536.397088233038, 7539.660715267936, 7275.1997280298065, 7280.089988751406, 7348.559086454829, 7358.411327093927, 7452.701891924262, 6916.1700595926795, 6974.442044636518, 6986.860262794818, 7015.36861682451, 7075.0, 4794.736289504111, 4856.223004319433, 4883.804647814048, 4889.7799599018845, 4919.0, 4892.046051020017, 4944.085344211332, 5008.0, 5029.371328583927, 5223.634345595772], "value": 4524.446798041059, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [286.8809444080706, 327.4351743242905, 330.68089293831446, 331.76444724245783, 334.1729220180053, 6646.969719693449, 6747.257801641816, 6759.290274521179, 6768.187817461899, 6821.556598821079, 6625.834354141149, 6674.799756007327, 6681.59910405376, 6686.665666716657, 6687.933120668792, 4738.455077666061, 4773.713577185363, 4903.779329930162, 4913.901721965576, 4939.407271127469, 4827.300041493982, 4886.584640305578, 4903.53416425439, 4905.901881962376, 4909.852704418855, 4850.781714822841, 4851.563359297663, 4858.931035172256, 4866.975665121687, 4903.730294833783, 4853.878653033667, 4869.293952376921, 4875.634327425421, 4888.535589119028, 4918.754062296871, 4214.810333534998, 4221.97889010553, 4222.493300803892, 4228.9154216915485, 4246.978765106154, 6184.907226391562, 6366.554341196118, 6375.2149398168585, 6445.323241059715, 6455.4835613150835, 5788.479036886701, 5851.093080572476, 5870.471657550841, 5885.79399721012, 5907.0, 5808.4772370486435, 6974.988626649108, 7101.751438699621, 7102.218755936843, 7166.784996450063, 4213.0, 4396.406485124531, 4734.597559207472, 4745.857624271277, 4755.286706993978, 7494.812629684247, 7529.661165247522, 7532.284432978859, 7548.9613057014785, 7563.848723025509, 3963.9405408918888, 4641.187792136363, 4855.441624213261, 4856.611471082376, 4865.416150062015, 4885.169521181408, 4937.629677774181, 4940.283658869444, 4942.159832828428, 4954.826581069645, 5812.82561523155, 5822.214001109881, 5825.592208545361, 5837.445442682938, 5846.269216347957, 5879.911801322984, 6086.2087928569, 7105.289471052956, 7208.207097219301, 7214.819629509304, 1698.6347935193992, 1717.4761697682077, 1719.4497760716451, 1721.5437908954136, 1722.6124122072433, 904.6788390121544, 1085.8751243606928, 1096.1011970184531, 1107.6621630402644, 1107.9889201107885, 5967.836271927019, 6309.968450157719, 6665.166854143232, 6755.392014718699, 6763.898541521881, 6561.606303621798, 7125.1093613298335, 7443.255674432622, 7453.068366454193, 7496.962515187498, 6577.112089867902, 6665.333466653393, 6690.59856408617, 6789.490788190807, 6800.251972283045, 3960.386140148282, 3984.183242435294, 3989.8005099744896, 4002.4996875390575, 4008.5390180129657, 7893.97378340812, 7897.960510197412, 7909.0, 7916.485428447132, 7925.0489941207425, 7520.172780994087, 7817.687292508236, 7845.0978137513775, 7854.960725196337, 7867.685292588232, 6658.800235992928, 7906.644201010969, 7926.445148839526, 7941.6029198539845, 7958.562279074591, 7529.698812047676, 7553.282438168365, 7562.0925488941675, 7573.0, 7577.848443031219, 7411.740589079356, 7422.925770742439, 7429.473978847218, 7433.776986690408, 7449.8882516761205, 5920.875033743575, 7010.579365238102, 7078.0, 7629.771106866803, 7903.84192316162, 4647.604953578983, 4655.767211639337, 4689.413823272091, 4702.905941881211, 4713.882152946204, 5930.644161350333, 7419.220981796833, 7454.030975973089, 7460.589667568336, 7463.552186868806, 7174.426045916419, 7182.0, 7191.676374563052, 7204.9639751800905, 7219.061522002106, 5464.972675136758, 6710.99335099739, 7207.675654595451, 7238.6018768968215, 7244.094488188976, 6965.651717414008, 6993.895091573529, 6995.685194166173, 7006.719731210695, 7009.12385951756, 7190.280971902768, 7207.387372073223, 7210.495265331377, 7230.6384680766805, 7235.131784185932, 6370.458511026614, 6386.9680651596445, 7033.929660703532, 7037.542559733601, 7079.929200707927], "value": 5212.556354241784, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [685.1675214614244, 752.8531936272423, 753.077480086894, 755.3654929858919, 757.7916073079903, 15317.157556334398, 15426.537203883901, 15587.844121558795, 15634.921825390857, 15645.061296322214, 14072.92963535181, 14129.222892740896, 14145.92927035366, 14148.78776818346, 14173.858261417408, 725.5864157430267, 918.8759517465123, 926.1063074133443, 940.2759874896326, 942.0955882352915, 913.5614904845645, 925.7916968682026, 932.3287233192125, 934.7943452440486, 939.8261321655482, 1003.0521157506159, 1003.0621369019954, 1003.172382784206, 1006.4716024087339, 1008.7679833638267, 940.2524992630875, 940.5720397219249, 971.621067783565, 986.0336869867534, 996.3324572536382, 668.7258224128084, 704.183147548846, 712.5582139073807, 725.9201487836334, 740.3521918321469, 14449.855501444956, 14489.347979340955, 14668.0, 14866.479673211386, 14897.180655063967, 12690.74618507634, 12708.428120734588, 12908.677283067904, 12932.612021639367, 13056.0, 9868.259880508918, 9983.700488985269, 10144.847827282596, 10307.0, 10398.168146548258, 3460.2041530448137, 3556.3183523588923, 3592.6407359263862, 3600.891973240807, 3624.3657359961903, 74903.87644185287, 78651.0, 79141.0, 79271.0, 81432.96417589608, 9537.856932146024, 9559.091886270793, 9620.75948101303, 9705.369151005161, 9741.123298903132, 22018.779812201672, 22156.55686886253, 22193.889030555067, 22214.66677999831, 22301.888490557445, 7478.177400485943, 7544.962275188589, 7565.167831538526, 7619.9238007619215, 7645.885311720329, 3874.593167717349, 4160.188763191149, 4162.854300099482, 4164.3961625564125, 4171.207470580641, 3561.8424012195624, 3587.515685382492, 3589.4077477215965, 3608.0979755061235, 3619.5475565554307, 638.396715104222, 649.2598437780933, 649.9350064993557, 654.4142992022114, 655.9278479367266, 595.4462350014528, 595.4611076975258, 598.4583951523878, 602.3073465514693, 608.4159207161175, 8230.506169629842, 8248.422610417334, 8282.461639993382, 8394.958025209835, 8421.831563368698, 520.2975982423698, 530.6206062665212, 571.2630706388795, 599.8200539838031, 616.2635650397822, 2747.7939154563487, 3556.288742251508, 3876.8449262029767, 4051.47330846988, 4139.834406623761, 78927.21072789199, 86281.0, 95451.52274238585, 98045.0, 98122.0, 73219.53560928858, 74087.25912740911, 74517.0, 79227.0, 83860.0, 45233.0, 58085.54786130381, 66618.33381666218, 67048.99426508704, 70173.2982670177, 7138.536599997092, 8111.472754270953, 8450.19723126302, 8587.398882078194, 8647.138090668122, 37177.25645487129, 43247.0, 46147.84630384267, 48472.5152748468, 49846.0, 67029.65940681257, 68869.0, 69654.30345696681, 69934.0, 70029.29970700228, 9445.197158241626, 10906.50920708554, 11055.336679799238, 11147.832782508107, 11721.945024947796, 11925.522979080739, 12069.758604827679, 12108.334041627795, 12167.391630418266, 12266.75466490683, 11276.718082048015, 11400.201985860911, 11490.94254528722, 11655.950964412905, 11845.822312665488, 10993.450327483753, 11191.832122518006, 11258.437078145897, 11449.198556101326, 11701.414929253333, 11105.444727763417, 11124.888751112385, 11258.605948791754, 11262.211645185087, 11524.0, 9613.615455381987, 9615.855762163714, 9726.416415015121, 9828.5085745711, 10106.646267380607, 7623.4663573549315, 9628.037196280316, 9691.466969316756, 9695.0, 9795.167410770164], "value": 6898.3119955706225, "unit": "score"}], "name": "dromaeo_css", "value": 7490.872653028064, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 170.0, 140.0, 117.0, 118.0, 118.0, 111.0, 170.0, 146.0, 116.0], "value": 133.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 122.0, 155.0, 161.0, 118.0, 141.0, 141.0, 123.0, 176.0, 125.0], "value": 141.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 199.0, 225.0, 261.0, 194.0, 201.0, 201.0, 198.0, 205.0, 200.0], "value": 208.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 96.0, 107.0, 203.0, 86.0, 82.0, 48.0, 61.0, 153.0, 83.0], "value": 97.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 86.0, 135.0, 80.0, 115.0, 88.0, 129.0, 80.0, 82.0, 118.0], "value": 99.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 89.0, 102.0, 131.0, 76.0, 131.0, 136.0, 93.0, 94.0, 87.0], "value": 103.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 96.0, 95.0, 96.0, 94.0, 126.0, 151.0, 98.0, 96.0], "value": 104.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 144.0, 90.0, 91.0, 90.0, 91.0, 91.0, 118.0, 142.0, 117.0], "value": 112.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 65.0, 65.0, 65.0, 66.0, 65.0, 82.0, 64.0, 62.0], "value": 65.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 89.0, 103.0, 75.0, 73.0, 65.0, 66.0, 66.0, 68.0], "value": 73.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 112.0, 110.0, 106.0, 74.0, 95.0, 109.0, 107.0, 109.0, 73.0], "value": 97.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 153.0, 149.0, 128.0, 131.0, 133.0, 152.0, 123.0, 168.0, 171.0], "value": 145.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 169.0, 168.0, 167.0, 169.0, 167.0, 172.0, 170.0, 164.0, 176.0], "value": 169.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 71.0, 66.0, 69.0, 65.0, 69.0, 70.0, 73.0, 72.0], "value": 69.0, "unit": "score"}], "name": "kraken", "value": 1620.5, "alertThreshold": 2.0}]} 16:12:34 INFO - Return code: 0 16:12:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:12:34 ERROR - # TBPL SUCCESS # 16:12:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:12:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:12:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:12:34 INFO - 2016-05-30 16:12:34.543 screenresolution[1685:11124] starting screenresolution argv=screenresolution get 16:12:34 INFO - 2016-05-30 16:12:34.545 screenresolution[1685:11124] Display 0: 1600x1200x32@60 16:12:34 INFO - 2016-05-30 16:12:34.551 screenresolution[1686:11126] starting screenresolution argv=screenresolution list 16:12:34 INFO - Available Modes on Display 0 16:12:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:12:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:12:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:12:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:12:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:12:34 INFO - Intel Iris: 16:12:34 INFO - Chipset Model: Intel Iris 16:12:34 INFO - Type: GPU 16:12:34 INFO - Bus: Built-In 16:12:34 INFO - VRAM (Dynamic, Max): 1536 MB 16:12:34 INFO - Vendor: Intel (0x8086) 16:12:34 INFO - Device ID: 0x0a2e 16:12:34 INFO - Revision ID: 0x0009 16:12:34 INFO - Displays: 16:12:34 INFO - Display: 16:12:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:12:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:12:34 INFO - Display Serial Number: 1600x1200 60 16:12:34 INFO - Main Display: Yes 16:12:34 INFO - Mirror: Off 16:12:34 INFO - Online: Yes 16:12:34 INFO - Rotation: Supported 16:12:34 INFO - Return code: 0 16:12:34 INFO - Running post-action listener: _resource_record_post_action 16:12:34 INFO - Running post-run listener: _resource_record_post_run 16:12:35 INFO - Total resource usage - Wall time: 1205s; CPU: 53.0%; Read bytes: 31243776; Write bytes: 656702976; Read time: 197; Write time: 1952 16:12:35 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 346036736; Write bytes: 346413056; Read time: 26409; Write time: 730 16:12:35 INFO - run-tests - Wall time: 1174s; CPU: 53.0%; Read bytes: 30318080; Write bytes: 300332544; Read time: 186; Write time: 1187 16:12:35 INFO - Running post-run listener: _upload_blobber_files 16:12:35 INFO - Blob upload gear active. 16:12:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:12:35 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 16:12:35 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'] 16:12:35 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 16:12:36 INFO - (blobuploader) - INFO - Open directory for files ... 16:12:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:12:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:12:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:12:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:12:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:12:36 INFO - (blobuploader) - INFO - Done attempting. 16:12:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:12:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:12:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:12:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:12:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:12:38 INFO - (blobuploader) - INFO - Done attempting. 16:12:38 INFO - (blobuploader) - INFO - Iteration through files over. 16:12:38 INFO - Return code: 0 16:12:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:12:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:12:38 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b0da0bdd82cf8afa545d9667b39cb81733c924ccd8920ca3f0ff3b6a8f16a03a61aa7e0c515260621aef19392bbcec7ef2511ce00ac23ffb6a40f3b75aecdb6d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a8c106da693b0f5bee2d3e768f32a53695b8b6fb9f73af64c9b6f03c0bb93c95734f78b962c192c520c1e9ff12c74d1f487db284ae154c17bcdbc01bbe12ce0f"} 16:12:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:12:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:12:38 INFO - Contents: 16:12:38 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b0da0bdd82cf8afa545d9667b39cb81733c924ccd8920ca3f0ff3b6a8f16a03a61aa7e0c515260621aef19392bbcec7ef2511ce00ac23ffb6a40f3b75aecdb6d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a8c106da693b0f5bee2d3e768f32a53695b8b6fb9f73af64c9b6f03c0bb93c95734f78b962c192c520c1e9ff12c74d1f487db284ae154c17bcdbc01bbe12ce0f"} 16:12:38 INFO - Running post-run listener: copy_logs_to_upload_dir 16:12:38 INFO - Copying logs to upload dir... 16:12:38 INFO - mkdir: /builds/slave/test/build/upload/logs 16:12:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1312.107769 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 52 secs) (at 2016-05-30 16:12:38.145697) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 16:12:38.149915) ========= 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.M4ICUpDxpj/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.ehpildmjMm/Listeners TMPDIR=/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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/b0da0bdd82cf8afa545d9667b39cb81733c924ccd8920ca3f0ff3b6a8f16a03a61aa7e0c515260621aef19392bbcec7ef2511ce00ac23ffb6a40f3b75aecdb6d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a8c106da693b0f5bee2d3e768f32a53695b8b6fb9f73af64c9b6f03c0bb93c95734f78b962c192c520c1e9ff12c74d1f487db284ae154c17bcdbc01bbe12ce0f"} build_url:https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011315 build_url: 'https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/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/b0da0bdd82cf8afa545d9667b39cb81733c924ccd8920ca3f0ff3b6a8f16a03a61aa7e0c515260621aef19392bbcec7ef2511ce00ac23ffb6a40f3b75aecdb6d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a8c106da693b0f5bee2d3e768f32a53695b8b6fb9f73af64c9b6f03c0bb93c95734f78b962c192c520c1e9ff12c74d1f487db284ae154c17bcdbc01bbe12ce0f"}' ========= master_lag: 0.10 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 16:12:38.260377) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:12:38.260960) ========= 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.M4ICUpDxpj/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.ehpildmjMm/Listeners TMPDIR=/var/folders/b8/n19k_6s53931h2l5j8hbqxrr00000w/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.005129 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:12:38.285119) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 16:12:38.285470) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 16:12:38.285828) ========= ========= Total master_lag: 0.24 =========