builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0044 starttime: 1462960405.05 results: success (0) buildid: 20160511004106 builduid: b433319d64204f6d9ee552857f8fcb01 revision: 9a64ae897313031dbca1cf58d4ff6aff64eb3009 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 02:53:25.052840) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 02:53:25.053330) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 02:53:25.053598) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 02:53:25.071657) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 02:53:25.072053) ========= 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.TGFezSwMHc/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.KQK6JKMM4g/Listeners TMPDIR=/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/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-11 02:53:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 6.70M=0.002s 2016-05-11 02:53:25 (6.70 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.081668 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 02:53:25.171901) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 02:53:25.172282) ========= 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.TGFezSwMHc/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.KQK6JKMM4g/Listeners TMPDIR=/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/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.019521 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 02:53:25.221174) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 mins, 13 secs) (at 2016-05-11 02:53:25.221528) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 9a64ae897313031dbca1cf58d4ff6aff64eb3009 --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 9a64ae897313031dbca1cf58d4ff6aff64eb3009 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TGFezSwMHc/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.KQK6JKMM4g/Listeners TMPDIR=/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/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-11 02:53:25,293 truncating revision to first 12 chars 2016-05-11 02:53:25,293 Setting DEBUG logging. 2016-05-11 02:53:25,293 attempt 1/10 2016-05-11 02:53:25,293 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/9a64ae897313?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-11 02:54:26,790 sleeping for 11.00s (attempt 1/10) 2016-05-11 02:54:37,791 attempt 2/10 2016-05-11 02:54:37,792 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/9a64ae897313?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-11 02:54:38,658 unpacking tar archive at: mozilla-aurora-9a64ae897313/testing/mozharness/ program finished with exit code 0 elapsedTime=73.620748 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 mins, 13 secs) (at 2016-05-11 02:54:38.858737) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 02:54:38.859083) ========= script_repo_revision: 9a64ae897313031dbca1cf58d4ff6aff64eb3009 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 02:54:38.859526) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 02:54:38.859813) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 02:54:38.873342) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-05-11 02:54:38.873617) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TGFezSwMHc/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.KQK6JKMM4g/Listeners TMPDIR=/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 02:54:39 INFO - MultiFileLogger online at 20160511 02:54:39 in /builds/slave/test 02:54:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:54:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:54:39 INFO - {'append_to_log': False, 02:54:39 INFO - 'base_work_dir': '/builds/slave/test', 02:54:39 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:54:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:54:39 INFO - 'branch': 'Mozilla-Aurora', 02:54:39 INFO - 'buildbot_json_path': 'buildprops.json', 02:54:39 INFO - 'config_files': ('talos/mac_config.py',), 02:54:39 INFO - 'default_actions': ('clobber', 02:54:39 INFO - 'read-buildbot-config', 02:54:39 INFO - 'download-and-extract', 02:54:39 INFO - 'populate-webroot', 02:54:39 INFO - 'create-virtualenv', 02:54:39 INFO - 'install', 02:54:39 INFO - 'run-tests'), 02:54:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:54:39 INFO - 'download_minidump_stackwalk': True, 02:54:39 INFO - 'download_symbols': 'ondemand', 02:54:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:54:39 INFO - 'tooltool.py': '/tools/tooltool.py', 02:54:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:54:39 INFO - '/tools/misc-python/virtualenv.py')}, 02:54:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:54:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:54:39 INFO - 'installer_path': 'installer.exe', 02:54:39 INFO - 'log_level': 'info', 02:54:39 INFO - 'log_name': 'talos', 02:54:39 INFO - 'log_to_console': True, 02:54:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:54:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:54:39 INFO - 'opt_config_files': (), 02:54:39 INFO - 'pip_index': False, 02:54:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:54:39 INFO - 'cmd': ('bash', 02:54:39 INFO - '-c', 02:54:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:54:39 INFO - 'enabled': True, 02:54:39 INFO - 'halt_on_failure': False, 02:54:39 INFO - 'name': 'check_screen_resolution'},), 02:54:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:54:39 INFO - 'cmd': ('bash', 02:54:39 INFO - '-c', 02:54:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:54:39 INFO - 'enabled': True, 02:54:39 INFO - 'halt_on_failure': False, 02:54:39 INFO - 'name': 'check_screen_resolution'},), 02:54:39 INFO - 'run_cmd_checks_enabled': True, 02:54:39 INFO - 'sps_profile': False, 02:54:39 INFO - 'sps_profile_interval': 0, 02:54:39 INFO - 'suite': 'dromaeojs', 02:54:39 INFO - 'system_bits': '32', 02:54:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:54:39 INFO - 'title': 't-yosemite-r7-0044', 02:54:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:54:39 INFO - 'use_talos_json': True, 02:54:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:54:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:54:39 INFO - 'work_dir': 'build'} 02:54:39 INFO - ##### 02:54:39 INFO - ##### Running clobber step. 02:54:39 INFO - ##### 02:54:39 INFO - Running pre-action listener: _resource_record_pre_action 02:54:39 INFO - Running main action method: clobber 02:54:39 INFO - rmtree: /builds/slave/test/build 02:54:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:54:40 INFO - Running post-action listener: _resource_record_post_action 02:54:40 INFO - ##### 02:54:40 INFO - ##### Running read-buildbot-config step. 02:54:40 INFO - ##### 02:54:40 INFO - Running pre-action listener: _resource_record_pre_action 02:54:40 INFO - Running main action method: read_buildbot_config 02:54:40 INFO - Using buildbot properties: 02:54:40 INFO - { 02:54:40 INFO - "project": "", 02:54:40 INFO - "product": "firefox", 02:54:40 INFO - "script_repo_revision": "production", 02:54:40 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:54:40 INFO - "repository": "", 02:54:40 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:54:40 INFO - "buildid": "20160511004106", 02:54:40 INFO - "stage_platform": "macosx64", 02:54:40 INFO - "basedir": "/builds/slave/test", 02:54:40 INFO - "nightly_build": "True", 02:54:40 INFO - "buildnumber": 2, 02:54:40 INFO - "slavename": "t-yosemite-r7-0044", 02:54:40 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 02:54:40 INFO - "platform": "yosemite_r7", 02:54:40 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:54:40 INFO - "branch": "mozilla-aurora", 02:54:40 INFO - "repo_path": "releases/mozilla-aurora", 02:54:40 INFO - "revision": "9a64ae897313031dbca1cf58d4ff6aff64eb3009", 02:54:40 INFO - "pgo_build": "False", 02:54:40 INFO - "builduid": "b433319d64204f6d9ee552857f8fcb01", 02:54:40 INFO - "slavebuilddir": "test" 02:54:40 INFO - } 02:54:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 02:54:40 INFO - Running post-action listener: _resource_record_post_action 02:54:40 INFO - ##### 02:54:40 INFO - ##### Running download-and-extract step. 02:54:40 INFO - ##### 02:54:40 INFO - Running pre-action listener: _resource_record_pre_action 02:54:40 INFO - Running main action method: download_and_extract 02:54:40 INFO - mkdir: /builds/slave/test/build/tests 02:54:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:54:40 INFO - https://queue.taskcluster.net/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:54:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:54:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:54:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:54:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 02:54:42 INFO - Downloaded 1199 bytes. 02:54:42 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:54:42 INFO - Using the following test package requirements: 02:54:42 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:54:42 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:54:42 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 02:54:42 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:54:42 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 02:54:42 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:54:42 INFO - u'jsshell-mac.zip'], 02:54:42 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:54:42 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 02:54:42 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:54:42 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:54:42 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 02:54:42 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:54:42 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 02:54:42 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:54:42 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 02:54:42 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:54:42 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 02:54:42 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:54:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:54:42 INFO - https://queue.taskcluster.net/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:54:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:54:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:54:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:54:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:54:43 INFO - Downloaded 18089367 bytes. 02:54:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:54:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:54:46 INFO - Return code: 0 02:54:46 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:54:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:54:46 INFO - https://queue.taskcluster.net/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:54:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:54:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:54:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:54:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:54:48 INFO - Downloaded 18089367 bytes. 02:54:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:54:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:54:52 INFO - Return code: 0 02:54:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:54:52 INFO - https://queue.taskcluster.net/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:54:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:54:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:54:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:54:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:54:53 INFO - Downloaded 10840145 bytes. 02:54:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:54:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 02:54:53 INFO - Return code: 0 02:54:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:54:53 INFO - https://queue.taskcluster.net/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 02:54:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:54:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:54:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 02:54:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 02:54:57 INFO - Downloaded 114218219 bytes. 02:54:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:54:57 INFO - mkdir: /builds/slave/test/properties 02:54:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:54:57 INFO - Writing to file /builds/slave/test/properties/build_url 02:54:57 INFO - Contents: 02:54:57 INFO - build_url:https://queue.taskcluster.net/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:54:58 INFO - Running post-action listener: _resource_record_post_action 02:54:58 INFO - Running post-action listener: set_extra_try_arguments 02:54:58 INFO - ##### 02:54:58 INFO - ##### Running populate-webroot step. 02:54:58 INFO - ##### 02:54:58 INFO - Running pre-action listener: _resource_record_pre_action 02:54:58 INFO - Running main action method: populate_webroot 02:54:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:54:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:54:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:54:58 INFO - '--tppagecycles', 02:54:58 INFO - '7'], 02:54:58 INFO - 'tests': ['tsvgm']}}, 02:54:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:54:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:54:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:54:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:54:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:54:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:54:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:54:58 INFO - 'talos_options': ['--disable-e10s'], 02:54:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:54:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:54:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:54:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:54:58 INFO - 'talos_options': ['--disable-e10s'], 02:54:58 INFO - 'tests': ['damp', 'tps']}, 02:54:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:54:58 INFO - 'tests': ['damp', 'tps']}, 02:54:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:54:58 INFO - 'tests': ['dromaeo_dom']}, 02:54:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:54:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:54:58 INFO - 'tests': ['a11yr', 02:54:58 INFO - 'ts_paint', 02:54:58 INFO - 'tpaint', 02:54:58 INFO - 'sessionrestore', 02:54:58 INFO - 'sessionrestore_no_auto_restore', 02:54:58 INFO - 'tabpaint']}, 02:54:58 INFO - 'other-e10s': {'tests': ['a11yr', 02:54:58 INFO - 'ts_paint', 02:54:58 INFO - 'tpaint', 02:54:58 INFO - 'sessionrestore', 02:54:58 INFO - 'sessionrestore_no_auto_restore', 02:54:58 INFO - 'tabpaint']}, 02:54:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:54:58 INFO - 'ts_paint', 02:54:58 INFO - 'tpaint', 02:54:58 INFO - 'sessionrestore', 02:54:58 INFO - 'sessionrestore_no_auto_restore', 02:54:58 INFO - 'tabpaint']}, 02:54:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:54:58 INFO - 'ts_paint', 02:54:58 INFO - 'tpaint', 02:54:58 INFO - 'sessionrestore', 02:54:58 INFO - 'sessionrestore_no_auto_restore', 02:54:58 INFO - 'tabpaint']}, 02:54:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:54:58 INFO - 'tests': ['a11yr', 02:54:58 INFO - 'ts_paint', 02:54:58 INFO - 'tpaint', 02:54:58 INFO - 'sessionrestore', 02:54:58 INFO - 'sessionrestore_no_auto_restore', 02:54:58 INFO - 'tabpaint']}, 02:54:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:54:58 INFO - 'tests': ['a11yr', 02:54:58 INFO - 'ts_paint', 02:54:58 INFO - 'tpaint', 02:54:58 INFO - 'sessionrestore', 02:54:58 INFO - 'sessionrestore_no_auto_restore', 02:54:58 INFO - 'tabpaint']}, 02:54:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:54:58 INFO - 'tests': ['tsvgx', 02:54:58 INFO - 'tsvgr_opacity', 02:54:58 INFO - 'tart', 02:54:58 INFO - 'tscrollx', 02:54:58 INFO - 'cart']}, 02:54:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:54:58 INFO - 'tsvgr_opacity', 02:54:58 INFO - 'tart', 02:54:58 INFO - 'tscrollx', 02:54:58 INFO - 'cart']}, 02:54:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:54:58 INFO - 'talos_options': ['--disable-e10s'], 02:54:58 INFO - 'tests': ['tp5o']}, 02:54:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:54:58 INFO - 'tests': ['tp5o']}, 02:54:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:54:58 INFO - 'talos_options': ['--disable-e10s', 02:54:58 INFO - '--xperf_path', 02:54:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:54:58 INFO - 'tests': ['tp5n']}, 02:54:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:54:58 INFO - 'talos_options': ['--xperf_path', 02:54:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:54:58 INFO - 'tests': ['tp5n']}}, 02:54:58 INFO - 'talos.zip': {'path': '', 02:54:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:54:58 INFO - Running post-action listener: _resource_record_post_action 02:54:58 INFO - ##### 02:54:58 INFO - ##### Running create-virtualenv step. 02:54:58 INFO - ##### 02:54:58 INFO - Running pre-action listener: _resource_record_pre_action 02:54:58 INFO - Running main action method: create_virtualenv 02:54:58 INFO - Creating virtualenv /builds/slave/test/build/venv 02:54:58 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:54:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:54:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:54:58 INFO - Using real prefix '/tools/python27' 02:54:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:54:59 INFO - Installing distribute.............................................................................................................................................................................................done. 02:55:02 INFO - Installing pip.................done. 02:55:02 INFO - Return code: 0 02:55:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:55:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:55:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:55:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:55:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:55:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a003a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a013420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febb959c070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ea2e00>, '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 0x109d0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e9db20>, '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.TGFezSwMHc/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.KQK6JKMM4g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:55:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:55:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:55:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TGFezSwMHc/Render', 02:55:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:55:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:55:02 INFO - 'HOME': '/Users/cltbld', 02:55:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:55:02 INFO - 'LOGNAME': 'cltbld', 02:55:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:02 INFO - 'MOZ_NO_REMOTE': '1', 02:55:02 INFO - 'NO_EM_RESTART': '1', 02:55:02 INFO - 'PAGER': '/bin/cat', 02:55:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:55:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:02 INFO - 'PWD': '/builds/slave/test', 02:55:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:55:02 INFO - 'SHELL': '/bin/bash', 02:55:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KQK6JKMM4g/Listeners', 02:55:02 INFO - 'TMPDIR': '/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/', 02:55:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:55:02 INFO - 'USER': 'cltbld', 02:55:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:55:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:55:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:02 INFO - 'XPC_FLAGS': '0x0', 02:55:02 INFO - 'XPC_SERVICE_NAME': '0', 02:55:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:55:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:55:02 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:55:02 INFO - Cleaning up... 02:55:02 INFO - Return code: 0 02:55:02 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:55:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:55:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:55:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:55:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:55:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a003a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a013420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febb959c070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ea2e00>, '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 0x109d0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e9db20>, '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.TGFezSwMHc/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.KQK6JKMM4g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:55:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 02:55:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 02:55:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TGFezSwMHc/Render', 02:55:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:55:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:55:02 INFO - 'HOME': '/Users/cltbld', 02:55:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:55:02 INFO - 'LOGNAME': 'cltbld', 02:55:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:02 INFO - 'MOZ_NO_REMOTE': '1', 02:55:02 INFO - 'NO_EM_RESTART': '1', 02:55:02 INFO - 'PAGER': '/bin/cat', 02:55:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:55:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:02 INFO - 'PWD': '/builds/slave/test', 02:55:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:55:02 INFO - 'SHELL': '/bin/bash', 02:55:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KQK6JKMM4g/Listeners', 02:55:02 INFO - 'TMPDIR': '/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/', 02:55:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:55:02 INFO - 'USER': 'cltbld', 02:55:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:55:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:55:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:02 INFO - 'XPC_FLAGS': '0x0', 02:55:02 INFO - 'XPC_SERVICE_NAME': '0', 02:55:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:55:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:55:02 INFO - Downloading/unpacking psutil>=0.7.1 02:55:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:55:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:55:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:55:06 INFO - Installing collected packages: psutil 02:55:06 INFO - Running setup.py install for psutil 02:55:06 INFO - building 'psutil._psutil_osx' extension 02:55:06 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:55:06 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:55:06 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:55:06 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:55:06 INFO - building 'psutil._psutil_posix' extension 02:55:06 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:55:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:55:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:55:06 INFO - ^ 02:55:06 INFO - 1 warning generated. 02:55:06 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:55:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:55:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:55:06 INFO - Successfully installed psutil 02:55:06 INFO - Cleaning up... 02:55:06 INFO - Return code: 0 02:55:06 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:55:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:55:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:55:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:55:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:55:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55: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', '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 0x10a003a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a013420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febb959c070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ea2e00>, '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 0x109d0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e9db20>, '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.TGFezSwMHc/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.KQK6JKMM4g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:55: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 02:55: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 mozsystemmonitor==0.0.0 02:55:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TGFezSwMHc/Render', 02:55:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:55:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:55:06 INFO - 'HOME': '/Users/cltbld', 02:55:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:55:06 INFO - 'LOGNAME': 'cltbld', 02:55:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:06 INFO - 'MOZ_NO_REMOTE': '1', 02:55:06 INFO - 'NO_EM_RESTART': '1', 02:55:06 INFO - 'PAGER': '/bin/cat', 02:55:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:55:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:06 INFO - 'PWD': '/builds/slave/test', 02:55:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:55:06 INFO - 'SHELL': '/bin/bash', 02:55:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KQK6JKMM4g/Listeners', 02:55:06 INFO - 'TMPDIR': '/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/', 02:55:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:55:06 INFO - 'USER': 'cltbld', 02:55:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:55:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:55:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:06 INFO - 'XPC_FLAGS': '0x0', 02:55:06 INFO - 'XPC_SERVICE_NAME': '0', 02:55:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:55:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:55:07 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:55:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:09 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:55:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:55:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:55:09 INFO - Installing collected packages: mozsystemmonitor 02:55:09 INFO - Running setup.py install for mozsystemmonitor 02:55:09 INFO - Successfully installed mozsystemmonitor 02:55:09 INFO - Cleaning up... 02:55:09 INFO - Return code: 0 02:55:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:55:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:55:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:55:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:55:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:55:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:09 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 0x10a003a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a013420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febb959c070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ea2e00>, '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 0x109d0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e9db20>, '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.TGFezSwMHc/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.KQK6JKMM4g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:55:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:55:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:55:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TGFezSwMHc/Render', 02:55:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:55:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:55:09 INFO - 'HOME': '/Users/cltbld', 02:55:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:55:09 INFO - 'LOGNAME': 'cltbld', 02:55:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:09 INFO - 'MOZ_NO_REMOTE': '1', 02:55:09 INFO - 'NO_EM_RESTART': '1', 02:55:09 INFO - 'PAGER': '/bin/cat', 02:55:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:55:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:09 INFO - 'PWD': '/builds/slave/test', 02:55:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:55:09 INFO - 'SHELL': '/bin/bash', 02:55:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KQK6JKMM4g/Listeners', 02:55:09 INFO - 'TMPDIR': '/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/', 02:55:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:55:09 INFO - 'USER': 'cltbld', 02:55:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:55:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:55:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:09 INFO - 'XPC_FLAGS': '0x0', 02:55:09 INFO - 'XPC_SERVICE_NAME': '0', 02:55:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:55:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:55:10 INFO - Downloading/unpacking blobuploader==1.2.4 02:55:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:12 INFO - Downloading blobuploader-1.2.4.tar.gz 02:55:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:55:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:55:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:55:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:55:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:13 INFO - Downloading docopt-0.6.1.tar.gz 02:55:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:55:13 INFO - Installing collected packages: blobuploader, requests, docopt 02:55:13 INFO - Running setup.py install for blobuploader 02:55:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:55:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:55:13 INFO - Running setup.py install for requests 02:55:14 INFO - Running setup.py install for docopt 02:55:14 INFO - Successfully installed blobuploader requests docopt 02:55:14 INFO - Cleaning up... 02:55:14 INFO - Return code: 0 02:55:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:55:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:55:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:55:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:55:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:55:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:14 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 0x10a003a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a013420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febb959c070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ea2e00>, '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 0x109d0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e9db20>, '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.TGFezSwMHc/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.KQK6JKMM4g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:55:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:55:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:55:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TGFezSwMHc/Render', 02:55:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:55:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:55:14 INFO - 'HOME': '/Users/cltbld', 02:55:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:55:14 INFO - 'LOGNAME': 'cltbld', 02:55:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:14 INFO - 'MOZ_NO_REMOTE': '1', 02:55:14 INFO - 'NO_EM_RESTART': '1', 02:55:14 INFO - 'PAGER': '/bin/cat', 02:55:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:55:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:14 INFO - 'PWD': '/builds/slave/test', 02:55:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:55:14 INFO - 'SHELL': '/bin/bash', 02:55:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KQK6JKMM4g/Listeners', 02:55:14 INFO - 'TMPDIR': '/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/', 02:55:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:55:14 INFO - 'USER': 'cltbld', 02:55:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:55:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:55:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:14 INFO - 'XPC_FLAGS': '0x0', 02:55:14 INFO - 'XPC_SERVICE_NAME': '0', 02:55:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:55:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:55:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:55:14 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-lXhvva-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:55:15 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-4OX0BE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:55:15 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-3LEAJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:55:15 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-8hLw8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:55:15 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-pAdvYw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:55:15 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-v7yFuR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:55:15 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-lpsIfs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:55:15 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-nxZyoc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:55:15 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-eaTW3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:55:15 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-fiO_2k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:55:16 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-Yf34is-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:55:16 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-MtN1dI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:55:16 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-eSVpIo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:55:16 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-Pw2JzU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:55:16 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-JMfsR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:55:16 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-400Nbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:55:16 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-tx8Tn8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:55:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:55:16 INFO - Running setup.py install for manifestparser 02:55:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:55:16 INFO - Running setup.py install for mozcrash 02:55:17 INFO - Running setup.py install for mozdebug 02:55:17 INFO - Running setup.py install for mozdevice 02:55:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:55:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:55:17 INFO - Running setup.py install for mozfile 02:55:17 INFO - Running setup.py install for mozhttpd 02:55:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:55:17 INFO - Running setup.py install for mozinfo 02:55:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:55:17 INFO - Running setup.py install for mozInstall 02:55:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:55:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:55:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:55:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:55:17 INFO - Running setup.py install for mozleak 02:55:18 INFO - Running setup.py install for mozlog 02:55:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:55:18 INFO - Running setup.py install for moznetwork 02:55:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:55:18 INFO - Running setup.py install for mozprocess 02:55:18 INFO - Running setup.py install for mozprofile 02:55:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:55:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:55:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:55:18 INFO - Running setup.py install for mozrunner 02:55:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:55:18 INFO - Running setup.py install for mozscreenshot 02:55:19 INFO - Running setup.py install for moztest 02:55:19 INFO - Running setup.py install for mozversion 02:55:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:55:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:55:19 INFO - Cleaning up... 02:55:19 INFO - Return code: 0 02:55:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:55:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:55:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:55:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:55:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:55:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:19 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 0x10a003a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a013420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febb959c070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ea2e00>, '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 0x109d0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e9db20>, '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.TGFezSwMHc/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.KQK6JKMM4g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:55:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:55:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:55:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TGFezSwMHc/Render', 02:55:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:55:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:55:19 INFO - 'HOME': '/Users/cltbld', 02:55:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:55:19 INFO - 'LOGNAME': 'cltbld', 02:55:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:19 INFO - 'MOZ_NO_REMOTE': '1', 02:55:19 INFO - 'NO_EM_RESTART': '1', 02:55:19 INFO - 'PAGER': '/bin/cat', 02:55:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:55:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:19 INFO - 'PWD': '/builds/slave/test', 02:55:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:55:19 INFO - 'SHELL': '/bin/bash', 02:55:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KQK6JKMM4g/Listeners', 02:55:19 INFO - 'TMPDIR': '/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/', 02:55:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:55:19 INFO - 'USER': 'cltbld', 02:55:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:55:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:55:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:19 INFO - 'XPC_FLAGS': '0x0', 02:55:19 INFO - 'XPC_SERVICE_NAME': '0', 02:55:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:55:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:55:19 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-sMPYhU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:55:19 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:55:19 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-7ogZM2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:55:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:55:19 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-W473ND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:55:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:55:19 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-EyPZMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:55:20 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-KulJPg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:55:20 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-a3sbL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:55:20 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-wnxthK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:55:20 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-Uz9QRn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:55:20 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-sovKHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:55:20 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-P_E8NS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:55:20 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-GsAaWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:55:20 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-gqCJWO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:55:20 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-YmdAeH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:55:21 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-6yxD_c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:55:21 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-7tE0FM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:55:21 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-pcdX8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:55:21 INFO - Running setup.py (path:/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/pip-562nQW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:55:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:55:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:23 INFO - Downloading blessings-1.6.tar.gz 02:55:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:55:23 INFO - Installing collected packages: blessings 02:55:23 INFO - Running setup.py install for blessings 02:55:24 INFO - Successfully installed blessings 02:55:24 INFO - Cleaning up... 02:55:24 INFO - Return code: 0 02:55:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:55:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:55:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:55:24 INFO - Reading from file tmpfile_stdout 02:55:24 INFO - Current package versions: 02:55:24 INFO - blessings == 1.6 02:55:24 INFO - blobuploader == 1.2.4 02:55:24 INFO - docopt == 0.6.1 02:55:24 INFO - manifestparser == 1.1 02:55:24 INFO - mozInstall == 1.12 02:55:24 INFO - mozcrash == 0.17 02:55:24 INFO - mozdebug == 0.1 02:55:24 INFO - mozdevice == 0.48 02:55:24 INFO - mozfile == 1.2 02:55:24 INFO - mozhttpd == 0.7 02:55:24 INFO - mozinfo == 0.9 02:55:24 INFO - mozleak == 0.1 02:55:24 INFO - mozlog == 3.1 02:55:24 INFO - moznetwork == 0.27 02:55:24 INFO - mozprocess == 0.22 02:55:24 INFO - mozprofile == 0.28 02:55:24 INFO - mozrunner == 6.11 02:55:24 INFO - mozscreenshot == 0.1 02:55:24 INFO - mozsystemmonitor == 0.0 02:55:24 INFO - moztest == 0.7 02:55:24 INFO - mozversion == 1.4 02:55:24 INFO - psutil == 3.1.1 02:55:24 INFO - requests == 1.2.3 02:55:24 INFO - wsgiref == 0.1.2 02:55:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:55: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')]} 02:55:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:55:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55: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')]} 02:55:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:55:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:24 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 0x10a003a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a013420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febb959c070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ea2e00>, '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 0x109d0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e9db20>, '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.TGFezSwMHc/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.KQK6JKMM4g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:55:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:55:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:55:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TGFezSwMHc/Render', 02:55:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:55:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:55:24 INFO - 'HOME': '/Users/cltbld', 02:55:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:55:24 INFO - 'LOGNAME': 'cltbld', 02:55:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:24 INFO - 'MOZ_NO_REMOTE': '1', 02:55:24 INFO - 'NO_EM_RESTART': '1', 02:55:24 INFO - 'PAGER': '/bin/cat', 02:55:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:55:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:24 INFO - 'PWD': '/builds/slave/test', 02:55:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:55:24 INFO - 'SHELL': '/bin/bash', 02:55:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KQK6JKMM4g/Listeners', 02:55:24 INFO - 'TMPDIR': '/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/', 02:55:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:55:24 INFO - 'USER': 'cltbld', 02:55:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:55:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:55:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:24 INFO - 'XPC_FLAGS': '0x0', 02:55:24 INFO - 'XPC_SERVICE_NAME': '0', 02:55:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:55:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:55:24 INFO - Cleaning up... 02:55:24 INFO - Return code: 0 02:55:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:55: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')]} 02:55:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:55:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55: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')]} 02:55:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:55:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a003a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a013420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febb959c070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ea2e00>, '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 0x109d0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e9db20>, '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.TGFezSwMHc/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.KQK6JKMM4g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:55: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', 'jsonschema'] in /builds/slave/test/build 02:55: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 jsonschema 02:55:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TGFezSwMHc/Render', 02:55:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:55:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:55:24 INFO - 'HOME': '/Users/cltbld', 02:55:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:55:24 INFO - 'LOGNAME': 'cltbld', 02:55:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:24 INFO - 'MOZ_NO_REMOTE': '1', 02:55:24 INFO - 'NO_EM_RESTART': '1', 02:55:24 INFO - 'PAGER': '/bin/cat', 02:55:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:55:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:24 INFO - 'PWD': '/builds/slave/test', 02:55:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:55:24 INFO - 'SHELL': '/bin/bash', 02:55:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KQK6JKMM4g/Listeners', 02:55:24 INFO - 'TMPDIR': '/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/', 02:55:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:55:24 INFO - 'USER': 'cltbld', 02:55:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:55:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:55:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:24 INFO - 'XPC_FLAGS': '0x0', 02:55:24 INFO - 'XPC_SERVICE_NAME': '0', 02:55:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:55:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:55:25 INFO - Downloading/unpacking jsonschema 02:55:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:55:27 INFO - Downloading/unpacking functools32 (from jsonschema) 02:55:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:27 INFO - Downloading functools32-3.2.3-2.tar.gz 02:55:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:55:28 INFO - warning: no files found matching '*.txt' 02:55:28 INFO - no previously-included directories found matching 'build' 02:55:28 INFO - no previously-included directories found matching 'dist' 02:55:28 INFO - no previously-included directories found matching '.git*' 02:55:28 INFO - Installing collected packages: jsonschema, functools32 02:55:28 INFO - Running setup.py install for functools32 02:55:28 INFO - warning: no files found matching '*.txt' 02:55:28 INFO - no previously-included directories found matching 'build' 02:55:28 INFO - no previously-included directories found matching 'dist' 02:55:28 INFO - no previously-included directories found matching '.git*' 02:55:28 INFO - Successfully installed jsonschema functools32 02:55:28 INFO - Cleaning up... 02:55:28 INFO - Return code: 0 02:55:28 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:55:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:55:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:55:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:55:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:55:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:55:28 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 0x10a003a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a013420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febb959c070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ea2e00>, '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 0x109d0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e9db20>, '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.TGFezSwMHc/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.KQK6JKMM4g/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:55:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 02:55:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 02:55:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TGFezSwMHc/Render', 02:55:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:55:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:55:28 INFO - 'HOME': '/Users/cltbld', 02:55:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:55:28 INFO - 'LOGNAME': 'cltbld', 02:55:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:55:28 INFO - 'MOZ_NO_REMOTE': '1', 02:55:28 INFO - 'NO_EM_RESTART': '1', 02:55:28 INFO - 'PAGER': '/bin/cat', 02:55:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:55:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:55:28 INFO - 'PWD': '/builds/slave/test', 02:55:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:55:28 INFO - 'SHELL': '/bin/bash', 02:55:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KQK6JKMM4g/Listeners', 02:55:28 INFO - 'TMPDIR': '/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/', 02:55:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:55:28 INFO - 'USER': 'cltbld', 02:55:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:55:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:55:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:55:28 INFO - 'XPC_FLAGS': '0x0', 02:55:28 INFO - 'XPC_SERVICE_NAME': '0', 02:55:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:55:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:55:28 INFO - Downloading/unpacking flake8 02:55:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:30 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:55:30 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:55:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:31 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:55:31 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:55:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:31 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:55:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:55:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:55:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:55:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:55:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:55:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:55:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:55:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:55:32 INFO - no previously-included directories found matching 'docs/_build' 02:55:32 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:55:32 INFO - Running setup.py install for pep8 02:55:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:55:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:55:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:55:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:55:32 INFO - no previously-included directories found matching 'docs/_build' 02:55:32 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:55:32 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:55:32 INFO - Cleaning up... 02:55:32 INFO - Return code: 0 02:55:32 INFO - Running post-action listener: _resource_record_post_action 02:55:32 INFO - Running post-action listener: _start_resource_monitoring 02:55:32 INFO - Starting resource monitoring. 02:55:32 INFO - ##### 02:55:32 INFO - ##### Running install step. 02:55:32 INFO - ##### 02:55:32 INFO - Running pre-action listener: _resource_record_pre_action 02:55:32 INFO - Running main action method: install 02:55:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:55:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:55:33 INFO - Reading from file tmpfile_stdout 02:55:33 INFO - Detecting whether we're running mozinstall >=1.0... 02:55:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:55:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:55:33 INFO - Reading from file tmpfile_stdout 02:55:33 INFO - Output received: 02:55:33 INFO - Usage: mozinstall [options] installer 02:55:33 INFO - Options: 02:55:33 INFO - -h, --help show this help message and exit 02:55:33 INFO - -d DEST, --destination=DEST 02:55:33 INFO - Directory to install application into. [default: 02:55:33 INFO - "/builds/slave/test"] 02:55:33 INFO - --app=APP Application being installed. [default: firefox] 02:55:33 INFO - mkdir: /builds/slave/test/build/application 02:55:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:55:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:56:04 INFO - Reading from file tmpfile_stdout 02:56:04 INFO - Output received: 02:56:04 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:56:04 INFO - Running post-action listener: _resource_record_post_action 02:56:04 INFO - ##### 02:56:04 INFO - ##### Running run-tests step. 02:56:04 INFO - ##### 02:56:04 INFO - Running pre-action listener: _resource_record_pre_action 02:56:04 INFO - Running main action method: run_tests 02:56:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:56:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:56:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:56:04 INFO - 2016-05-11 02:56:04.895 screenresolution[1913:14473] starting screenresolution argv=screenresolution get 02:56:04 INFO - 2016-05-11 02:56:04.897 screenresolution[1913:14473] Display 0: 1600x1200x32@60 02:56:04 INFO - 2016-05-11 02:56:04.903 screenresolution[1914:14476] starting screenresolution argv=screenresolution list 02:56:04 INFO - Available Modes on Display 0 02:56:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:56:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:56:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:56:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:56:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:56:05 INFO - Intel Iris: 02:56:05 INFO - Chipset Model: Intel Iris 02:56:05 INFO - Type: GPU 02:56:05 INFO - Bus: Built-In 02:56:05 INFO - VRAM (Dynamic, Max): 1536 MB 02:56:05 INFO - Vendor: Intel (0x8086) 02:56:05 INFO - Device ID: 0x0a2e 02:56:05 INFO - Revision ID: 0x0009 02:56:05 INFO - Displays: 02:56:05 INFO - Display: 02:56:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:56:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:56:05 INFO - Display Serial Number: 1600x1200 60 02:56:05 INFO - Main Display: Yes 02:56:05 INFO - Mirror: Off 02:56:05 INFO - Online: Yes 02:56:05 INFO - Rotation: Supported 02:56:05 INFO - Return code: 0 02:56:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:56:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:56:05 INFO - Python 2.7.3 02:56:05 INFO - Return code: 0 02:56:05 INFO - grabbing minidump binary from tooltool 02:56:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:56:05 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 0x109ea2e00>, '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 0x109d0bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e9db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:56:05 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:56:05 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:56:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:56:05 INFO - Return code: 0 02:56:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:56:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:56:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:56:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:56:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:56:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:56:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:56:06 INFO - Return code: 0 02:56:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0044', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 02:56:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0044 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 02:56:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TGFezSwMHc/Render', 02:56:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:56:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:56:06 INFO - 'HOME': '/Users/cltbld', 02:56:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:56:06 INFO - 'LOGNAME': 'cltbld', 02:56:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:56:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:56:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:56:06 INFO - 'MOZ_NO_REMOTE': '1', 02:56:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:56:06 INFO - 'NO_EM_RESTART': '1', 02:56:06 INFO - 'PAGER': '/bin/cat', 02:56:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:56:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:56:06 INFO - 'PWD': '/builds/slave/test', 02:56:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:56:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:56:06 INFO - 'SHELL': '/bin/bash', 02:56:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KQK6JKMM4g/Listeners', 02:56:06 INFO - 'TMPDIR': '/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/', 02:56:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:56:06 INFO - 'USER': 'cltbld', 02:56:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:56:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:56:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:56:06 INFO - 'XPC_FLAGS': '0x0', 02:56:06 INFO - 'XPC_SERVICE_NAME': '0', 02:56:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:56:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0044', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 02:56:06 INFO - mozversion application_buildid: 20160511004106 02:56:06 INFO - mozversion application_changeset: 9a64ae897313031dbca1cf58d4ff6aff64eb3009 02:56:06 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:56:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:56:06 INFO - mozversion application_name: Firefox 02:56:06 INFO - mozversion application_remotingname: firefox-dev 02:56:06 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:56:06 INFO - mozversion application_vendor: Mozilla 02:56:06 INFO - mozversion application_version: 48.0a2 02:56:06 INFO - mozversion platform_buildid: 20160511004106 02:56:06 INFO - mozversion platform_changeset: 9a64ae897313031dbca1cf58d4ff6aff64eb3009 02:56:06 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:56:06 INFO - mozversion platform_version: 48.0a2 02:56:06 INFO - using testdate: 1462960566 02:56:06 INFO - actual date: 1462960566 02:56:06 INFO - starting webserver on 'localhost:52325' 02:56:06 INFO - SUITE-START | Running 2 tests 02:56:06 INFO - TEST-START | dromaeo_css 02:56:06 INFO - operating with platform_type : mac_ 02:56:06 INFO - Initialising browser for dromaeo_css test... 02:56:06 INFO - TEST-INFO | started process 1932 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/tmpgz2BJb/profile http://localhost:52325/getInfo.html) 02:56:08 INFO - PROCESS | 1932 | __metrics Screen width/height:1600/1200 02:56:08 INFO - PROCESS | 1932 | colorDepth:24 02:56:08 INFO - PROCESS | 1932 | Browser inner width/height: 1024/689 02:56:08 INFO - PROCESS | 1932 | __metrics 02:56:08 INFO - PROCESS | 1932 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:56:08 ERROR - PROCESS | 1932 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:56:09 INFO - TEST-INFO | 1932: exit 0 02:56:09 INFO - Browser initialized. 02:56:09 INFO - Running cycle 1/1 for dromaeo_css test... 02:56:09 INFO - TEST-INFO | started process 1935 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/tmpgz2BJb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:56:21 INFO - PROCESS | 1935 | RSS: Main: 211210240 02:56:21 INFO - PROCESS | 1935 | 02:57:12 INFO - PROCESS | 1935 | 1462960632957 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 02:59:23 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52325/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52325/tests/dromaeo/cssquery-ext.html) 02:59:25 INFO - PROCESS | 1935 | RSS: Main: 405676032 02:59:25 INFO - PROCESS | 1935 | 03:02:28 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52325/tests/dromaeo/cssquery-ext.html (next: http://localhost:52325/tests/dromaeo/cssquery-jquery.html) 03:02:28 INFO - PROCESS | 1935 | RSS: Main: 358760448 03:02:28 INFO - PROCESS | 1935 | 03:05:30 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52325/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52325/tests/dromaeo/cssquery-mootools.html) 03:05:31 INFO - PROCESS | 1935 | RSS: Main: 361525248 03:05:31 INFO - PROCESS | 1935 | 03:08:33 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52325/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52325/tests/dromaeo/cssquery-prototype.html) 03:08:34 INFO - PROCESS | 1935 | RSS: Main: 360665088 03:08:34 INFO - PROCESS | 1935 | 03:11:37 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52325/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52325/tests/dromaeo/cssquery-yui.html) 03:11:38 INFO - PROCESS | 1935 | RSS: Main: 330436608 03:11:38 INFO - PROCESS | 1935 | 03:14:41 INFO - PROCESS | 1935 | Cycle 1(1): loaded http://localhost:52325/tests/dromaeo/cssquery-yui.html (next: http://localhost:52325/tests/dromaeo/cssquery-dojo.html) 03:14:41 INFO - PROCESS | 1935 | RSS: Main: 367407104 03:14:41 INFO - PROCESS | 1935 | 03:14:41 INFO - PROCESS | 1935 | __start_tp_report 03:14:41 INFO - PROCESS | 1935 | _x_x_mozilla_page_load 03:14:41 INFO - PROCESS | 1935 | _x_x_mozilla_page_load_details 03:14:41 INFO - PROCESS | 1935 | |i|pagename|runs| 03:14:41 INFO - PROCESS | 1935 | |0;dojo.html;3241.0438920518454;3308.30525589626;3322.186064414219;3325.634180240175;3380.560527131474;5356.7321633918245;5371.060064488714;5372.57019438445;5376.731163441822;5382.703951282678;5354.116570765819;5374.7043912584795;5376.946230537703;5396.082665946788;5397.946020539794;4772.3795906532105;4874.147024270747;4904.926426103611;4936.679115857458;4993.246307898586;4851.102546028996;4965.453800081988;4969.850904472872;4970.453250142481;4984.3021976923255;4852.29641701953;4937.975310123445;4937.975310123445;4944.159492886218;4944.480829512903;4924.532169443915;4950.9009819803405;4985.277134815467;5013.849584512471;5020.623453241022;4782.473927867932;4814.7833347499445;4871.196252618313;4920.852374428773;4984.501549845023;5237.083510385667;5288.603354748409;5300.0459917214885;5304.257403963448;5310.309659744247;5741.425857414225;5825.271841019872;5834.212381328552;5835.066389377722;5836.766529338822;5671.461211184972;5809.622374545641;5821.912671309934;5872.324682661463;5877.500412464946;5431.8098866539485;5456.672599644034;5461.481159289861;5476.096444086721;5479.863003424906;6015.097735339734;6015.428534289235;6017.578769486182;6021.6989150542295;6031.8793624127275;5523.143912693539;5624.55003599711;5705.429457054262;5716.742746576414;5723.055695810144;5751.654900705971;5860.794872179538;5876.853078672982;5880.588358814842;5884.323302820145;5720.971395142998;5721.141828725725;5732.197492351074;5734.569907256972;5735.967525845388;5719.914201286984;5740.885182296414;5752.568557358215;5754.251947246831;5781.942180578225;4585.633149348044;4627.514111018362;4687.695299805502;4715.740634265148;4747.145513807548;4203.579642035772;4254.893627659333;4267.317229243306;4270.572942705767;4295.570442955679;5225.581953443715;5390.838274851761;5414.40441551429;5414.972925135428;5416.431274716177;4981.277714731344;5700.914486282709;5773.018586840247;5794.797182098607;5879.970600147058;5351;5361.946380536223;5370.113931201308;5380.112281473591;5384.19237114436;4267.935980960288;5127.564157046618;5135.15269980449;5148.562372198405;5189.040027594907;5942.910856337158;6018.909716354258;6079.1793107931635;6115.266168059861;6127.724252408741;5836.037053886062;5882.294124705063;5899.705014749331;5902.085176797603;5913.172155898092;5927.851803704942;6112.969435152796;6120.8775824484155;6134.754609815736;6157.384261573807;5589.692566908859;5637.605367624391;5646.85882852932;5648.406917273627;5651.745671444713;5598.972005140111;5650.604457687922;5715.31416230055;5727.971360143172;5732.627379220337;5547.667139971614;5583.134614134816;5586.832395028156;5601.271834661468;5702.173184888251;5618.606697531297;5638.323401191883;5638.520725738358;5645.06856368711;5657.773689052392;5500.367457742251;5520.972395137998;5526.55787536988;5531.170324451365;5542.8337149885565;5492.972535137299;5557.388687244481;5629.718514074362;5686.715664216856;5743.942560574508;5464.016477034172;5493.560515158698;5536.418676039119;5548.334199896039;5555.055640541199 03:14:41 INFO - PROCESS | 1935 | |1;ext.html;537.2451705353978;540.0845566764334;557.8675289162999;590.9202257695215;601.9217501724775;30606.84696576517;31337.68662313379;31422.214444638896;38040.42939355905;38803.02992425191;41754.373684394755;41979;42153.15693686132;42171.57828421723;42219.622430205585;9478.28912831537;9694.17599504043;9698.709038728814;9701;9717.028297170262;8637.697680581181;8840.513771742551;9469.905300947006;11580.420978951019;11822.467988940476;11592.188546801728;11621.709457263552;11632.360220187886;11641.941790291079;11650.883491165107;8622.741317760414;11364.431778411088;11450.141239407078;11469.54121835134;11471.19701620887;10084.64703735373;11527.308361498253;11733.647990560297;11737.823932641017;11738.823917641243;37922.810385947894;38356.041098972746;38444.038899027466;38479.03802404934;38493.23013539742;3259.3807176636365;3442.242706604543;3672.797996110213;3813.3517302058717;3840.5967373425947;27620;27637.723622763715;27640;28397;28808.991685290915;2775.264554892955;2784.540550809114;2827.618271533358;2892.8264304141817;2951.8081324713826;500180;503274;503751;504430;504668;36283.818580906925;62447;63623.681881590295;63680.36319636744;63838;35563.933082007585;35746.64253357433;35769.284614307566;35774.642253577134;35826.28347433089;15283.159426231665;15468.767968480483;15469.767953480707;15470.690586188213;15472.458463953708;12210.26738395699;12357.691057723448;13073.803892941614;13155.408006639724;13157.868421315856;8174.509529428254;8263.504189748634;8297.460665056751;8346.582670866432;8357.331413486907;3075.185075954874;3314.8895120134544;3399.7280217582534;3437.4843773434186;3461.9480707789403;1001.1790331927895;1035.736401590074;1056.7675111475462;1062.5377960587168;1100.3439823066394;24398.878005610215;29193.416131677674;29624.407511849644;30070.24824379408;30815.845920770702;1135.4436326200102;1157.3923690062616;1167.4064901409515;1184.5084290019718;1186.5016692988966;12671.17637353569;13207.273599952096;15450.6137346565;15569.688606227812;16244.269007894673;588084;632550;651915;656886;659331;51189.48810511922;60612.393876060676;61481.38518614846;61636.69181654063;61781.38218617846;1268.936553172356;1373.9038267321191;1382.640513466506;1452.7433769789268;1510.9546713598609;28760.137195884156;28909.421811563232;29145.43891128368;29308.706912930596;29338.559921600765;49345;49433.258501121796;49763.75118124386;49836.25245621391;49910;374191;377906.2209377871;380549;381576;381696;23542.646860296765;23753.762462375154;24059.639105413782;24186.395340116636;26916.788744506935;34067.82966085154;42704;46125.538744612124;46769;48196.7590162047;32033.839830800698;35611.82194089013;35835.82082089677;36656.45015324825;36663.45004824983;35090.298194035466;35337;35450.645493545766;35756.64243357637;35879;35065.123371915906;35549.64450355567;35742;35937.28125437529;36489;30829.845850770602;31383.686163138078;31470.685293146777;31489.21276968094;31560;25386.74613253844;25587.9764809413;25659;25767;26283.342916427242 03:14:41 INFO - PROCESS | 1935 | |2;jquery.html;362.6138162856559;400.6754528831647;406.49797500087385;406.73358949887813;413.86342506972704;14085.962219539944;16701.91649041753;20167.49581260469;20786.792132078674;20806.687899681518;15275.541733747978;20195.495112622193;20727;21200;21236.04437800299;5659.349174844884;5782.653040817543;5966.552508561854;5982.401759824026;5984.700764961756;4205.432266643995;5944.791932282371;5996.550258730591;6004.819855404345;6235.7505699771955;6471.288158302583;6480.189976252968;6488.7728929487475;6488.837779055514;6498.707558159894;6338.746450141989;6395.027417932979;6468.159139311882;6515.381038801306;6564.474842012675;5698.914516282259;5723.055695810186;5740.368559458456;5740.569457290678;5744.3394009689;25393.365165870815;25522.851471683818;25598.616020759702;25598.744012559822;26130.73869261302;2240.115154513958;2255.7067581214337;2317.3279748872806;2876.6404199475064;2889.465448891962;14746.852531474762;14862.777058344134;15083.095014299177;15091.47179848711;15360.846391536053;1591.880608954333;1765.2144795565978;1779.8487128593956;1798.586325145224;1834.6697594432997;101262.97474050478;108272;108634.45682771536;109851.9999999992;110213.44893275562;10804.67585972422;10965.725856853642;11152.776944461228;11425.31448113116;11475.54097836094;12842;13361.599152025456;14466.421343146367;14476.420943162155;14500.129992200502;6865.725370985205;8527.744167674855;8564.571771411405;8600.78498037554;8718.782030449289;8655;8663.436876603;8694.826103477895;8764.298856091498;8774.034856165817;6656.267810540837;6772.52592318533;6782.354881596181;6793.490488213305;6794.966025169842;1905.8189472000138;1960.225710844237;1989.383291179739;1998.1607724755638;2001.3095482058666;918.5728636184207;996.6412091647002;1073.672529878379;1091.328832767842;1133.6429024857143;20465.48836279105;21081.156753729985;21127.894360528408;21215.36353909385;21417.036233369538;968.8934217236099;1009.2934945538153;1042.0985847242082;1050.0497050169608;1116.9106471482423;11152.442377881236;11327.32036077838;11361.715957101138;11406.48670809799;11460.656180314438;248314;248916.75541622177;250285;621194;652883;61329;77061;99280.503597483;99482.50776238363;107385.46307268414;1024.2369434770994;1048.6539441984128;1056.3450660590483;1059.2426415113077;1062.829947208443;18014.91910485375;18191.363302284357;18254.634907302046;18286.31138975956;18333.633327333646;46192.769036154605;46818.29772553482;46822.063558729315;47029.76485117689;48424.51575484341;67405.3259467399;68673;69591;71782.28217717756;71844.64077679787;6858.177018757781;7698.384129269533;7797.259260370256;7930.563818990011;7969.681212751425;12310.507579696712;12437.502499899903;12663.93668031654;12784.169029013085;12942.935285323512;10584.947075264574;10597.735056623338;10922.453877305945;10937.343759374464;10960.506777194885;10677.733056673645;10789.460526973777;10942.835857461985;11172.776544469227;11206.943965280121;10908.127349812155;11216.55133794639;11413.714657133638;11511.30932144074;11565.95906368431;6926.995585640155;7967.123616402307;8043.436959412785;8177.795555110932;8412.03261624921;10905.345679258953;11000.614978475714;11291.322520648788;11303.208775385645;11360.829587556358 03:14:41 INFO - PROCESS | 1935 | |3;mootools.html;1366.6788304748388;1539.607400112971;1563.1324614838768;1772.530279475939;1912.5505506206039;7855.371570274378;8594.269487093597;8753.649854005847;8761.86857197141;8769.824603507934;8431.70489032884;8684.13158684133;8694.478331300126;8695.86956195656;8716.956415217915;2181.9781802182015;2243.8204943604474;2247.741509726386;2469.64190192422;2493.2769496845895;2155.1918030738475;2448.1186772761794;2499.3626625210545;2504.8622325772076;2506.010126000238;2426.0053378114976;2434.513097380522;2464.9260522184272;2469.5801713708624;2471.7775400213977;2167.3389616167065;2435.7077150741934;2444.706635203769;2446.6330050492393;2447.1801946347996;2142.646463333548;2420.140849998243;2479.3181874984525;2484.763947424992;2487.639292302606;8429.536375499345;8583.699570515002;8590.570471476463;8610.44032137909;8621.482711037357;5290.603204759658;5401.459854014606;5402.9729851350885;5419.864503387407;5420.674759514442;3452.1197094740933;3530.75284730069;3614.1868079682126;3615.8192090395373;3642.1076836175043;1043.7964596903607;1068.5672302717412;1070.2454769387564;1086.869575650927;1107.8892110788938;11656.883431165665;11661.242019268806;11677.416129193507;11700;11704.882951170464;4781.402324709411;4795.448523419851;4803.855884323476;4907.607391408679;4912.828051018198;9967.302288839712;9998.850017249748;10013.749656258506;10023.749406264902;10035.448050357301;5454.263674403905;5488.615796894179;5512.283403157644;5526.530244929146;5532.5573954083575;4416.11677664462;4533.455985281787;4959.082569724613;4961.627877909099;5015.673981191211;3994.680425566006;4012.8194231259663;4080.367543030835;4083.7958102094776;4088.509378874495;1124.5839039555253;1152.458344578047;1360.5034162530605;1362.230339857977;1369.459063669845;915.5285028210477;919.1773362840354;949.4730424614268;955.3169483819103;969.4474149734724;5858.794942177004;5902.203202567598;6036.788712395133;6068.544859135583;6082.847928801815;972.9464879431557;982.2387649571639;1001.2690735762934;1010.3786171504472;1021.6577446555339;3463.2034632034774;3869.4776205212497;3945.309570825094;3949.1311911379453;3989.7007724420782;10511.737206569896;10772.676819695422;10875.347479151276;10980.945095274632;11012.063974562092;10581.31221470602;10591.205659575562;10719.035286824223;10809;10814.35113893169;7871.881921771063;8267.917320826835;8329.66681332752;8347.165283471604;8446.113158118309;5803.129530570448;5875.559333049953;5916.349201587908;5987.042073268256;6066.393360663898;9291.535423228675;9398;9512.762180945256;9512.762180945532;9526.618935242514;9275.72172834816;9703.223742100474;9867.802643947225;9873.259505537257;9901.009899010041;6706.497012723968;6840.384365407137;7276.126864776261;7431.145418276751;7537.359324457482;7404.481686281909;7472.252774722701;7901.407394445555;8145.941027666366;8162.755117346489;7123.964380178066;7217.927820721936;7218.386437152691;7296.7081316746735;7359.153697324662;7017.228104908354;7037.225905150532;7106.680199390937;7141.357277845019;7146.32109949554;6581.967090164519;6839.316068393121;6912.965435172792;6939.4448444125355;6941.062956500808;4823.03539292136;4863.319135320987;4902.656814022984;4994.925076123788;5015.623828212826;4381.18947994622;4955.727434990966;5013.147764879906;5021.246812978083;5080.949190508048 03:14:41 INFO - PROCESS | 1935 | |4;prototype.html;301.23336109601064;327.33224222585926;331.6318886036499;337.2647628170588;342.95911907361165;6121.081837724343;6740;6747.79756607302;6793.6603169841555;6834.726610935557;6719.294474080212;6728.461723062155;6738.42723368513;6744.258131605532;6757.831054223647;4722.740249286297;4801.5438533339275;4815.181419158752;4897.7755561109725;4938.456769755324;4870.951290487085;4913.680610760289;4917.803287868481;4945.876353091184;4954.157793175169;4874.780634871439;4885.5358740919555;4889.462159162489;4894.926576101361;4895.21676531755;4710.246360582308;4741.525847415248;4848.200046992242;4856.635752318555;4869.366982292279;4156.729812562204;4182.184474027566;4182.2890108681595;4184.330507118846;4186.0581369192;5898.498627616657;6017.428344307284;6510.576812507172;6540.607563546202;6542.247641521239;5858.033424484955;5860.765569377219;5865.882682346371;5868.178455016301;5875.6474611523445;6760.1549806274215;7153.60655163971;7166.964165179141;7169.498135130543;7187.676554555058;4600.585947264729;4612.308153776927;4613.653975951784;4632.675712700112;4652.534746525321;7436.814079647998;7534.962325188339;7547.547147171187;7555.546667199986;7556.395488360918;4677.970846413784;4683.250679891201;4701.200795864711;4712.929306060412;4720.834770783006;4750.978539614;4764.6664733469015;4768.332433459318;4777.856664300007;4779.641526885497;5475.917861232085;5712.314522257356;5721.599488035798;5725.541956643458;5744.138379243063;7155.141383033965;7164.570125792469;7193.712251509985;7194.316539928698;7201.747938822116;1610.1466222901881;1655.1806855606312;1664.2261348473019;1665.2423147467873;1667.7831881855525;979.647326962293;990.6631745206664;1024.3956065921143;1034.9379037257788;1059.586761163147;6604.1084453599115;6901.758438454731;6914.239433662293;6928.584284942819;6929.44564434844;7461.626918654046;7468.290512401313;7471.252874712485;7480.513766605153;7509.549427034395;5522.696251706195;6686.866262674719;6697.129373181455;6708.765193218312;6736.494762892803;3792.60177681345;3910.2179564087296;3970.2059588082498;3970.7617542947514;3972.503437070366;7408.148062972719;7788.182240864741;7791.922080779233;7820.452568320163;7824.608769561499;6568.573042752206;7473.813154671177;7717.575533345607;7735.7679269622;7736.071671399468;7785.961070194613;7788.8052798679355;7797.493162944391;7799.45403821727;7830.256125668053;7162.391196748335;7420.146683131509;7446.888296675663;7472.402207823252;7480.438967077382;7320.377767889789;7326.9267307326245;7341.045664063638;7365.0057242271605;7373.889391659237;7766.844663106819;7780.533168009937;7791;7799.713047347124;7809.843803123792;4699.295105734167;4712.316714076508;4715.24556071027;4719.433667959866;4719.433667959866;7169.7849064528145;7341.229170936975;7437.665305061393;7450.8882366763455;7469.402447804054;7163.140423149047;7203.567785932861;7211.242819504087;7218.711251549879;7234.457415693949;7206.2793720627515;7210.747623833141;7235.059442272471;7237.240089790496;7243;5850.356460789395;7025.929740702528;7037.296270372922;7039;7060.71757129709;6872.140982377203;7187.640617969185;7195.316444937723;7224.566526008456;7227.927720722936;6741.797746067626;6864.622445765531;6925.792226233221;7009.509334346751;7077.929220707727 03:14:41 INFO - PROCESS | 1935 | |5;yui.html;705.1855107351009;746.734909107267;760.7565579014715;769.6613490064371;774.930256276935;14854.25728713564;15118.319675614597;15120.16839073849;15120.243987800593;15149.242537873104;13821.51624693136;13879.375428105734;13904.860951390483;13913.930430347835;13918.791218131712;736.7568702328219;753.6081237756364;893.5576889439736;913.9451632902014;916.221211969827;819.1971867569786;870.6778491957984;913.1872633356318;919.5678031325266;921.9953900230523;939.9436033837958;947.1664934857316;957.2533423929347;957.5499515227866;960.3949511807551;773.0645918438696;949.7578117579973;951.8905325887542;951.9476428796413;953.5375342958729;721.6355740351141;722.8301349182938;724.0478770416885;724.478375569589;725.8258018075624;14205.573832785032;14213.78679319811;14231.288435578179;14236.074655147382;14246.145231286157;12021;12251.57119500822;12463.688407789787;12745.553905613258;13085.214887106804;9862.556184971621;9914.355566888202;9946.602135914554;10188.796224075477;10626.043656070913;3146.3392687535643;3481.9651803482147;3488.023353461035;3511.525943997553;3552.3960926642535;70927.22681932941;71354.28645713528;72233;72783;73006.80979570621;8929.151730585583;9228.8615670765;9256.583453744597;9503.66737164196;9522.00018998009;20015.399538013884;20069.59860802805;20125.194992200148;20138.093785779674;20175.697364539545;7295.270472952662;7391;7409.703611855573;7522.5862577558755;7742.10965738936;3740.7194460415576;3819.1406933439753;3849.615038496184;3854.9229015419533;3974.3044967131214;3605.080704420356;3676.963230367662;3684.907877303035;3685.152414944524;3688.22547265072;611.6452457574596;615.8029430582171;620.6679426506887;622.00168729189;639.6002498438476;519.8284566093256;554.9805756798493;594.9077892926604;611.9418655227746;617.9505639548826;7713.382929365637;8088.757337279891;8098.473599216032;8136.75589732309;8177.877331840027;584.433099893103;587.2307277466447;589.1839801874349;620.3424370167637;631.6715308039885;3177.269228077509;3793.506844110248;3824.7322687411615;3840.183102796265;3885.2423777363706;92851;92856.53571732098;93391;93961.0603893966;95762.52118739502;70114.64942675254;72390.63804680943;73154;73309.26690733132;74518;68976.6551167241;69694.3030569698;70736;71682.64158679174;71970.2802971974;7031.7890463286185;7489.6893043716045;7594.164641889277;7765.650545725569;7826.647800849089;45286.77356613196;48970.755146224044;50418.991620168126;50523;50896.49103508917;48693;63663.363366367594;67871.66064169648;68907.31092689009;69373.95939060817;10684.465776710978;11041.723956901142;11090.5563777448;11185.272957257752;11265.914481061278;11431.771364572829;12157.75684486323;12330;12403.25580465175;12564.811527827273;11251.943740281245;11332.6033588824;11427.885721142682;11791.587294444653;12108.273503589813;11097.833532497181;11269.774604508028;11399.886001140214;11473.311601303949;11995.76008479843;10896.692466588749;10906.563737450731;11108.333499990027;11345.829812552653;12191.75616487683;9049.136074473123;9226.769330766785;9473.194778443909;9485.33602647808;9558.808823823623;8345.248927596545;8456.2812160967;8512.233898949124;8612.956935215283;8735.606897689746 03:14:41 INFO - PROCESS | 1935 | __end_tp_report 03:14:41 INFO - PROCESS | 1935 | __start_cc_report 03:14:41 INFO - PROCESS | 1935 | _x_x_mozilla_cycle_collect,1995 03:14:41 INFO - PROCESS | 1935 | __end_cc_report 03:14:41 INFO - PROCESS | 1935 | __startTimestamp1462961681303__endTimestamp 03:14:41 INFO - PROCESS | 1935 | 03:14:41 INFO - PROCESS | 1935 | ------- Summary: start ------- 03:14:41 INFO - PROCESS | 1935 | Number of tests: 6 03:14:41 INFO - PROCESS | 1935 | 03:14:41 INFO - PROCESS | 1935 | [#0] dojo.html Cycles:165 Average:5382.09 Median:5526.56 stddev:567.07 (10.3%) stddev-sans-first:543.36 03:14:41 INFO - PROCESS | 1935 | Values: 3241.0 3308.3 3322.2 3325.6 3380.6 5356.7 5371.1 5372.6 5376.7 5382.7 5354.1 5374.7 5376.9 5396.1 5397.9 4772.4 4874.1 4904.9 4936.7 4993.2 4851.1 4965.5 4969.9 4970.5 4984.3 4852.3 4938.0 4938.0 4944.2 4944.5 4924.5 4950.9 4985.3 5013.8 5020.6 4782.5 4814.8 4871.2 4920.9 4984.5 5237.1 5288.6 5300.0 5304.3 5310.3 5741.4 5825.3 5834.2 5835.1 5836.8 5671.5 5809.6 5821.9 5872.3 5877.5 5431.8 5456.7 5461.5 5476.1 5479.9 6015.1 6015.4 6017.6 6021.7 6031.9 5523.1 5624.6 5705.4 5716.7 5723.1 5751.7 5860.8 5876.9 5880.6 5884.3 5721.0 5721.1 5732.2 5734.6 5736.0 5719.9 5740.9 5752.6 5754.3 5781.9 4585.6 4627.5 4687.7 4715.7 4747.1 4203.6 4254.9 4267.3 4270.6 4295.6 5225.6 5390.8 5414.4 5415.0 5416.4 4981.3 5700.9 5773.0 5794.8 5880.0 5351.0 5361.9 5370.1 5380.1 5384.2 4267.9 5127.6 5135.2 5148.6 5189.0 5942.9 6018.9 6079.2 6115.3 6127.7 5836.0 5882.3 5899.7 5902.1 5913.2 5927.9 6113.0 6120.9 6134.8 6157.4 5589.7 5637.6 5646.9 5648.4 5651.7 5599.0 5650.6 5715.3 5728.0 5732.6 5547.7 5583.1 5586.8 5601.3 5702.2 5618.6 5638.3 5638.5 5645.1 5657.8 5500.4 5521.0 5526.6 5531.2 5542.8 5493.0 5557.4 5629.7 5686.7 5743.9 5464.0 5493.6 5536.4 5548.3 5555.1 03:14:41 INFO - PROCESS | 1935 | 03:14:41 INFO - PROCESS | 1935 | [#1] ext.html Cycles:180 Average:63159.76 Median:3439.86 stddev:138607.71 (4029.5%) stddev-sans-first:138916.80 03:14:41 INFO - PROCESS | 1935 | Values: 537.2 540.1 557.9 590.9 601.9 30606.8 31337.7 31422.2 38040.4 38803.0 41754.4 41979.0 42153.2 42171.6 42219.6 9478.3 9694.2 9698.7 9701.0 9717.0 8637.7 8840.5 9469.9 11580.4 11822.5 11592.2 11621.7 11632.4 11641.9 11650.9 8622.7 11364.4 11450.1 11469.5 11471.2 10084.6 11527.3 11733.6 11737.8 11738.8 37922.8 38356.0 38444.0 38479.0 38493.2 3259.4 3442.2 3672.8 3813.4 3840.6 27620.0 27637.7 27640.0 28397.0 28809.0 2775.3 2784.5 2827.6 2892.8 2951.8 500180.0 503274.0 503751.0 504430.0 504668.0 36283.8 62447.0 63623.7 63680.4 63838.0 35563.9 35746.6 35769.3 35774.6 35826.3 15283.2 15468.8 15469.8 15470.7 15472.5 12210.3 12357.7 13073.8 13155.4 13157.9 8174.5 8263.5 8297.5 8346.6 8357.3 3075.2 3314.9 3399.7 3437.5 3461.9 1001.2 1035.7 1056.8 1062.5 1100.3 24398.9 29193.4 29624.4 30070.2 30815.8 1135.4 1157.4 1167.4 1184.5 1186.5 12671.2 13207.3 15450.6 15569.7 16244.3 588084.0 632550.0 651915.0 656886.0 659331.0 51189.5 60612.4 61481.4 61636.7 61781.4 1268.9 1373.9 1382.6 1452.7 1511.0 28760.1 28909.4 29145.4 29308.7 29338.6 49345.0 49433.3 49763.8 49836.3 49910.0 374191.0 377906.2 380549.0 381576.0 381696.0 23542.6 23753.8 24059.6 24186.4 26916.8 34067.8 42704.0 46125.5 46769.0 48196.8 32033.8 35611.8 35835.8 36656.5 36663.5 35090.3 35337.0 35450.6 35756.6 35879.0 35065.1 35549.6 35742.0 35937.3 36489.0 30829.8 31383.7 31470.7 31489.2 31560.0 25386.7 25588.0 25659.0 25767.0 26283.3 03:14:41 INFO - PROCESS | 1935 | 03:14:41 INFO - PROCESS | 1935 | [#2] jquery.html Cycles:180 Average:28015.97 Median:21225.70 stddev:75177.78 (354.2%) stddev-sans-first:75360.00 03:14:41 INFO - PROCESS | 1935 | Values: 362.6 400.7 406.5 406.7 413.9 14086.0 16701.9 20167.5 20786.8 20806.7 15275.5 20195.5 20727.0 21200.0 21236.0 5659.3 5782.7 5966.6 5982.4 5984.7 4205.4 5944.8 5996.6 6004.8 6235.8 6471.3 6480.2 6488.8 6488.8 6498.7 6338.7 6395.0 6468.2 6515.4 6564.5 5698.9 5723.1 5740.4 5740.6 5744.3 25393.4 25522.9 25598.6 25598.7 26130.7 2240.1 2255.7 2317.3 2876.6 2889.5 14746.9 14862.8 15083.1 15091.5 15360.8 1591.9 1765.2 1779.8 1798.6 1834.7 101263.0 108272.0 108634.5 109852.0 110213.4 10804.7 10965.7 11152.8 11425.3 11475.5 12842.0 13361.6 14466.4 14476.4 14500.1 6865.7 8527.7 8564.6 8600.8 8718.8 8655.0 8663.4 8694.8 8764.3 8774.0 6656.3 6772.5 6782.4 6793.5 6795.0 1905.8 1960.2 1989.4 1998.2 2001.3 918.6 996.6 1073.7 1091.3 1133.6 20465.5 21081.2 21127.9 21215.4 21417.0 968.9 1009.3 1042.1 1050.0 1116.9 11152.4 11327.3 11361.7 11406.5 11460.7 248314.0 248916.8 250285.0 621194.0 652883.0 61329.0 77061.0 99280.5 99482.5 107385.5 1024.2 1048.7 1056.3 1059.2 1062.8 18014.9 18191.4 18254.6 18286.3 18333.6 46192.8 46818.3 46822.1 47029.8 48424.5 67405.3 68673.0 69591.0 71782.3 71844.6 6858.2 7698.4 7797.3 7930.6 7969.7 12310.5 12437.5 12663.9 12784.2 12942.9 10584.9 10597.7 10922.5 10937.3 10960.5 10677.7 10789.5 10942.8 11172.8 11206.9 10908.1 11216.6 11413.7 11511.3 11566.0 6927.0 7967.1 8043.4 8177.8 8412.0 10905.3 11000.6 11291.3 11303.2 11360.8 03:14:41 INFO - PROCESS | 1935 | 03:14:41 INFO - PROCESS | 1935 | [#3] mootools.html Cycles:180 Average:5671.52 Median:5004.04 stddev:3131.55 (62.6%) stddev-sans-first:3123.62 03:14:41 INFO - PROCESS | 1935 | Values: 1366.7 1539.6 1563.1 1772.5 1912.6 7855.4 8594.3 8753.6 8761.9 8769.8 8431.7 8684.1 8694.5 8695.9 8717.0 2182.0 2243.8 2247.7 2469.6 2493.3 2155.2 2448.1 2499.4 2504.9 2506.0 2426.0 2434.5 2464.9 2469.6 2471.8 2167.3 2435.7 2444.7 2446.6 2447.2 2142.6 2420.1 2479.3 2484.8 2487.6 8429.5 8583.7 8590.6 8610.4 8621.5 5290.6 5401.5 5403.0 5419.9 5420.7 3452.1 3530.8 3614.2 3615.8 3642.1 1043.8 1068.6 1070.2 1086.9 1107.9 11656.9 11661.2 11677.4 11700.0 11704.9 4781.4 4795.4 4803.9 4907.6 4912.8 9967.3 9998.9 10013.7 10023.7 10035.4 5454.3 5488.6 5512.3 5526.5 5532.6 4416.1 4533.5 4959.1 4961.6 5015.7 3994.7 4012.8 4080.4 4083.8 4088.5 1124.6 1152.5 1360.5 1362.2 1369.5 915.5 919.2 949.5 955.3 969.4 5858.8 5902.2 6036.8 6068.5 6082.8 972.9 982.2 1001.3 1010.4 1021.7 3463.2 3869.5 3945.3 3949.1 3989.7 10511.7 10772.7 10875.3 10980.9 11012.1 10581.3 10591.2 10719.0 10809.0 10814.4 7871.9 8267.9 8329.7 8347.2 8446.1 5803.1 5875.6 5916.3 5987.0 6066.4 9291.5 9398.0 9512.8 9512.8 9526.6 9275.7 9703.2 9867.8 9873.3 9901.0 6706.5 6840.4 7276.1 7431.1 7537.4 7404.5 7472.3 7901.4 8145.9 8162.8 7124.0 7217.9 7218.4 7296.7 7359.2 7017.2 7037.2 7106.7 7141.4 7146.3 6582.0 6839.3 6913.0 6939.4 6941.1 4823.0 4863.3 4902.7 4994.9 5015.6 4381.2 4955.7 5013.1 5021.2 5080.9 03:14:41 INFO - PROCESS | 1935 | 03:14:41 INFO - PROCESS | 1935 | [#4] prototype.html Cycles:180 Average:5871.11 Median:6740.90 stddev:1890.81 (28.0%) stddev-sans-first:1849.32 03:14:41 INFO - PROCESS | 1935 | Values: 301.2 327.3 331.6 337.3 343.0 6121.1 6740.0 6747.8 6793.7 6834.7 6719.3 6728.5 6738.4 6744.3 6757.8 4722.7 4801.5 4815.2 4897.8 4938.5 4871.0 4913.7 4917.8 4945.9 4954.2 4874.8 4885.5 4889.5 4894.9 4895.2 4710.2 4741.5 4848.2 4856.6 4869.4 4156.7 4182.2 4182.3 4184.3 4186.1 5898.5 6017.4 6510.6 6540.6 6542.2 5858.0 5860.8 5865.9 5868.2 5875.6 6760.2 7153.6 7167.0 7169.5 7187.7 4600.6 4612.3 4613.7 4632.7 4652.5 7436.8 7535.0 7547.5 7555.5 7556.4 4678.0 4683.3 4701.2 4712.9 4720.8 4751.0 4764.7 4768.3 4777.9 4779.6 5475.9 5712.3 5721.6 5725.5 5744.1 7155.1 7164.6 7193.7 7194.3 7201.7 1610.1 1655.2 1664.2 1665.2 1667.8 979.6 990.7 1024.4 1034.9 1059.6 6604.1 6901.8 6914.2 6928.6 6929.4 7461.6 7468.3 7471.3 7480.5 7509.5 5522.7 6686.9 6697.1 6708.8 6736.5 3792.6 3910.2 3970.2 3970.8 3972.5 7408.1 7788.2 7791.9 7820.5 7824.6 6568.6 7473.8 7717.6 7735.8 7736.1 7786.0 7788.8 7797.5 7799.5 7830.3 7162.4 7420.1 7446.9 7472.4 7480.4 7320.4 7326.9 7341.0 7365.0 7373.9 7766.8 7780.5 7791.0 7799.7 7809.8 4699.3 4712.3 4715.2 4719.4 4719.4 7169.8 7341.2 7437.7 7450.9 7469.4 7163.1 7203.6 7211.2 7218.7 7234.5 7206.3 7210.7 7235.1 7237.2 7243.0 5850.4 7025.9 7037.3 7039.0 7060.7 6872.1 7187.6 7195.3 7224.6 7227.9 6741.8 6864.6 6925.8 7009.5 7077.9 03:14:41 INFO - PROCESS | 1935 | 03:14:41 INFO - PROCESS | 1935 | [#5] yui.html Cycles:180 Average:17702.52 Median:626.84 stddev:24765.50 (3950.9%) stddev-sans-first:24802.08 03:14:41 INFO - PROCESS | 1935 | Values: 705.2 746.7 760.8 769.7 774.9 14854.3 15118.3 15120.2 15120.2 15149.2 13821.5 13879.4 13904.9 13913.9 13918.8 736.8 753.6 893.6 913.9 916.2 819.2 870.7 913.2 919.6 922.0 939.9 947.2 957.3 957.5 960.4 773.1 949.8 951.9 951.9 953.5 721.6 722.8 724.0 724.5 725.8 14205.6 14213.8 14231.3 14236.1 14246.1 12021.0 12251.6 12463.7 12745.6 13085.2 9862.6 9914.4 9946.6 10188.8 10626.0 3146.3 3482.0 3488.0 3511.5 3552.4 70927.2 71354.3 72233.0 72783.0 73006.8 8929.2 9228.9 9256.6 9503.7 9522.0 20015.4 20069.6 20125.2 20138.1 20175.7 7295.3 7391.0 7409.7 7522.6 7742.1 3740.7 3819.1 3849.6 3854.9 3974.3 3605.1 3677.0 3684.9 3685.2 3688.2 611.6 615.8 620.7 622.0 639.6 519.8 555.0 594.9 611.9 618.0 7713.4 8088.8 8098.5 8136.8 8177.9 584.4 587.2 589.2 620.3 631.7 3177.3 3793.5 3824.7 3840.2 3885.2 92851.0 92856.5 93391.0 93961.1 95762.5 70114.6 72390.6 73154.0 73309.3 74518.0 68976.7 69694.3 70736.0 71682.6 71970.3 7031.8 7489.7 7594.2 7765.7 7826.6 45286.8 48970.8 50419.0 50523.0 50896.5 48693.0 63663.4 67871.7 68907.3 69374.0 10684.5 11041.7 11090.6 11185.3 11265.9 11431.8 12157.8 12330.0 12403.3 12564.8 11251.9 11332.6 11427.9 11791.6 12108.3 11097.8 11269.8 11399.9 11473.3 11995.8 10896.7 10906.6 11108.3 11345.8 12191.8 9049.1 9226.8 9473.2 9485.3 9558.8 8345.2 8456.3 8512.2 8613.0 8735.6 03:14:41 INFO - PROCESS | 1935 | -------- Summary: end -------- 03:14:41 INFO - PROCESS | 1935 | 03:14:41 INFO - TEST-INFO | 1935: exit 0 03:14:41 INFO - TEST-OK | dromaeo_css | took 1115309ms 03:14:41 INFO - TEST-START | kraken 03:14:41 INFO - operating with platform_type : mac_ 03:14:41 INFO - Initialising browser for kraken test... 03:14:41 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/tmp0Rm4rp/profile http://localhost:52325/getInfo.html) 03:14:43 INFO - PROCESS | 1939 | __metrics Screen width/height:1600/1200 03:14:43 INFO - PROCESS | 1939 | colorDepth:24 03:14:43 INFO - PROCESS | 1939 | Browser inner width/height: 1024/689 03:14:43 INFO - PROCESS | 1939 | __metrics 03:14:44 INFO - PROCESS | 1939 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:14:44 ERROR - PROCESS | 1939 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:14:44 INFO - TEST-INFO | 1939: exit 0 03:14:44 INFO - Browser initialized. 03:14:44 INFO - Running cycle 1/1 for kraken test... 03:14:44 INFO - TEST-INFO | started process 1942 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/tmp0Rm4rp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:14:56 INFO - PROCESS | 1942 | RSS: Main: 211120128 03:14:56 INFO - PROCESS | 1942 | 03:14:59 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:52325/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52325/tests/kraken/driver.html?testName=audio-beat-detection) 03:15:00 INFO - PROCESS | 1942 | RSS: Main: 271745024 03:15:00 INFO - PROCESS | 1942 | 03:15:02 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:52325/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52325/tests/kraken/driver.html?testName=audio-dft) 03:15:03 INFO - PROCESS | 1942 | RSS: Main: 286257152 03:15:03 INFO - PROCESS | 1942 | 03:15:06 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:52325/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52325/tests/kraken/driver.html?testName=audio-fft) 03:15:07 INFO - PROCESS | 1942 | RSS: Main: 373813248 03:15:07 INFO - PROCESS | 1942 | 03:15:09 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:52325/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52325/tests/kraken/driver.html?testName=audio-oscillator) 03:15:09 INFO - PROCESS | 1942 | RSS: Main: 278986752 03:15:09 INFO - PROCESS | 1942 | 03:15:12 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:52325/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52325/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:15:12 INFO - PROCESS | 1942 | RSS: Main: 280317952 03:15:12 INFO - PROCESS | 1942 | 03:15:15 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:52325/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52325/tests/kraken/driver.html?testName=imaging-darkroom) 03:15:15 INFO - PROCESS | 1942 | RSS: Main: 318140416 03:15:15 INFO - PROCESS | 1942 | 03:15:18 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:52325/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52325/tests/kraken/driver.html?testName=imaging-desaturate) 03:15:19 INFO - PROCESS | 1942 | RSS: Main: 318427136 03:15:19 INFO - PROCESS | 1942 | 03:15:22 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:52325/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52325/tests/kraken/driver.html?testName=json-parse-financial) 03:15:22 INFO - PROCESS | 1942 | RSS: Main: 317599744 03:15:22 INFO - PROCESS | 1942 | 03:15:24 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:52325/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52325/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:15:24 INFO - PROCESS | 1942 | RSS: Main: 309211136 03:15:24 INFO - PROCESS | 1942 | 03:15:26 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:52325/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52325/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:15:27 INFO - PROCESS | 1942 | RSS: Main: 315461632 03:15:27 INFO - PROCESS | 1942 | 03:15:29 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:52325/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52325/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:15:30 INFO - PROCESS | 1942 | RSS: Main: 319057920 03:15:30 INFO - PROCESS | 1942 | 03:15:33 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:52325/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52325/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:15:33 INFO - PROCESS | 1942 | RSS: Main: 320704512 03:15:33 INFO - PROCESS | 1942 | 03:15:36 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:52325/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52325/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:15:37 INFO - PROCESS | 1942 | RSS: Main: 317546496 03:15:37 INFO - PROCESS | 1942 | 03:15:39 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:52325/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52325/tests/kraken/driver.html?testName=ai-astar) 03:15:39 INFO - PROCESS | 1942 | RSS: Main: 381784064 03:15:39 INFO - PROCESS | 1942 | 03:15:39 INFO - PROCESS | 1942 | __start_tp_report 03:15:39 INFO - PROCESS | 1942 | _x_x_mozilla_page_load 03:15:39 INFO - PROCESS | 1942 | _x_x_mozilla_page_load_details 03:15:39 INFO - PROCESS | 1942 | |i|pagename|runs| 03:15:39 INFO - PROCESS | 1942 | |0;ai-astar;113;114;117;115;111;114;113;114;114;134 03:15:39 INFO - PROCESS | 1942 | |1;audio-beat-detection;159;107;164;189;143;155;116;152;157;116 03:15:39 INFO - PROCESS | 1942 | |2;audio-dft;184;185;186;184;187;183;185;186;186;185 03:15:39 INFO - PROCESS | 1942 | |3;audio-fft;69;79;77;154;56;55;58;63;133;54 03:15:39 INFO - PROCESS | 1942 | |4;audio-oscillator;90;89;144;75;86;83;128;79;77;126 03:15:39 INFO - PROCESS | 1942 | |5;imaging-gaussian-blur;86;85;86;84;86;83;87;84;87;84 03:15:39 INFO - PROCESS | 1942 | |6;imaging-darkroom;92;92;92;93;91;92;93;92;92;92 03:15:39 INFO - PROCESS | 1942 | |7;imaging-desaturate;85;85;84;85;86;84;83;85;84;83 03:15:39 INFO - PROCESS | 1942 | |8;json-parse-financial;61;65;64;65;63;65;68;84;60;60 03:15:39 INFO - PROCESS | 1942 | |9;json-stringify-tinderbox;64;65;71;109;109;104;67;67;75;69 03:15:39 INFO - PROCESS | 1942 | |10;stanford-crypto-aes;89;83;103;79;80;81;80;81;80;86 03:15:39 INFO - PROCESS | 1942 | |11;stanford-crypto-ccm;144;138;146;124;125;130;113;141;181;162 03:15:39 INFO - PROCESS | 1942 | |12;stanford-crypto-pbkdf2;167;162;197;169;153;200;218;179;165;169 03:15:39 INFO - PROCESS | 1942 | |13;stanford-crypto-sha256-iterative;68;69;66;67;67;66;66;69;68;66 03:15:39 INFO - PROCESS | 1942 | __end_tp_report 03:15:39 INFO - PROCESS | 1942 | __start_cc_report 03:15:39 INFO - PROCESS | 1942 | _x_x_mozilla_cycle_collect,2007 03:15:39 INFO - PROCESS | 1942 | __end_cc_report 03:15:39 INFO - PROCESS | 1942 | __startTimestamp1462961739507__endTimestamp 03:15:39 INFO - PROCESS | 1942 | 03:15:39 INFO - PROCESS | 1942 | ------- Summary: start ------- 03:15:39 INFO - PROCESS | 1942 | Number of tests: 14 03:15:39 INFO - PROCESS | 1942 | 03:15:39 INFO - PROCESS | 1942 | [#0] ai-astar Cycles:10 Average:115.90 Median:114.00 stddev:6.54 (5.7%) stddev-sans-first:6.85 03:15:39 INFO - PROCESS | 1942 | Values: 113.0 114.0 117.0 115.0 111.0 114.0 113.0 114.0 114.0 134.0 03:15:39 INFO - PROCESS | 1942 | 03:15:39 INFO - PROCESS | 1942 | [#1] audio-beat-detection Cycles:10 Average:145.80 Median:156.00 stddev:25.62 (16.4%) stddev-sans-first:26.73 03:15:39 INFO - PROCESS | 1942 | Values: 159.0 107.0 164.0 189.0 143.0 155.0 116.0 152.0 157.0 116.0 03:15:39 INFO - PROCESS | 1942 | 03:15:39 INFO - PROCESS | 1942 | [#2] audio-dft Cycles:10 Average:185.10 Median:185.50 stddev:1.20 (0.6%) stddev-sans-first:1.20 03:15:39 INFO - PROCESS | 1942 | Values: 184.0 185.0 186.0 184.0 187.0 183.0 185.0 186.0 186.0 185.0 03:15:39 INFO - PROCESS | 1942 | 03:15:39 INFO - PROCESS | 1942 | [#3] audio-fft Cycles:10 Average:79.80 Median:60.50 stddev:35.06 (58.0%) stddev-sans-first:36.97 03:15:39 INFO - PROCESS | 1942 | Values: 69.0 79.0 77.0 154.0 56.0 55.0 58.0 63.0 133.0 54.0 03:15:39 INFO - PROCESS | 1942 | 03:15:39 INFO - PROCESS | 1942 | [#4] audio-oscillator Cycles:10 Average:97.70 Median:81.00 stddev:25.04 (30.9%) stddev-sans-first:26.41 03:15:39 INFO - PROCESS | 1942 | Values: 90.0 89.0 144.0 75.0 86.0 83.0 128.0 79.0 77.0 126.0 03:15:39 INFO - PROCESS | 1942 | 03:15:39 INFO - PROCESS | 1942 | [#5] imaging-gaussian-blur Cycles:10 Average:85.20 Median:86.00 stddev:1.40 (1.6%) stddev-sans-first:1.45 03:15:39 INFO - PROCESS | 1942 | Values: 86.0 85.0 86.0 84.0 86.0 83.0 87.0 84.0 87.0 84.0 03:15:39 INFO - PROCESS | 1942 | 03:15:39 INFO - PROCESS | 1942 | [#6] imaging-darkroom Cycles:10 Average:92.10 Median:92.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 03:15:39 INFO - PROCESS | 1942 | Values: 92.0 92.0 92.0 93.0 91.0 92.0 93.0 92.0 92.0 92.0 03:15:39 INFO - PROCESS | 1942 | 03:15:39 INFO - PROCESS | 1942 | [#7] imaging-desaturate Cycles:10 Average:84.40 Median:85.00 stddev:0.97 (1.1%) stddev-sans-first:1.00 03:15:39 INFO - PROCESS | 1942 | Values: 85.0 85.0 84.0 85.0 86.0 84.0 83.0 85.0 84.0 83.0 03:15:39 INFO - PROCESS | 1942 | 03:15:39 INFO - PROCESS | 1942 | [#8] json-parse-financial Cycles:10 Average:65.50 Median:65.00 stddev:6.98 (10.7%) stddev-sans-first:7.21 03:15:39 INFO - PROCESS | 1942 | Values: 61.0 65.0 64.0 65.0 63.0 65.0 68.0 84.0 60.0 60.0 03:15:39 INFO - PROCESS | 1942 | 03:15:39 INFO - PROCESS | 1942 | [#9] json-stringify-tinderbox Cycles:10 Average:80.00 Median:67.00 stddev:19.16 (28.6%) stddev-sans-first:19.43 03:15:39 INFO - PROCESS | 1942 | Values: 64.0 65.0 71.0 109.0 109.0 104.0 67.0 67.0 75.0 69.0 03:15:39 INFO - PROCESS | 1942 | 03:15:39 INFO - PROCESS | 1942 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:81.00 stddev:7.32 (9.0%) stddev-sans-first:7.55 03:15:39 INFO - PROCESS | 1942 | Values: 89.0 83.0 103.0 79.0 80.0 81.0 80.0 81.0 80.0 86.0 03:15:39 INFO - PROCESS | 1942 | 03:15:39 INFO - PROCESS | 1942 | [#11] stanford-crypto-ccm Cycles:10 Average:140.40 Median:142.50 stddev:19.81 (13.9%) stddev-sans-first:20.96 03:15:39 INFO - PROCESS | 1942 | Values: 144.0 138.0 146.0 124.0 125.0 130.0 113.0 141.0 181.0 162.0 03:15:39 INFO - PROCESS | 1942 | 03:15:39 INFO - PROCESS | 1942 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.90 Median:174.00 stddev:20.49 (11.8%) stddev-sans-first:21.35 03:15:39 INFO - PROCESS | 1942 | Values: 167.0 162.0 197.0 169.0 153.0 200.0 218.0 179.0 165.0 169.0 03:15:39 INFO - PROCESS | 1942 | 03:15:39 INFO - PROCESS | 1942 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.50 stddev:1.23 (1.8%) stddev-sans-first:1.27 03:15:39 INFO - PROCESS | 1942 | Values: 68.0 69.0 66.0 67.0 67.0 66.0 66.0 69.0 68.0 66.0 03:15:39 INFO - PROCESS | 1942 | -------- Summary: end -------- 03:15:39 INFO - PROCESS | 1942 | 03:15:40 INFO - TEST-INFO | 1942: exit 0 03:15:40 INFO - TEST-OK | kraken | took 58454ms 03:15:40 INFO - SUITE-END | took 1173s 03:15:40 INFO - Completed test suite (00:19:34) 03:15:40 INFO - javascript benchmark 03:15:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3241.0438920518454, 3308.30525589626, 3322.186064414219, 3325.634180240175, 3380.560527131474, 5356.7321633918245, 5371.060064488714, 5372.57019438445, 5376.731163441822, 5382.703951282678, 5354.116570765819, 5374.7043912584795, 5376.946230537703, 5396.082665946788, 5397.946020539794, 4772.3795906532105, 4874.147024270747, 4904.926426103611, 4936.679115857458, 4993.246307898586, 4851.102546028996, 4965.453800081988, 4969.850904472872, 4970.453250142481, 4984.3021976923255, 4852.29641701953, 4937.975310123445, 4937.975310123445, 4944.159492886218, 4944.480829512903, 4924.532169443915, 4950.9009819803405, 4985.277134815467, 5013.849584512471, 5020.623453241022, 4782.473927867932, 4814.7833347499445, 4871.196252618313, 4920.852374428773, 4984.501549845023, 5237.083510385667, 5288.603354748409, 5300.0459917214885, 5304.257403963448, 5310.309659744247, 5741.425857414225, 5825.271841019872, 5834.212381328552, 5835.066389377722, 5836.766529338822, 5671.461211184972, 5809.622374545641, 5821.912671309934, 5872.324682661463, 5877.500412464946, 5431.8098866539485, 5456.672599644034, 5461.481159289861, 5476.096444086721, 5479.863003424906, 6015.097735339734, 6015.428534289235, 6017.578769486182, 6021.6989150542295, 6031.8793624127275, 5523.143912693539, 5624.55003599711, 5705.429457054262, 5716.742746576414, 5723.055695810144, 5751.654900705971, 5860.794872179538, 5876.853078672982, 5880.588358814842, 5884.323302820145, 5720.971395142998, 5721.141828725725, 5732.197492351074, 5734.569907256972, 5735.967525845388, 5719.914201286984, 5740.885182296414, 5752.568557358215, 5754.251947246831, 5781.942180578225, 4585.633149348044, 4627.514111018362, 4687.695299805502, 4715.740634265148, 4747.145513807548, 4203.579642035772, 4254.893627659333, 4267.317229243306, 4270.572942705767, 4295.570442955679, 5225.581953443715, 5390.838274851761, 5414.40441551429, 5414.972925135428, 5416.431274716177, 4981.277714731344, 5700.914486282709, 5773.018586840247, 5794.797182098607, 5879.970600147058, 5351.0, 5361.946380536223, 5370.113931201308, 5380.112281473591, 5384.19237114436, 4267.935980960288, 5127.564157046618, 5135.15269980449, 5148.562372198405, 5189.040027594907, 5942.910856337158, 6018.909716354258, 6079.1793107931635, 6115.266168059861, 6127.724252408741, 5836.037053886062, 5882.294124705063, 5899.705014749331, 5902.085176797603, 5913.172155898092, 5927.851803704942, 6112.969435152796, 6120.8775824484155, 6134.754609815736, 6157.384261573807, 5589.692566908859, 5637.605367624391, 5646.85882852932, 5648.406917273627, 5651.745671444713, 5598.972005140111, 5650.604457687922, 5715.31416230055, 5727.971360143172, 5732.627379220337, 5547.667139971614, 5583.134614134816, 5586.832395028156, 5601.271834661468, 5702.173184888251, 5618.606697531297, 5638.323401191883, 5638.520725738358, 5645.06856368711, 5657.773689052392, 5500.367457742251, 5520.972395137998, 5526.55787536988, 5531.170324451365, 5542.8337149885565, 5492.972535137299, 5557.388687244481, 5629.718514074362, 5686.715664216856, 5743.942560574508, 5464.016477034172, 5493.560515158698, 5536.418676039119, 5548.334199896039, 5555.055640541199], "value": 5348.560878967121, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [537.2451705353978, 540.0845566764334, 557.8675289162999, 590.9202257695215, 601.9217501724775, 30606.84696576517, 31337.68662313379, 31422.214444638896, 38040.42939355905, 38803.02992425191, 41754.373684394755, 41979.0, 42153.15693686132, 42171.57828421723, 42219.622430205585, 9478.28912831537, 9694.17599504043, 9698.709038728814, 9701.0, 9717.028297170262, 8637.697680581181, 8840.513771742551, 9469.905300947006, 11580.420978951019, 11822.467988940476, 11592.188546801728, 11621.709457263552, 11632.360220187886, 11641.941790291079, 11650.883491165107, 8622.741317760414, 11364.431778411088, 11450.141239407078, 11469.54121835134, 11471.19701620887, 10084.64703735373, 11527.308361498253, 11733.647990560297, 11737.823932641017, 11738.823917641243, 37922.810385947894, 38356.041098972746, 38444.038899027466, 38479.03802404934, 38493.23013539742, 3259.3807176636365, 3442.242706604543, 3672.797996110213, 3813.3517302058717, 3840.5967373425947, 27620.0, 27637.723622763715, 27640.0, 28397.0, 28808.991685290915, 2775.264554892955, 2784.540550809114, 2827.618271533358, 2892.8264304141817, 2951.8081324713826, 500180.0, 503274.0, 503751.0, 504430.0, 504668.0, 36283.818580906925, 62447.0, 63623.681881590295, 63680.36319636744, 63838.0, 35563.933082007585, 35746.64253357433, 35769.284614307566, 35774.642253577134, 35826.28347433089, 15283.159426231665, 15468.767968480483, 15469.767953480707, 15470.690586188213, 15472.458463953708, 12210.26738395699, 12357.691057723448, 13073.803892941614, 13155.408006639724, 13157.868421315856, 8174.509529428254, 8263.504189748634, 8297.460665056751, 8346.582670866432, 8357.331413486907, 3075.185075954874, 3314.8895120134544, 3399.7280217582534, 3437.4843773434186, 3461.9480707789403, 1001.1790331927895, 1035.736401590074, 1056.7675111475462, 1062.5377960587168, 1100.3439823066394, 24398.878005610215, 29193.416131677674, 29624.407511849644, 30070.24824379408, 30815.845920770702, 1135.4436326200102, 1157.3923690062616, 1167.4064901409515, 1184.5084290019718, 1186.5016692988966, 12671.17637353569, 13207.273599952096, 15450.6137346565, 15569.688606227812, 16244.269007894673, 588084.0, 632550.0, 651915.0, 656886.0, 659331.0, 51189.48810511922, 60612.393876060676, 61481.38518614846, 61636.69181654063, 61781.38218617846, 1268.936553172356, 1373.9038267321191, 1382.640513466506, 1452.7433769789268, 1510.9546713598609, 28760.137195884156, 28909.421811563232, 29145.43891128368, 29308.706912930596, 29338.559921600765, 49345.0, 49433.258501121796, 49763.75118124386, 49836.25245621391, 49910.0, 374191.0, 377906.2209377871, 380549.0, 381576.0, 381696.0, 23542.646860296765, 23753.762462375154, 24059.639105413782, 24186.395340116636, 26916.788744506935, 34067.82966085154, 42704.0, 46125.538744612124, 46769.0, 48196.7590162047, 32033.839830800698, 35611.82194089013, 35835.82082089677, 36656.45015324825, 36663.45004824983, 35090.298194035466, 35337.0, 35450.645493545766, 35756.64243357637, 35879.0, 35065.123371915906, 35549.64450355567, 35742.0, 35937.28125437529, 36489.0, 30829.845850770602, 31383.686163138078, 31470.685293146777, 31489.21276968094, 31560.0, 25386.74613253844, 25587.9764809413, 25659.0, 25767.0, 26283.342916427242], "value": 18497.631837159002, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.6138162856559, 400.6754528831647, 406.49797500087385, 406.73358949887813, 413.86342506972704, 14085.962219539944, 16701.91649041753, 20167.49581260469, 20786.792132078674, 20806.687899681518, 15275.541733747978, 20195.495112622193, 20727.0, 21200.0, 21236.04437800299, 5659.349174844884, 5782.653040817543, 5966.552508561854, 5982.401759824026, 5984.700764961756, 4205.432266643995, 5944.791932282371, 5996.550258730591, 6004.819855404345, 6235.7505699771955, 6471.288158302583, 6480.189976252968, 6488.7728929487475, 6488.837779055514, 6498.707558159894, 6338.746450141989, 6395.027417932979, 6468.159139311882, 6515.381038801306, 6564.474842012675, 5698.914516282259, 5723.055695810186, 5740.368559458456, 5740.569457290678, 5744.3394009689, 25393.365165870815, 25522.851471683818, 25598.616020759702, 25598.744012559822, 26130.73869261302, 2240.115154513958, 2255.7067581214337, 2317.3279748872806, 2876.6404199475064, 2889.465448891962, 14746.852531474762, 14862.777058344134, 15083.095014299177, 15091.47179848711, 15360.846391536053, 1591.880608954333, 1765.2144795565978, 1779.8487128593956, 1798.586325145224, 1834.6697594432997, 101262.97474050478, 108272.0, 108634.45682771536, 109851.9999999992, 110213.44893275562, 10804.67585972422, 10965.725856853642, 11152.776944461228, 11425.31448113116, 11475.54097836094, 12842.0, 13361.599152025456, 14466.421343146367, 14476.420943162155, 14500.129992200502, 6865.725370985205, 8527.744167674855, 8564.571771411405, 8600.78498037554, 8718.782030449289, 8655.0, 8663.436876603, 8694.826103477895, 8764.298856091498, 8774.034856165817, 6656.267810540837, 6772.52592318533, 6782.354881596181, 6793.490488213305, 6794.966025169842, 1905.8189472000138, 1960.225710844237, 1989.383291179739, 1998.1607724755638, 2001.3095482058666, 918.5728636184207, 996.6412091647002, 1073.672529878379, 1091.328832767842, 1133.6429024857143, 20465.48836279105, 21081.156753729985, 21127.894360528408, 21215.36353909385, 21417.036233369538, 968.8934217236099, 1009.2934945538153, 1042.0985847242082, 1050.0497050169608, 1116.9106471482423, 11152.442377881236, 11327.32036077838, 11361.715957101138, 11406.48670809799, 11460.656180314438, 248314.0, 248916.75541622177, 250285.0, 621194.0, 652883.0, 61329.0, 77061.0, 99280.503597483, 99482.50776238363, 107385.46307268414, 1024.2369434770994, 1048.6539441984128, 1056.3450660590483, 1059.2426415113077, 1062.829947208443, 18014.91910485375, 18191.363302284357, 18254.634907302046, 18286.31138975956, 18333.633327333646, 46192.769036154605, 46818.29772553482, 46822.063558729315, 47029.76485117689, 48424.51575484341, 67405.3259467399, 68673.0, 69591.0, 71782.28217717756, 71844.64077679787, 6858.177018757781, 7698.384129269533, 7797.259260370256, 7930.563818990011, 7969.681212751425, 12310.507579696712, 12437.502499899903, 12663.93668031654, 12784.169029013085, 12942.935285323512, 10584.947075264574, 10597.735056623338, 10922.453877305945, 10937.343759374464, 10960.506777194885, 10677.733056673645, 10789.460526973777, 10942.835857461985, 11172.776544469227, 11206.943965280121, 10908.127349812155, 11216.55133794639, 11413.714657133638, 11511.30932144074, 11565.95906368431, 6926.995585640155, 7967.123616402307, 8043.436959412785, 8177.795555110932, 8412.03261624921, 10905.345679258953, 11000.614978475714, 11291.322520648788, 11303.208775385645, 11360.829587556358], "value": 9579.80505305267, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1366.6788304748388, 1539.607400112971, 1563.1324614838768, 1772.530279475939, 1912.5505506206039, 7855.371570274378, 8594.269487093597, 8753.649854005847, 8761.86857197141, 8769.824603507934, 8431.70489032884, 8684.13158684133, 8694.478331300126, 8695.86956195656, 8716.956415217915, 2181.9781802182015, 2243.8204943604474, 2247.741509726386, 2469.64190192422, 2493.2769496845895, 2155.1918030738475, 2448.1186772761794, 2499.3626625210545, 2504.8622325772076, 2506.010126000238, 2426.0053378114976, 2434.513097380522, 2464.9260522184272, 2469.5801713708624, 2471.7775400213977, 2167.3389616167065, 2435.7077150741934, 2444.706635203769, 2446.6330050492393, 2447.1801946347996, 2142.646463333548, 2420.140849998243, 2479.3181874984525, 2484.763947424992, 2487.639292302606, 8429.536375499345, 8583.699570515002, 8590.570471476463, 8610.44032137909, 8621.482711037357, 5290.603204759658, 5401.459854014606, 5402.9729851350885, 5419.864503387407, 5420.674759514442, 3452.1197094740933, 3530.75284730069, 3614.1868079682126, 3615.8192090395373, 3642.1076836175043, 1043.7964596903607, 1068.5672302717412, 1070.2454769387564, 1086.869575650927, 1107.8892110788938, 11656.883431165665, 11661.242019268806, 11677.416129193507, 11700.0, 11704.882951170464, 4781.402324709411, 4795.448523419851, 4803.855884323476, 4907.607391408679, 4912.828051018198, 9967.302288839712, 9998.850017249748, 10013.749656258506, 10023.749406264902, 10035.448050357301, 5454.263674403905, 5488.615796894179, 5512.283403157644, 5526.530244929146, 5532.5573954083575, 4416.11677664462, 4533.455985281787, 4959.082569724613, 4961.627877909099, 5015.673981191211, 3994.680425566006, 4012.8194231259663, 4080.367543030835, 4083.7958102094776, 4088.509378874495, 1124.5839039555253, 1152.458344578047, 1360.5034162530605, 1362.230339857977, 1369.459063669845, 915.5285028210477, 919.1773362840354, 949.4730424614268, 955.3169483819103, 969.4474149734724, 5858.794942177004, 5902.203202567598, 6036.788712395133, 6068.544859135583, 6082.847928801815, 972.9464879431557, 982.2387649571639, 1001.2690735762934, 1010.3786171504472, 1021.6577446555339, 3463.2034632034774, 3869.4776205212497, 3945.309570825094, 3949.1311911379453, 3989.7007724420782, 10511.737206569896, 10772.676819695422, 10875.347479151276, 10980.945095274632, 11012.063974562092, 10581.31221470602, 10591.205659575562, 10719.035286824223, 10809.0, 10814.35113893169, 7871.881921771063, 8267.917320826835, 8329.66681332752, 8347.165283471604, 8446.113158118309, 5803.129530570448, 5875.559333049953, 5916.349201587908, 5987.042073268256, 6066.393360663898, 9291.535423228675, 9398.0, 9512.762180945256, 9512.762180945532, 9526.618935242514, 9275.72172834816, 9703.223742100474, 9867.802643947225, 9873.259505537257, 9901.009899010041, 6706.497012723968, 6840.384365407137, 7276.126864776261, 7431.145418276751, 7537.359324457482, 7404.481686281909, 7472.252774722701, 7901.407394445555, 8145.941027666366, 8162.755117346489, 7123.964380178066, 7217.927820721936, 7218.386437152691, 7296.7081316746735, 7359.153697324662, 7017.228104908354, 7037.225905150532, 7106.680199390937, 7141.357277845019, 7146.32109949554, 6581.967090164519, 6839.316068393121, 6912.965435172792, 6939.4448444125355, 6941.062956500808, 4823.03539292136, 4863.319135320987, 4902.656814022984, 4994.925076123788, 5015.623828212826, 4381.18947994622, 4955.727434990966, 5013.147764879906, 5021.246812978083, 5080.949190508048], "value": 4595.133238767487, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.23336109601064, 327.33224222585926, 331.6318886036499, 337.2647628170588, 342.95911907361165, 6121.081837724343, 6740.0, 6747.79756607302, 6793.6603169841555, 6834.726610935557, 6719.294474080212, 6728.461723062155, 6738.42723368513, 6744.258131605532, 6757.831054223647, 4722.740249286297, 4801.5438533339275, 4815.181419158752, 4897.7755561109725, 4938.456769755324, 4870.951290487085, 4913.680610760289, 4917.803287868481, 4945.876353091184, 4954.157793175169, 4874.780634871439, 4885.5358740919555, 4889.462159162489, 4894.926576101361, 4895.21676531755, 4710.246360582308, 4741.525847415248, 4848.200046992242, 4856.635752318555, 4869.366982292279, 4156.729812562204, 4182.184474027566, 4182.2890108681595, 4184.330507118846, 4186.0581369192, 5898.498627616657, 6017.428344307284, 6510.576812507172, 6540.607563546202, 6542.247641521239, 5858.033424484955, 5860.765569377219, 5865.882682346371, 5868.178455016301, 5875.6474611523445, 6760.1549806274215, 7153.60655163971, 7166.964165179141, 7169.498135130543, 7187.676554555058, 4600.585947264729, 4612.308153776927, 4613.653975951784, 4632.675712700112, 4652.534746525321, 7436.814079647998, 7534.962325188339, 7547.547147171187, 7555.546667199986, 7556.395488360918, 4677.970846413784, 4683.250679891201, 4701.200795864711, 4712.929306060412, 4720.834770783006, 4750.978539614, 4764.6664733469015, 4768.332433459318, 4777.856664300007, 4779.641526885497, 5475.917861232085, 5712.314522257356, 5721.599488035798, 5725.541956643458, 5744.138379243063, 7155.141383033965, 7164.570125792469, 7193.712251509985, 7194.316539928698, 7201.747938822116, 1610.1466222901881, 1655.1806855606312, 1664.2261348473019, 1665.2423147467873, 1667.7831881855525, 979.647326962293, 990.6631745206664, 1024.3956065921143, 1034.9379037257788, 1059.586761163147, 6604.1084453599115, 6901.758438454731, 6914.239433662293, 6928.584284942819, 6929.44564434844, 7461.626918654046, 7468.290512401313, 7471.252874712485, 7480.513766605153, 7509.549427034395, 5522.696251706195, 6686.866262674719, 6697.129373181455, 6708.765193218312, 6736.494762892803, 3792.60177681345, 3910.2179564087296, 3970.2059588082498, 3970.7617542947514, 3972.503437070366, 7408.148062972719, 7788.182240864741, 7791.922080779233, 7820.452568320163, 7824.608769561499, 6568.573042752206, 7473.813154671177, 7717.575533345607, 7735.7679269622, 7736.071671399468, 7785.961070194613, 7788.8052798679355, 7797.493162944391, 7799.45403821727, 7830.256125668053, 7162.391196748335, 7420.146683131509, 7446.888296675663, 7472.402207823252, 7480.438967077382, 7320.377767889789, 7326.9267307326245, 7341.045664063638, 7365.0057242271605, 7373.889391659237, 7766.844663106819, 7780.533168009937, 7791.0, 7799.713047347124, 7809.843803123792, 4699.295105734167, 4712.316714076508, 4715.24556071027, 4719.433667959866, 4719.433667959866, 7169.7849064528145, 7341.229170936975, 7437.665305061393, 7450.8882366763455, 7469.402447804054, 7163.140423149047, 7203.567785932861, 7211.242819504087, 7218.711251549879, 7234.457415693949, 7206.2793720627515, 7210.747623833141, 7235.059442272471, 7237.240089790496, 7243.0, 5850.356460789395, 7025.929740702528, 7037.296270372922, 7039.0, 7060.71757129709, 6872.140982377203, 7187.640617969185, 7195.316444937723, 7224.566526008456, 7227.927720722936, 6741.797746067626, 6864.622445765531, 6925.792226233221, 7009.509334346751, 7077.929220707727], "value": 5228.144065933703, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.1855107351009, 746.734909107267, 760.7565579014715, 769.6613490064371, 774.930256276935, 14854.25728713564, 15118.319675614597, 15120.16839073849, 15120.243987800593, 15149.242537873104, 13821.51624693136, 13879.375428105734, 13904.860951390483, 13913.930430347835, 13918.791218131712, 736.7568702328219, 753.6081237756364, 893.5576889439736, 913.9451632902014, 916.221211969827, 819.1971867569786, 870.6778491957984, 913.1872633356318, 919.5678031325266, 921.9953900230523, 939.9436033837958, 947.1664934857316, 957.2533423929347, 957.5499515227866, 960.3949511807551, 773.0645918438696, 949.7578117579973, 951.8905325887542, 951.9476428796413, 953.5375342958729, 721.6355740351141, 722.8301349182938, 724.0478770416885, 724.478375569589, 725.8258018075624, 14205.573832785032, 14213.78679319811, 14231.288435578179, 14236.074655147382, 14246.145231286157, 12021.0, 12251.57119500822, 12463.688407789787, 12745.553905613258, 13085.214887106804, 9862.556184971621, 9914.355566888202, 9946.602135914554, 10188.796224075477, 10626.043656070913, 3146.3392687535643, 3481.9651803482147, 3488.023353461035, 3511.525943997553, 3552.3960926642535, 70927.22681932941, 71354.28645713528, 72233.0, 72783.0, 73006.80979570621, 8929.151730585583, 9228.8615670765, 9256.583453744597, 9503.66737164196, 9522.00018998009, 20015.399538013884, 20069.59860802805, 20125.194992200148, 20138.093785779674, 20175.697364539545, 7295.270472952662, 7391.0, 7409.703611855573, 7522.5862577558755, 7742.10965738936, 3740.7194460415576, 3819.1406933439753, 3849.615038496184, 3854.9229015419533, 3974.3044967131214, 3605.080704420356, 3676.963230367662, 3684.907877303035, 3685.152414944524, 3688.22547265072, 611.6452457574596, 615.8029430582171, 620.6679426506887, 622.00168729189, 639.6002498438476, 519.8284566093256, 554.9805756798493, 594.9077892926604, 611.9418655227746, 617.9505639548826, 7713.382929365637, 8088.757337279891, 8098.473599216032, 8136.75589732309, 8177.877331840027, 584.433099893103, 587.2307277466447, 589.1839801874349, 620.3424370167637, 631.6715308039885, 3177.269228077509, 3793.506844110248, 3824.7322687411615, 3840.183102796265, 3885.2423777363706, 92851.0, 92856.53571732098, 93391.0, 93961.0603893966, 95762.52118739502, 70114.64942675254, 72390.63804680943, 73154.0, 73309.26690733132, 74518.0, 68976.6551167241, 69694.3030569698, 70736.0, 71682.64158679174, 71970.2802971974, 7031.7890463286185, 7489.6893043716045, 7594.164641889277, 7765.650545725569, 7826.647800849089, 45286.77356613196, 48970.755146224044, 50418.991620168126, 50523.0, 50896.49103508917, 48693.0, 63663.363366367594, 67871.66064169648, 68907.31092689009, 69373.95939060817, 10684.465776710978, 11041.723956901142, 11090.5563777448, 11185.272957257752, 11265.914481061278, 11431.771364572829, 12157.75684486323, 12330.0, 12403.25580465175, 12564.811527827273, 11251.943740281245, 11332.6033588824, 11427.885721142682, 11791.587294444653, 12108.273503589813, 11097.833532497181, 11269.774604508028, 11399.886001140214, 11473.311601303949, 11995.76008479843, 10896.692466588749, 10906.563737450731, 11108.333499990027, 11345.829812552653, 12191.75616487683, 9049.136074473123, 9226.769330766785, 9473.194778443909, 9485.33602647808, 9558.808823823623, 8345.248927596545, 8456.2812160967, 8512.233898949124, 8612.956935215283, 8735.606897689746], "value": 6766.458845750544, "unit": "score"}], "name": "dromaeo_css", "value": 7321.9280072101965, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 117.0, 115.0, 111.0, 114.0, 113.0, 114.0, 114.0, 134.0], "value": 115.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 107.0, 164.0, 189.0, 143.0, 155.0, 116.0, 152.0, 157.0, 116.0], "value": 145.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 186.0, 184.0, 187.0, 183.0, 185.0, 186.0, 186.0, 185.0], "value": 185.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 79.0, 77.0, 154.0, 56.0, 55.0, 58.0, 63.0, 133.0, 54.0], "value": 79.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 144.0, 75.0, 86.0, 83.0, 128.0, 79.0, 77.0, 126.0], "value": 97.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 86.0, 84.0, 86.0, 83.0, 87.0, 84.0, 87.0, 84.0], "value": 85.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 92.0, 93.0, 91.0, 92.0, 93.0, 92.0, 92.0, 92.0], "value": 92.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 84.0, 85.0, 86.0, 84.0, 83.0, 85.0, 84.0, 83.0], "value": 84.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 65.0, 64.0, 65.0, 63.0, 65.0, 68.0, 84.0, 60.0, 60.0], "value": 65.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 71.0, 109.0, 109.0, 104.0, 67.0, 67.0, 75.0, 69.0], "value": 80.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 83.0, 103.0, 79.0, 80.0, 81.0, 80.0, 81.0, 80.0, 86.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 138.0, 146.0, 124.0, 125.0, 130.0, 113.0, 141.0, 181.0, 162.0], "value": 140.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 162.0, 197.0, 169.0, 153.0, 200.0, 218.0, 179.0, 165.0, 169.0], "value": 177.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 66.0, 67.0, 67.0, 66.0, 66.0, 69.0, 68.0, 66.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1501.2000000000003, "alertThreshold": 2.0}]} 03:15:40 INFO - Return code: 0 03:15:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:15:40 ERROR - # TBPL SUCCESS # 03:15:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:15:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:15:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:15:40 INFO - 2016-05-11 03:15:40.830 screenresolution[1946:16643] starting screenresolution argv=screenresolution get 03:15:40 INFO - 2016-05-11 03:15:40.833 screenresolution[1946:16643] Display 0: 1600x1200x32@60 03:15:40 INFO - 2016-05-11 03:15:40.839 screenresolution[1947:16646] starting screenresolution argv=screenresolution list 03:15:40 INFO - Available Modes on Display 0 03:15:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:15:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:15:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:15:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:15:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:15:41 INFO - Intel Iris: 03:15:41 INFO - Chipset Model: Intel Iris 03:15:41 INFO - Type: GPU 03:15:41 INFO - Bus: Built-In 03:15:41 INFO - VRAM (Dynamic, Max): 1536 MB 03:15:41 INFO - Vendor: Intel (0x8086) 03:15:41 INFO - Device ID: 0x0a2e 03:15:41 INFO - Revision ID: 0x0009 03:15:41 INFO - Displays: 03:15:41 INFO - Display: 03:15:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:15:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:15:41 INFO - Display Serial Number: 1600x1200 60 03:15:41 INFO - Main Display: Yes 03:15:41 INFO - Mirror: Off 03:15:41 INFO - Online: Yes 03:15:41 INFO - Rotation: Supported 03:15:41 INFO - Return code: 0 03:15:41 INFO - Running post-action listener: _resource_record_post_action 03:15:41 INFO - Running post-run listener: _resource_record_post_run 03:15:42 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 892928; Write bytes: 653790208; Read time: 12; Write time: 2005 03:15:42 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 354191872; Write bytes: 356099584; Read time: 27994; Write time: 785 03:15:42 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 892928; Write bytes: 296633856; Read time: 12; Write time: 1214 03:15:42 INFO - Running post-run listener: _upload_blobber_files 03:15:42 INFO - Blob upload gear active. 03:15:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:15:42 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:15:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:15:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:15:42 INFO - (blobuploader) - INFO - Open directory for files ... 03:15:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:15:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:15:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:15:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:15:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:15:43 INFO - (blobuploader) - INFO - Done attempting. 03:15:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:15:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:15:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:15:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:15:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:15:44 INFO - (blobuploader) - INFO - Done attempting. 03:15:44 INFO - (blobuploader) - INFO - Iteration through files over. 03:15:44 INFO - Return code: 0 03:15:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:15:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:15:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ac375f3e37452349cf55d39d821e9e9c7dc2b9f0a5a26750e8d75a179af32ad422230c142726feaead24d85f288768217c4159af5f9f08b3929c6ef37ca1a01", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/685a3f333767815ce57e5e7deb0e3fe023881f7d84943c5985affc61f25d7db14758adeb842919aee4f4d4b89d9bbe5c1c3ca5df99fa93320d380d2ea294a526"} 03:15:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:15:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:15:44 INFO - Contents: 03:15:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ac375f3e37452349cf55d39d821e9e9c7dc2b9f0a5a26750e8d75a179af32ad422230c142726feaead24d85f288768217c4159af5f9f08b3929c6ef37ca1a01", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/685a3f333767815ce57e5e7deb0e3fe023881f7d84943c5985affc61f25d7db14758adeb842919aee4f4d4b89d9bbe5c1c3ca5df99fa93320d380d2ea294a526"} 03:15:44 INFO - Running post-run listener: copy_logs_to_upload_dir 03:15:44 INFO - Copying logs to upload dir... 03:15:44 INFO - mkdir: /builds/slave/test/build/upload/logs 03:15:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.814401 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-05-11 03:15:44.723172) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 03:15:44.726677) ========= 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.TGFezSwMHc/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.KQK6JKMM4g/Listeners TMPDIR=/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ac375f3e37452349cf55d39d821e9e9c7dc2b9f0a5a26750e8d75a179af32ad422230c142726feaead24d85f288768217c4159af5f9f08b3929c6ef37ca1a01", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/685a3f333767815ce57e5e7deb0e3fe023881f7d84943c5985affc61f25d7db14758adeb842919aee4f4d4b89d9bbe5c1c3ca5df99fa93320d380d2ea294a526"} build_url:https://queue.taskcluster.net/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011164 build_url: 'https://queue.taskcluster.net/v1/task/CRNT8GeqRMSXszilSUJCYQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5ac375f3e37452349cf55d39d821e9e9c7dc2b9f0a5a26750e8d75a179af32ad422230c142726feaead24d85f288768217c4159af5f9f08b3929c6ef37ca1a01", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/685a3f333767815ce57e5e7deb0e3fe023881f7d84943c5985affc61f25d7db14758adeb842919aee4f4d4b89d9bbe5c1c3ca5df99fa93320d380d2ea294a526"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 03:15:44.754787) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 03:15:44.755120) ========= 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.TGFezSwMHc/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.KQK6JKMM4g/Listeners TMPDIR=/var/folders/z1/5zz99xf938j6hh205lswyzgc00000w/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.004751 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 03:15:44.804530) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 03:15:44.804879) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 03:15:44.805230) ========= ========= Total master_lag: 0.16 =========