builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0056 starttime: 1461664013.99 results: success (0) buildid: 20160426004107 builduid: 38934b15869c4ff8bf70109d6f20fde7 revision: ca6ab5be342e2d7817b0015447872dab3ecd5ef1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 02:46:53.992746) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 02:46:53.993231) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-26 02:46:53.993508) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-26 02:46:54.011604) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 02:46:54.012002) ========= 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.BUMwjIKqyq/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.rx5SGQMzif/Listeners TMPDIR=/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-26 02:46:54-- 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% 52.9M=0s 2016-04-26 02:46:54 (52.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.155554 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 02:46:54.186392) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 02:46:54.186789) ========= 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.BUMwjIKqyq/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.rx5SGQMzif/Listeners TMPDIR=/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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.026774 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 02:46:54.235081) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-26 02:46:54.235455) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ca6ab5be342e2d7817b0015447872dab3ecd5ef1 --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 ca6ab5be342e2d7817b0015447872dab3ecd5ef1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.BUMwjIKqyq/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.rx5SGQMzif/Listeners TMPDIR=/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-26 02:46:54,314 truncating revision to first 12 chars 2016-04-26 02:46:54,314 Setting DEBUG logging. 2016-04-26 02:46:54,314 attempt 1/10 2016-04-26 02:46:54,314 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ca6ab5be342e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-26 02:46:56,082 unpacking tar archive at: mozilla-aurora-ca6ab5be342e/testing/mozharness/ program finished with exit code 0 elapsedTime=2.018570 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-26 02:46:56.277261) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 02:46:56.277709) ========= script_repo_revision: ca6ab5be342e2d7817b0015447872dab3ecd5ef1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 02:46:56.278185) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 02:46:56.278469) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 02:46:56.296334) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-04-26 02:46:56.296773) ========= /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.BUMwjIKqyq/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.rx5SGQMzif/Listeners TMPDIR=/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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:46:56 INFO - MultiFileLogger online at 20160426 02:46:56 in /builds/slave/test 02:46:56 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:46:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:46:56 INFO - {'append_to_log': False, 02:46:56 INFO - 'base_work_dir': '/builds/slave/test', 02:46:56 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:46:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:46:56 INFO - 'branch': 'Mozilla-Aurora', 02:46:56 INFO - 'buildbot_json_path': 'buildprops.json', 02:46:56 INFO - 'config_files': ('talos/mac_config.py',), 02:46:56 INFO - 'default_actions': ('clobber', 02:46:56 INFO - 'read-buildbot-config', 02:46:56 INFO - 'download-and-extract', 02:46:56 INFO - 'populate-webroot', 02:46:56 INFO - 'create-virtualenv', 02:46:56 INFO - 'install', 02:46:56 INFO - 'run-tests'), 02:46:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:46:56 INFO - 'download_minidump_stackwalk': True, 02:46:56 INFO - 'download_symbols': 'ondemand', 02:46:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:46:56 INFO - 'tooltool.py': '/tools/tooltool.py', 02:46:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:46:56 INFO - '/tools/misc-python/virtualenv.py')}, 02:46:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:46:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:46:56 INFO - 'installer_path': 'installer.exe', 02:46:56 INFO - 'log_level': 'info', 02:46:56 INFO - 'log_name': 'talos', 02:46:56 INFO - 'log_to_console': True, 02:46:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:46:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:46:56 INFO - 'opt_config_files': (), 02:46:56 INFO - 'pip_index': False, 02:46:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:46:56 INFO - 'cmd': ('bash', 02:46:56 INFO - '-c', 02:46:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:46:56 INFO - 'enabled': True, 02:46:56 INFO - 'halt_on_failure': False, 02:46:56 INFO - 'name': 'check_screen_resolution'},), 02:46:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:46:56 INFO - 'cmd': ('bash', 02:46:56 INFO - '-c', 02:46:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:46:56 INFO - 'enabled': True, 02:46:56 INFO - 'halt_on_failure': False, 02:46:56 INFO - 'name': 'check_screen_resolution'},), 02:46:56 INFO - 'run_cmd_checks_enabled': True, 02:46:56 INFO - 'sps_profile': False, 02:46:56 INFO - 'sps_profile_interval': 0, 02:46:56 INFO - 'suite': 'dromaeojs', 02:46:56 INFO - 'system_bits': '32', 02:46:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:46:56 INFO - 'title': 't-yosemite-r7-0056', 02:46:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:46:56 INFO - 'use_talos_json': True, 02:46:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:46:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:46:56 INFO - 'work_dir': 'build'} 02:46:56 INFO - ##### 02:46:56 INFO - ##### Running clobber step. 02:46:56 INFO - ##### 02:46:56 INFO - Running pre-action listener: _resource_record_pre_action 02:46:56 INFO - Running main action method: clobber 02:46:56 INFO - rmtree: /builds/slave/test/build 02:46:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:46:58 INFO - Running post-action listener: _resource_record_post_action 02:46:58 INFO - ##### 02:46:58 INFO - ##### Running read-buildbot-config step. 02:46:58 INFO - ##### 02:46:58 INFO - Running pre-action listener: _resource_record_pre_action 02:46:58 INFO - Running main action method: read_buildbot_config 02:46:58 INFO - Using buildbot properties: 02:46:58 INFO - { 02:46:58 INFO - "project": "", 02:46:58 INFO - "product": "firefox", 02:46:58 INFO - "script_repo_revision": "production", 02:46:58 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:46:58 INFO - "repository": "", 02:46:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:46:58 INFO - "buildid": "20160426004107", 02:46:58 INFO - "stage_platform": "macosx64", 02:46:58 INFO - "basedir": "/builds/slave/test", 02:46:58 INFO - "nightly_build": "True", 02:46:58 INFO - "buildnumber": 292, 02:46:58 INFO - "slavename": "t-yosemite-r7-0056", 02:46:58 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 02:46:58 INFO - "platform": "yosemite_r7", 02:46:58 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:46:58 INFO - "branch": "mozilla-aurora", 02:46:58 INFO - "repo_path": "releases/mozilla-aurora", 02:46:58 INFO - "revision": "ca6ab5be342e2d7817b0015447872dab3ecd5ef1", 02:46:58 INFO - "pgo_build": "False", 02:46:58 INFO - "builduid": "38934b15869c4ff8bf70109d6f20fde7", 02:46:58 INFO - "slavebuilddir": "test" 02:46:58 INFO - } 02:46:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 02:46:58 INFO - Running post-action listener: _resource_record_post_action 02:46:58 INFO - ##### 02:46:58 INFO - ##### Running download-and-extract step. 02:46:58 INFO - ##### 02:46:58 INFO - Running pre-action listener: _resource_record_pre_action 02:46:58 INFO - Running main action method: download_and_extract 02:46:58 INFO - mkdir: /builds/slave/test/build/tests 02:46:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:46:58 INFO - https://queue.taskcluster.net/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:46:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:46:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:46:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/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:46:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/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:46:59 INFO - Downloaded 1199 bytes. 02:46:59 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:46:59 INFO - Using the following test package requirements: 02:46:59 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:46:59 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:46:59 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 02:46:59 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:46:59 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 02:46:59 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:46:59 INFO - u'jsshell-mac.zip'], 02:46:59 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:46:59 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 02:46:59 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:46:59 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:46:59 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 02:46:59 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:46:59 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 02:46:59 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:46:59 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 02:46:59 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:46:59 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 02:46:59 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:46:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:46:59 INFO - https://queue.taskcluster.net/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:46:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:46:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:46:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/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:46:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:47:00 INFO - Downloaded 18109665 bytes. 02:47:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:47:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:47:04 INFO - Return code: 0 02:47:04 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:47:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:04 INFO - https://queue.taskcluster.net/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:47:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:47:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:47:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:47:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:47:05 INFO - Downloaded 18109665 bytes. 02:47:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:47:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:47:08 INFO - Return code: 0 02:47:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:08 INFO - https://queue.taskcluster.net/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:47:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:47:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:47:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:47:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:47:09 INFO - Downloaded 10840145 bytes. 02:47:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:47:09 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 02:47:09 INFO - Return code: 0 02:47: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:47:09 INFO - https://queue.taskcluster.net/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 02:47:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:47:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:47:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 02:47:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 02:47:14 INFO - Downloaded 114216509 bytes. 02:47:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:47:14 INFO - mkdir: /builds/slave/test/properties 02:47:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:47:14 INFO - Writing to file /builds/slave/test/properties/build_url 02:47:14 INFO - Contents: 02:47:14 INFO - build_url:https://queue.taskcluster.net/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:47:16 INFO - Running post-action listener: _resource_record_post_action 02:47:16 INFO - Running post-action listener: set_extra_try_arguments 02:47:16 INFO - ##### 02:47:16 INFO - ##### Running populate-webroot step. 02:47:16 INFO - ##### 02:47:16 INFO - Running pre-action listener: _resource_record_pre_action 02:47:16 INFO - Running main action method: populate_webroot 02:47:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:47:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:47:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:47:16 INFO - '--tppagecycles', 02:47:16 INFO - '7'], 02:47:16 INFO - 'tests': ['tsvgm']}}, 02:47:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:47:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:47:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:47:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:47:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:47:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:47:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:47:16 INFO - 'talos_options': ['--disable-e10s'], 02:47:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:47:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:47:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:47:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:47:16 INFO - 'talos_options': ['--disable-e10s'], 02:47:16 INFO - 'tests': ['damp', 'tps']}, 02:47:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:47:16 INFO - 'tests': ['damp', 'tps']}, 02:47:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:47:16 INFO - 'tests': ['dromaeo_dom']}, 02:47:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:47:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:47:16 INFO - 'tests': ['a11yr', 02:47:16 INFO - 'ts_paint', 02:47:16 INFO - 'tpaint', 02:47:16 INFO - 'sessionrestore', 02:47:16 INFO - 'sessionrestore_no_auto_restore', 02:47:16 INFO - 'tabpaint']}, 02:47:16 INFO - 'other-e10s': {'tests': ['a11yr', 02:47:16 INFO - 'ts_paint', 02:47:16 INFO - 'tpaint', 02:47:16 INFO - 'sessionrestore', 02:47:16 INFO - 'sessionrestore_no_auto_restore', 02:47:16 INFO - 'tabpaint']}, 02:47:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:47:16 INFO - 'ts_paint', 02:47:16 INFO - 'tpaint', 02:47:16 INFO - 'sessionrestore', 02:47:16 INFO - 'sessionrestore_no_auto_restore', 02:47:16 INFO - 'tabpaint']}, 02:47:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:47:16 INFO - 'ts_paint', 02:47:16 INFO - 'tpaint', 02:47:16 INFO - 'sessionrestore', 02:47:16 INFO - 'sessionrestore_no_auto_restore', 02:47:16 INFO - 'tabpaint']}, 02:47:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:47:16 INFO - 'tests': ['a11yr', 02:47:16 INFO - 'ts_paint', 02:47:16 INFO - 'tpaint', 02:47:16 INFO - 'sessionrestore', 02:47:16 INFO - 'sessionrestore_no_auto_restore', 02:47:16 INFO - 'tabpaint']}, 02:47:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:47:16 INFO - 'tests': ['a11yr', 02:47:16 INFO - 'ts_paint', 02:47:16 INFO - 'tpaint', 02:47:16 INFO - 'sessionrestore', 02:47:16 INFO - 'sessionrestore_no_auto_restore', 02:47:16 INFO - 'tabpaint']}, 02:47:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:47:16 INFO - 'tests': ['tsvgx', 02:47:16 INFO - 'tsvgr_opacity', 02:47:16 INFO - 'tart', 02:47:16 INFO - 'tscrollx', 02:47:16 INFO - 'cart']}, 02:47:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:47:16 INFO - 'tsvgr_opacity', 02:47:16 INFO - 'tart', 02:47:16 INFO - 'tscrollx', 02:47:16 INFO - 'cart']}, 02:47:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:47:16 INFO - 'talos_options': ['--disable-e10s'], 02:47:16 INFO - 'tests': ['tp5o']}, 02:47:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:47:16 INFO - 'tests': ['tp5o']}, 02:47:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:47:16 INFO - 'talos_options': ['--disable-e10s', 02:47:16 INFO - '--xperf_path', 02:47:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:47:16 INFO - 'tests': ['tp5n']}, 02:47:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:47:16 INFO - 'talos_options': ['--xperf_path', 02:47:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:47:16 INFO - 'tests': ['tp5n']}}, 02:47:16 INFO - 'talos.zip': {'path': '', 02:47:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:47:16 INFO - Running post-action listener: _resource_record_post_action 02:47:16 INFO - ##### 02:47:16 INFO - ##### Running create-virtualenv step. 02:47:16 INFO - ##### 02:47:16 INFO - Running pre-action listener: _resource_record_pre_action 02:47:16 INFO - Running main action method: create_virtualenv 02:47:16 INFO - Creating virtualenv /builds/slave/test/build/venv 02:47:16 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:47:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:47:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:47:16 INFO - Using real prefix '/tools/python27' 02:47:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:47:17 INFO - Installing distribute.............................................................................................................................................................................................done. 02:47:19 INFO - Installing pip.................done. 02:47:19 INFO - Return code: 0 02:47:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:47: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:47:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47: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:47:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:19 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 0x106aa0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ab0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feff0dab700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10693fe00>, '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 0x1067a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10693ab20>, '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.BUMwjIKqyq/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.rx5SGQMzif/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:47:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:47:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:47:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BUMwjIKqyq/Render', 02:47:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:47:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:47:19 INFO - 'HOME': '/Users/cltbld', 02:47:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:47:19 INFO - 'LOGNAME': 'cltbld', 02:47:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:19 INFO - 'MOZ_NO_REMOTE': '1', 02:47:19 INFO - 'NO_EM_RESTART': '1', 02:47:19 INFO - 'PAGER': '/bin/cat', 02:47:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:47:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:19 INFO - 'PWD': '/builds/slave/test', 02:47:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:47:19 INFO - 'SHELL': '/bin/bash', 02:47:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rx5SGQMzif/Listeners', 02:47:19 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 02:47:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:47:19 INFO - 'USER': 'cltbld', 02:47:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:47:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:47:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:19 INFO - 'XPC_FLAGS': '0x0', 02:47:19 INFO - 'XPC_SERVICE_NAME': '0', 02:47:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:47:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:20 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:47:20 INFO - Cleaning up... 02:47:20 INFO - Return code: 0 02:47:20 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:47:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:20 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 0x106aa0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ab0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feff0dab700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10693fe00>, '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 0x1067a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10693ab20>, '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.BUMwjIKqyq/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.rx5SGQMzif/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:47:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 02:47:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 02:47:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BUMwjIKqyq/Render', 02:47:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:47:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:47:20 INFO - 'HOME': '/Users/cltbld', 02:47:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:47:20 INFO - 'LOGNAME': 'cltbld', 02:47:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:20 INFO - 'MOZ_NO_REMOTE': '1', 02:47:20 INFO - 'NO_EM_RESTART': '1', 02:47:20 INFO - 'PAGER': '/bin/cat', 02:47:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:47:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:20 INFO - 'PWD': '/builds/slave/test', 02:47:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:47:20 INFO - 'SHELL': '/bin/bash', 02:47:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rx5SGQMzif/Listeners', 02:47:20 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 02:47:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:47:20 INFO - 'USER': 'cltbld', 02:47:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:47:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:47:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:20 INFO - 'XPC_FLAGS': '0x0', 02:47:20 INFO - 'XPC_SERVICE_NAME': '0', 02:47:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:47:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:20 INFO - Downloading/unpacking psutil>=0.7.1 02:47:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:47:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:47:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:47:23 INFO - Installing collected packages: psutil 02:47:23 INFO - Running setup.py install for psutil 02:47:23 INFO - building 'psutil._psutil_osx' extension 02:47:23 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:47:23 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:47:23 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:47:24 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:47:24 INFO - building 'psutil._psutil_posix' extension 02:47:24 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:47:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:47:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:47:24 INFO - ^ 02:47:24 INFO - 1 warning generated. 02:47:24 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:47:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:47:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:47:24 INFO - Successfully installed psutil 02:47:24 INFO - Cleaning up... 02:47:24 INFO - Return code: 0 02:47:24 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:47: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:47:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47: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:47:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47: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', '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 0x106aa0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ab0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feff0dab700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10693fe00>, '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 0x1067a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10693ab20>, '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.BUMwjIKqyq/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.rx5SGQMzif/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:47: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 02:47: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 mozsystemmonitor==0.0.0 02:47:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BUMwjIKqyq/Render', 02:47:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:47:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:47:24 INFO - 'HOME': '/Users/cltbld', 02:47:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:47:24 INFO - 'LOGNAME': 'cltbld', 02:47:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:24 INFO - 'MOZ_NO_REMOTE': '1', 02:47:24 INFO - 'NO_EM_RESTART': '1', 02:47:24 INFO - 'PAGER': '/bin/cat', 02:47:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:47:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:24 INFO - 'PWD': '/builds/slave/test', 02:47:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:47:24 INFO - 'SHELL': '/bin/bash', 02:47:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rx5SGQMzif/Listeners', 02:47:24 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 02:47:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:47:24 INFO - 'USER': 'cltbld', 02:47:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:47:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:47:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:24 INFO - 'XPC_FLAGS': '0x0', 02:47:24 INFO - 'XPC_SERVICE_NAME': '0', 02:47:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:47:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:24 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:47:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:27 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:47:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:47:27 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:47:27 INFO - Installing collected packages: mozsystemmonitor 02:47:27 INFO - Running setup.py install for mozsystemmonitor 02:47:27 INFO - Successfully installed mozsystemmonitor 02:47:27 INFO - Cleaning up... 02:47:27 INFO - Return code: 0 02:47:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:47:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:27 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 0x106aa0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ab0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feff0dab700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10693fe00>, '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 0x1067a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10693ab20>, '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.BUMwjIKqyq/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.rx5SGQMzif/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:47:27 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:47:27 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:47:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BUMwjIKqyq/Render', 02:47:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:47:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:47:27 INFO - 'HOME': '/Users/cltbld', 02:47:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:47:27 INFO - 'LOGNAME': 'cltbld', 02:47:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:27 INFO - 'MOZ_NO_REMOTE': '1', 02:47:27 INFO - 'NO_EM_RESTART': '1', 02:47:27 INFO - 'PAGER': '/bin/cat', 02:47:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:47:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:27 INFO - 'PWD': '/builds/slave/test', 02:47:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:47:27 INFO - 'SHELL': '/bin/bash', 02:47:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rx5SGQMzif/Listeners', 02:47:27 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 02:47:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:47:27 INFO - 'USER': 'cltbld', 02:47:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:47:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:47:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:27 INFO - 'XPC_FLAGS': '0x0', 02:47:27 INFO - 'XPC_SERVICE_NAME': '0', 02:47:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:47:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:27 INFO - Downloading/unpacking blobuploader==1.2.4 02:47: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:47: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:47: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:47: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:47: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:47: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:47:30 INFO - Downloading blobuploader-1.2.4.tar.gz 02:47:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:47:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:47: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:47: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:47: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:47: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:47: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:47: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:47:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:47:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:47: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:47: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:47: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:47: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:47: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:47: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:47:31 INFO - Downloading docopt-0.6.1.tar.gz 02:47:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:47:31 INFO - Installing collected packages: blobuploader, requests, docopt 02:47:31 INFO - Running setup.py install for blobuploader 02:47:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:47:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:47:31 INFO - Running setup.py install for requests 02:47:31 INFO - Running setup.py install for docopt 02:47:32 INFO - Successfully installed blobuploader requests docopt 02:47:32 INFO - Cleaning up... 02:47:32 INFO - Return code: 0 02:47:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:47:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:32 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 0x106aa0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ab0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feff0dab700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10693fe00>, '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 0x1067a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10693ab20>, '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.BUMwjIKqyq/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.rx5SGQMzif/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:47:32 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:47:32 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:47:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BUMwjIKqyq/Render', 02:47:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:47:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:47:32 INFO - 'HOME': '/Users/cltbld', 02:47:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:47:32 INFO - 'LOGNAME': 'cltbld', 02:47:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:32 INFO - 'MOZ_NO_REMOTE': '1', 02:47:32 INFO - 'NO_EM_RESTART': '1', 02:47:32 INFO - 'PAGER': '/bin/cat', 02:47:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:47:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:32 INFO - 'PWD': '/builds/slave/test', 02:47:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:47:32 INFO - 'SHELL': '/bin/bash', 02:47:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rx5SGQMzif/Listeners', 02:47:32 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 02:47:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:47:32 INFO - 'USER': 'cltbld', 02:47:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:47:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:47:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:32 INFO - 'XPC_FLAGS': '0x0', 02:47:32 INFO - 'XPC_SERVICE_NAME': '0', 02:47:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:47:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:47:32 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-SW6FKj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:47:32 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-Wmw4gH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:47:32 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-kkiR6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:47:32 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-U02NGb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:47:32 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-Ii0Am1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:47:32 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-39luua-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:47:33 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-Csc5O0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:47:33 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-J_GfDv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:47:33 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-RdaaeG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:47:33 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-gt3Vm2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:47:33 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-BeH0Zw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:47:33 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-1xPttr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:47:33 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-ZuwccA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:47:33 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-8y66JA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:47:33 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-Q7RVN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:47:34 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-YZYpCF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:47:34 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-lfsOCL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:47:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:47:34 INFO - Running setup.py install for manifestparser 02:47:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:47:34 INFO - Running setup.py install for mozcrash 02:47:34 INFO - Running setup.py install for mozdebug 02:47:34 INFO - Running setup.py install for mozdevice 02:47:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:47:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:47:34 INFO - Running setup.py install for mozfile 02:47:34 INFO - Running setup.py install for mozhttpd 02:47:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:47:35 INFO - Running setup.py install for mozinfo 02:47:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:47:35 INFO - Running setup.py install for mozInstall 02:47:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:47:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:47:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:47:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:47:35 INFO - Running setup.py install for mozleak 02:47:35 INFO - Running setup.py install for mozlog 02:47:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:47:35 INFO - Running setup.py install for moznetwork 02:47:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:47:35 INFO - Running setup.py install for mozprocess 02:47:36 INFO - Running setup.py install for mozprofile 02:47:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:47:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:47:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:47:36 INFO - Running setup.py install for mozrunner 02:47:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:47:36 INFO - Running setup.py install for mozscreenshot 02:47:36 INFO - Running setup.py install for moztest 02:47:36 INFO - Running setup.py install for mozversion 02:47:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:47:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:47:36 INFO - Cleaning up... 02:47:36 INFO - Return code: 0 02:47:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:47:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:36 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 0x106aa0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ab0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feff0dab700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10693fe00>, '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 0x1067a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10693ab20>, '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.BUMwjIKqyq/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.rx5SGQMzif/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:47:36 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:47:36 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:47:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BUMwjIKqyq/Render', 02:47:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:47:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:47:36 INFO - 'HOME': '/Users/cltbld', 02:47:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:47:36 INFO - 'LOGNAME': 'cltbld', 02:47:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:36 INFO - 'MOZ_NO_REMOTE': '1', 02:47:36 INFO - 'NO_EM_RESTART': '1', 02:47:36 INFO - 'PAGER': '/bin/cat', 02:47:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:47:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:36 INFO - 'PWD': '/builds/slave/test', 02:47:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:47:36 INFO - 'SHELL': '/bin/bash', 02:47:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rx5SGQMzif/Listeners', 02:47:36 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 02:47:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:47:36 INFO - 'USER': 'cltbld', 02:47:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:47:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:47:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:36 INFO - 'XPC_FLAGS': '0x0', 02:47:36 INFO - 'XPC_SERVICE_NAME': '0', 02:47:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:47:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:47:37 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-6zdlGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:47:37 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:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:47:37 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-llaOx0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:47:37 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:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:47:37 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-eXVYS4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:47:37 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:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:47:37 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-3FkuOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:47:37 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:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:47:37 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-fwTX8b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:47:37 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:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:47:37 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-Vh0ccO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:47:37 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:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:47:37 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-9pRhEB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:47:37 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:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:47:37 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-GzVCAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:47:37 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:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:47:37 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-3WJ3Q3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:47:38 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:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:47:38 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-NfBl_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:47:38 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:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:47:38 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-NyTVfo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:47:38 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:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:47:38 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-xW1fnI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:47:38 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:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:47:38 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-nPPqCi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:47:38 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:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:47:38 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-3s9Faa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:47:38 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:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:47:38 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-Y4SBfb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:47:38 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:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:47:38 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-4b7JL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:47:38 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:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:47:38 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-Kr2NFH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:47:38 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:47:38 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:47:38 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:47:38 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:47:38 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:47:38 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:47:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:47:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:41 INFO - Downloading blessings-1.6.tar.gz 02:47:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:47:41 INFO - Installing collected packages: blessings 02:47:41 INFO - Running setup.py install for blessings 02:47:41 INFO - Successfully installed blessings 02:47:41 INFO - Cleaning up... 02:47:41 INFO - Return code: 0 02:47:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:47:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:47:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:47:41 INFO - Reading from file tmpfile_stdout 02:47:41 INFO - Current package versions: 02:47:41 INFO - blessings == 1.6 02:47:41 INFO - blobuploader == 1.2.4 02:47:41 INFO - docopt == 0.6.1 02:47:41 INFO - manifestparser == 1.1 02:47:41 INFO - mozInstall == 1.12 02:47:41 INFO - mozcrash == 0.17 02:47:41 INFO - mozdebug == 0.1 02:47:41 INFO - mozdevice == 0.48 02:47:41 INFO - mozfile == 1.2 02:47:41 INFO - mozhttpd == 0.7 02:47:41 INFO - mozinfo == 0.9 02:47:41 INFO - mozleak == 0.1 02:47:41 INFO - mozlog == 3.1 02:47:41 INFO - moznetwork == 0.27 02:47:41 INFO - mozprocess == 0.22 02:47:41 INFO - mozprofile == 0.28 02:47:41 INFO - mozrunner == 6.11 02:47:41 INFO - mozscreenshot == 0.1 02:47:41 INFO - mozsystemmonitor == 0.0 02:47:41 INFO - moztest == 0.7 02:47:41 INFO - mozversion == 1.4 02:47:41 INFO - psutil == 3.1.1 02:47:41 INFO - requests == 1.2.3 02:47:41 INFO - wsgiref == 0.1.2 02:47:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:47:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:41 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 0x106aa0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ab0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feff0dab700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10693fe00>, '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 0x1067a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10693ab20>, '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.BUMwjIKqyq/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.rx5SGQMzif/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:47:41 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:47:41 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:47:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BUMwjIKqyq/Render', 02:47:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:47:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:47:41 INFO - 'HOME': '/Users/cltbld', 02:47:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:47:41 INFO - 'LOGNAME': 'cltbld', 02:47:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:41 INFO - 'MOZ_NO_REMOTE': '1', 02:47:41 INFO - 'NO_EM_RESTART': '1', 02:47:41 INFO - 'PAGER': '/bin/cat', 02:47:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:47:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:41 INFO - 'PWD': '/builds/slave/test', 02:47:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:47:41 INFO - 'SHELL': '/bin/bash', 02:47:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rx5SGQMzif/Listeners', 02:47:41 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 02:47:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:47:41 INFO - 'USER': 'cltbld', 02:47:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:47:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:47:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:41 INFO - 'XPC_FLAGS': '0x0', 02:47:41 INFO - 'XPC_SERVICE_NAME': '0', 02:47:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:47:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:42 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:47:42 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:47:42 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:47:42 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:47:42 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:47:42 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:47:42 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:47:42 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:47:42 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:47:42 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:47:42 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:47:42 INFO - Cleaning up... 02:47:42 INFO - Return code: 0 02:47:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:47: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:47:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47: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:47:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:42 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 0x106aa0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ab0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feff0dab700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10693fe00>, '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 0x1067a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10693ab20>, '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.BUMwjIKqyq/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.rx5SGQMzif/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:47:42 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:47:42 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:47:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BUMwjIKqyq/Render', 02:47:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:47:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:47:42 INFO - 'HOME': '/Users/cltbld', 02:47:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:47:42 INFO - 'LOGNAME': 'cltbld', 02:47:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:42 INFO - 'MOZ_NO_REMOTE': '1', 02:47:42 INFO - 'NO_EM_RESTART': '1', 02:47:42 INFO - 'PAGER': '/bin/cat', 02:47:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:47:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:42 INFO - 'PWD': '/builds/slave/test', 02:47:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:47:42 INFO - 'SHELL': '/bin/bash', 02:47:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rx5SGQMzif/Listeners', 02:47:42 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 02:47:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:47:42 INFO - 'USER': 'cltbld', 02:47:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:47:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:47:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:42 INFO - 'XPC_FLAGS': '0x0', 02:47:42 INFO - 'XPC_SERVICE_NAME': '0', 02:47:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:47:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:42 INFO - Downloading/unpacking jsonschema 02:47:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:47:44 INFO - Downloading/unpacking functools32 (from jsonschema) 02:47:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:45 INFO - Downloading functools32-3.2.3-2.tar.gz 02:47:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:47:45 INFO - warning: no files found matching '*.txt' 02:47:45 INFO - no previously-included directories found matching 'build' 02:47:45 INFO - no previously-included directories found matching 'dist' 02:47:45 INFO - no previously-included directories found matching '.git*' 02:47:45 INFO - Installing collected packages: jsonschema, functools32 02:47:45 INFO - Running setup.py install for functools32 02:47:45 INFO - warning: no files found matching '*.txt' 02:47:45 INFO - no previously-included directories found matching 'build' 02:47:45 INFO - no previously-included directories found matching 'dist' 02:47:45 INFO - no previously-included directories found matching '.git*' 02:47:45 INFO - Successfully installed jsonschema functools32 02:47:45 INFO - Cleaning up... 02:47:45 INFO - Return code: 0 02:47:45 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:47:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:45 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 0x106aa0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106ab0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feff0dab700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10693fe00>, '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 0x1067a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10693ab20>, '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.BUMwjIKqyq/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.rx5SGQMzif/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:47:45 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:47:45 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:47:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BUMwjIKqyq/Render', 02:47:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:47:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:47:45 INFO - 'HOME': '/Users/cltbld', 02:47:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:47:45 INFO - 'LOGNAME': 'cltbld', 02:47:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:45 INFO - 'MOZ_NO_REMOTE': '1', 02:47:45 INFO - 'NO_EM_RESTART': '1', 02:47:45 INFO - 'PAGER': '/bin/cat', 02:47:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:47:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:45 INFO - 'PWD': '/builds/slave/test', 02:47:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:47:45 INFO - 'SHELL': '/bin/bash', 02:47:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rx5SGQMzif/Listeners', 02:47:45 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 02:47:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:47:45 INFO - 'USER': 'cltbld', 02:47:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:47:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:47:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:45 INFO - 'XPC_FLAGS': '0x0', 02:47:45 INFO - 'XPC_SERVICE_NAME': '0', 02:47:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:47:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:45 INFO - Downloading/unpacking flake8 02:47:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:48 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:47:48 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:47:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:48 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:47:48 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:47:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:49 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:47:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:47:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:47:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:47:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:47:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:47:49 INFO - no previously-included directories found matching 'docs/_build' 02:47:49 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:47:49 INFO - Running setup.py install for pep8 02:47:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:47:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:47:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:47:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:47:49 INFO - no previously-included directories found matching 'docs/_build' 02:47:50 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:47:50 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:47:50 INFO - Cleaning up... 02:47:50 INFO - Return code: 0 02:47:50 INFO - Running post-action listener: _resource_record_post_action 02:47:50 INFO - Running post-action listener: _start_resource_monitoring 02:47:50 INFO - Starting resource monitoring. 02:47:50 INFO - ##### 02:47:50 INFO - ##### Running install step. 02:47:50 INFO - ##### 02:47:50 INFO - Running pre-action listener: _resource_record_pre_action 02:47:50 INFO - Running main action method: install 02:47:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:47:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:47:50 INFO - Reading from file tmpfile_stdout 02:47:50 INFO - Detecting whether we're running mozinstall >=1.0... 02:47:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:47:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:47:50 INFO - Reading from file tmpfile_stdout 02:47:50 INFO - Output received: 02:47:50 INFO - Usage: mozinstall [options] installer 02:47:50 INFO - Options: 02:47:50 INFO - -h, --help show this help message and exit 02:47:50 INFO - -d DEST, --destination=DEST 02:47:50 INFO - Directory to install application into. [default: 02:47:50 INFO - "/builds/slave/test"] 02:47:50 INFO - --app=APP Application being installed. [default: firefox] 02:47:50 INFO - mkdir: /builds/slave/test/build/application 02:47:50 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:47:50 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:48:22 INFO - Reading from file tmpfile_stdout 02:48:22 INFO - Output received: 02:48:22 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:48:22 INFO - Running post-action listener: _resource_record_post_action 02:48:22 INFO - ##### 02:48:22 INFO - ##### Running run-tests step. 02:48:22 INFO - ##### 02:48:22 INFO - Running pre-action listener: _resource_record_pre_action 02:48:22 INFO - Running main action method: run_tests 02:48:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:48:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:48:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:48:22 INFO - 2016-04-26 02:48:22.272 screenresolution[1647:7425] starting screenresolution argv=screenresolution get 02:48:22 INFO - 2016-04-26 02:48:22.274 screenresolution[1647:7425] Display 0: 1600x1200x32@60 02:48:22 INFO - 2016-04-26 02:48:22.279 screenresolution[1648:7427] starting screenresolution argv=screenresolution list 02:48:22 INFO - Available Modes on Display 0 02:48:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:48:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:48:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:48:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:48:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:48:22 INFO - Intel Iris: 02:48:22 INFO - Chipset Model: Intel Iris 02:48:22 INFO - Type: GPU 02:48:22 INFO - Bus: Built-In 02:48:22 INFO - VRAM (Dynamic, Max): 1536 MB 02:48:22 INFO - Vendor: Intel (0x8086) 02:48:22 INFO - Device ID: 0x0a2e 02:48:22 INFO - Revision ID: 0x0009 02:48:22 INFO - Displays: 02:48:22 INFO - Display: 02:48:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:48:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:48:22 INFO - Display Serial Number: 1600x1200 60 02:48:22 INFO - Main Display: Yes 02:48:22 INFO - Mirror: Off 02:48:22 INFO - Online: Yes 02:48:22 INFO - Rotation: Supported 02:48:22 INFO - Return code: 0 02:48:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:48:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:48:22 INFO - Python 2.7.3 02:48:22 INFO - Return code: 0 02:48:22 INFO - grabbing minidump binary from tooltool 02:48:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:48:22 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 0x10693fe00>, '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 0x1067a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10693ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:48:22 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:48:22 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:48:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:48:22 INFO - Return code: 0 02:48:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:48:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:48:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:48:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:48:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:48:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:48:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:48:23 INFO - Return code: 0 02:48:23 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/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0056', '--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:48:23 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/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0056 --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:48:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BUMwjIKqyq/Render', 02:48:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:23 INFO - 'HOME': '/Users/cltbld', 02:48:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:23 INFO - 'LOGNAME': 'cltbld', 02:48:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:48:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:48:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:23 INFO - 'MOZ_NO_REMOTE': '1', 02:48:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:48:23 INFO - 'NO_EM_RESTART': '1', 02:48:23 INFO - 'PAGER': '/bin/cat', 02:48:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:23 INFO - 'PWD': '/builds/slave/test', 02:48:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:48:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:23 INFO - 'SHELL': '/bin/bash', 02:48:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rx5SGQMzif/Listeners', 02:48:23 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 02:48:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:23 INFO - 'USER': 'cltbld', 02:48:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:23 INFO - 'XPC_FLAGS': '0x0', 02:48:23 INFO - 'XPC_SERVICE_NAME': '0', 02:48:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:23 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/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0056', '--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:48:23 INFO - mozversion application_buildid: 20160426004107 02:48:23 INFO - mozversion application_changeset: ca6ab5be342e2d7817b0015447872dab3ecd5ef1 02:48:23 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:48:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:48:23 INFO - mozversion application_name: Firefox 02:48:23 INFO - mozversion application_remotingname: firefox-dev 02:48:23 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:48:23 INFO - mozversion application_vendor: Mozilla 02:48:23 INFO - mozversion application_version: 48.0a2 02:48:23 INFO - mozversion platform_buildid: 20160426004107 02:48:23 INFO - mozversion platform_changeset: ca6ab5be342e2d7817b0015447872dab3ecd5ef1 02:48:23 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:48:23 INFO - mozversion platform_version: 48.0a2 02:48:23 INFO - using testdate: 1461664103 02:48:23 INFO - actual date: 1461664103 02:48:23 INFO - starting webserver on 'localhost:49280' 02:48:23 INFO - SUITE-START | Running 2 tests 02:48:23 INFO - TEST-START | dromaeo_css 02:48:23 INFO - operating with platform_type : mac_ 02:48:23 INFO - Initialising browser for dromaeo_css test... 02:48:24 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/tmpnkOz3N/profile http://localhost:49280/getInfo.html) 02:48:26 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 02:48:26 INFO - PROCESS | 1666 | colorDepth:24 02:48:26 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 02:48:26 INFO - PROCESS | 1666 | __metrics 02:48:26 INFO - PROCESS | 1666 | 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:48:26 ERROR - PROCESS | 1666 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:48:27 INFO - TEST-INFO | 1666: exit 0 02:48:27 INFO - Browser initialized. 02:48:27 INFO - Running cycle 1/1 for dromaeo_css test... 02:48:27 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/tmpnkOz3N/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:48:39 INFO - PROCESS | 1669 | RSS: Main: 211570688 02:48:39 INFO - PROCESS | 1669 | 02:49:30 INFO - PROCESS | 1669 | 1461664170929 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:51:41 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49280/tests/dromaeo/cssquery-ext.html) 02:51:42 INFO - PROCESS | 1669 | RSS: Main: 319766528 02:51:42 INFO - PROCESS | 1669 | 02:54:45 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-ext.html (next: http://localhost:49280/tests/dromaeo/cssquery-jquery.html) 02:54:46 INFO - PROCESS | 1669 | RSS: Main: 359059456 02:54:46 INFO - PROCESS | 1669 | 02:57:48 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49280/tests/dromaeo/cssquery-mootools.html) 02:57:49 INFO - PROCESS | 1669 | RSS: Main: 361771008 02:57:49 INFO - PROCESS | 1669 | 03:00:51 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49280/tests/dromaeo/cssquery-prototype.html) 03:00:51 INFO - PROCESS | 1669 | RSS: Main: 351580160 03:00:51 INFO - PROCESS | 1669 | 03:03:55 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49280/tests/dromaeo/cssquery-yui.html) 03:03:55 INFO - PROCESS | 1669 | RSS: Main: 327217152 03:03:55 INFO - PROCESS | 1669 | 03:06:58 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-yui.html (next: http://localhost:49280/tests/dromaeo/cssquery-dojo.html) 03:06:58 INFO - PROCESS | 1669 | RSS: Main: 375042048 03:06:58 INFO - PROCESS | 1669 | 03:06:58 INFO - PROCESS | 1669 | __start_tp_report 03:06:58 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 03:06:58 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 03:06:58 INFO - PROCESS | 1669 | |i|pagename|runs| 03:06:58 INFO - PROCESS | 1669 | |0;dojo.html;2869.2826793301674;3361.1933136047346;3450.2064525159203;3461.5499985001948;3467.7399195060375;5644.915326270104;5659.801906933259;5664.971675141629;5673.092305231164;5680.147977803332;5633.690147041911;5669.979403707331;5679.545636349092;5692.089265717486;5785.479306862381;5071.568916642072;5129.820456284021;5136.43499215086;5159.664621799589;5208.505192006773;5208.348956380452;5222.321098257221;5224.1119009768245;5227.686338819683;5231.65994210377;5142.537171654551;5174.948250517485;5209.244659524366;5212.661177023481;5232.241325007872;5203.297554830087;5242.344706911636;5253.711045892474;5261.710605916673;5310.867228319285;5023.673461225008;5054.898902021976;5074.06129865976;5150;5176.171812510018;4591.83928562502;5453.372862120827;5464.6994415307145;5537.723113844291;5557.916631250535;5091.541761241506;6154.384561543855;6162.7226774795245;6163.599366041193;6164.476019538344;5538.141588053858;6108.267007959029;6134.344744984521;6256;6265.8120256392385;5780.075187969905;5783.652980821122;5784.1034639630925;5785;5812.128180772918;6321.3678632137335;6323.083152942798;6326.367363263636;6328.208973878265;6348.079528468346;5525.502704756591;5915.1718759373725;6021.2473440819895;6068.271807383142;6131.938680613226;6106.369660900722;6242.75028998844;6256.280527739368;6260.718267677966;6264.123022776815;6113.84715382119;6148.508119350441;6191.133241346215;6193.349698281616;6195.566310358232;5977.6114552553945;6111.755529778759;6221.5333849961435;6232.283287421914;6245;4634.096351211549;4855.271709243641;4867.683600565952;4868.537488938545;4873.293372460973;4411.801468933906;4435.246008178618;4438.001449673862;4471.1728330259;4471.709338892962;5588.91616625751;5699.629524080921;5704.8859022819315;5713.685747283939;5713.94292055971;5621.8594535136945;5753.539716822645;6265.467435268053;6266;6279.905801412982;5661.830145095653;5667.773289068474;5672.716364181774;5680.602357834994;5683;5253.23828044939;5341.332333458317;5345.33183352081;5422.105352616774;5445.401005889349;6282.3089460158435;6315.40003699657;6317.526185536103;6325.14610527582;6343.841403964845;6302.936970630235;6314.494840412848;6333.2716737575765;6335.208098987627;6339.619622822645;6229.3770622937345;6244;6285.842853928689;6306.65313407767;6321.96839015802;6175.9073613894925;6179.69101544912;6181.81454556364;6181.9490686582485;6184.226971628546;5138.845834624967;6138.14066030765;6266.874662506815;6286.748530058747;6299.842503937255;5875.529957603296;6027.57806953509;6115.877682446415;6118.571699980958;6142.078688196806;4698.600618947427;6012.068129439944;6059;6064.151018857276;6081.179040829608;5695.288088988877;5825.912611310919;5886.58793884424;5894.587378883437;5895.587308888336;5589.385167631476;6111.449969502766;6113.969430152821;6115.143879856734;6140.600860944024;4960.751962401938;5922.289325280994;5946.464818166212;5967.1944287522365;5968.880622387441 03:06:58 INFO - PROCESS | 1669 | |1;ext.html;490.5560467776661;522.9686218826869;541.0801637216731;554.437246195112;562.2072877243087;28020.57969130462;30954.690453095463;38323.425148622795;39008;39177.21645567087;43476.78261608695;44269;44273.55726442735;44368.778156109176;44371.33442998357;8477.406581539295;9466.100720431565;9708.708738737849;9713.660021899235;9725.367851089692;7336.596487193202;8678.392512524126;8679.566021698922;8684.652613895436;11189.664310070712;11388.316700997966;11488.195826292163;11500.712482187928;11503.482343294572;11510.194286399956;10024.59901603935;11331.716707082307;11424.485898134604;11585.710357241052;11587;8368.372372072057;11074.889251107466;11149.554017839277;11402.543898244061;11413.029892459152;38706.41940370897;39342.60657393447;39385.80307098446;39431.01422464433;39556.615518457;3205.951910721341;3208.3904058229095;3262.2822978944596;3321.618013928381;3332.8333583320737;26586.46827063448;27322.45355092887;28016.579751303543;28066.719332806613;28946.42107157845;2688.526555568881;2729.7952653551065;2739.1097893184797;2742.5474796658527;2771.3764403009163;475142.99999999657;478618;480662;481644;481647.55057348334;61540.69229653823;61911.69044154751;61965;62053;62061.448463788765;65230.02154967679;65321.673391632736;65371.69256614841;65420.67289663521;65455.34544654588;16919.577010574587;17524.386646467312;17533.29866805339;17569;17610.031448270212;13457.596272111852;13545.729085418236;13572.049956503148;13596.932015340059;13608.795868061987;14044.71910561783;14292.499762508261;14658.047226930215;15835.891487595989;16219.189040547926;6977.860442791116;7380.520266182681;7399.186089530147;7407.074115735533;7463.365613922769;856.3919617071824;856.9459564735286;942.1614762861059;1017.5370206556147;1098.906592939593;27997.44005119886;28686.139415817557;29038.854805725838;29613;29700.40599188004;1168.5617893290016;1185.8339832423467;1192.773373059116;1221.4930803716359;1232.2236990695803;15021.624459388384;15051.247437628075;15133.167675777939;15217.9239103806;15486.767698484531;632703;638139;639975;641127;641979;58905;60474;60501;60539.091913621334;60724.69637651872;1484.3617244584932;1486.4648726458684;1496.2219645784348;1498.1385703220474;1587.2222610920796;28272;28641.28396790097;29008.09555934783;29307.12078637559;29324.706752933052;50696.23955640742;51099.23351149661;51198.74400627973;51217;51319.74340128276;564218;578282.1085894543;578897;579778;581830;24620.6306905393;30464.78140874429;35423.82288088543;39778.60221397749;40199.59800402076;39529.209415812096;39899.698796684665;42031.7898410506;42755.786221068694;42986.140277193655;34610.826945866116;34706.652933470345;35415.46876796902;35540.933771986885;36249.09377265589;35039.299214016086;35263;35399.115022123624;35854.10364740902;37235.44146837745;36043.81978090093;36367.90896273116;36681.63318366889;36685.81657091698;36863.81568092143;29630.259243519085;31501.842490788316;31929.042128736175;31938.6806131945;32070.518942215418;23411.180608678613;23997.52004959926;24198.395040124138;24252;24565.263042108767 03:06:58 INFO - PROCESS | 1669 | |2;jquery.html;373.32241980804855;398.5217738713544;401.7609522334212;403.4472772301945;417.21771678103556;14802.407903683854;19454;20658.586828263426;20711.55019148662;20715.896420517875;17233.56916077099;19928.202871885107;20172.59654806903;20198.091085901135;20411;4166.9791651041705;5741.655500669952;5923.437273459014;5971.4625683688455;5986.431289027535;3934.4688467056867;4429.60133587977;4429.6899217054815;4436.645068394537;4438.800253988562;5707.771689132451;6176.536759743015;6512.902306465407;6514.609123452585;6529;5998.520118390518;6008.309044459856;6042.395760423967;6050.485708714765;6089.269287685506;5679.290088738908;5691.345495268056;5783.450572195677;5820.214271073393;5827.388124246935;12821.67945801353;13143.802842957364;13308.268045257508;13310;13465.394057267447;2091.184438069154;2099.071392314346;2102.0750869617473;2345.5074434368803;2583.3929026678716;9690.012847923252;9709.029097090235;9778.853317200248;10107.545160467796;10140.949295253551;1658.1294820219362;1731.117130263562;1744.2761254079542;1744.459217642535;1891.9527011824678;123068.38465807613;126760;128896;129708.3514582421;131531;9996.300258981946;11610.129240307011;11651.883481165249;11700.94149529264;11816.113791465674;13334.399952002183;14383.208923509308;15170.393184272727;15265.923670381577;15272.389104435699;7692.461527693008;8505.02192247887;8815.647374104963;8831.425957312755;8968.10318968098;8771.16673915977;8777.868331975025;8787.428817126865;8880.067592902653;8905.599248033725;4016.5180178378782;4511.616512596466;4533.954660453419;4595.356650068993;4598.632109431237;1669.9248533815764;1922.1350392323511;1964.557974455736;1987.5826076523804;2016.4757163137397;716.4985537887834;895.2658819767859;923.6951805904035;977.2231076294363;1004;19243.230270789292;20021.29925452631;20153.39539813808;20277.69583456249;20314.085866136058;1003.2826529031777;1036.429963520061;1049.443794788748;1057.5822550092823;1064.914806815453;10268.281220314506;10716.089132423831;10723.981221783917;10741.355518668905;10770.78458430827;611953;613715;619254;622617;628976;67560;130468;145692;146421;147101;1011.5043628622069;1045.6915210013065;1061.4480470155631;1061.8407238914224;1065.2862582070031;14205.076670016417;17567.385141520495;17949.192286346886;18247.996360199784;18553.81446185575;38956.80521597374;51540.74229628828;52989;54323;54826.72586637041;76719.84920226313;78475.21524784908;79040;80046;80917.19082809097;7764.107127680389;7835.902973583817;7988.440809143304;8210.794730131556;8268.586570671563;7925.28672419485;9940.602375904882;12753.48986040585;13071.215727056408;13246.403911824184;9467.857982130135;10301.484925753832;10730.463476826284;11189.832152517556;11599.188056835941;10034.548445320124;10884.45577721095;10997.945010274898;11053.557857685924;11368.602098926498;10155.39067655943;11172.664820055412;11237.831432528683;11573.942130289295;11736.647900562997;8112.797180070545;8220.712275070344;8253.422260441712;8484.406091573777;8688.044315125457;10696.89303106959;11020.338779673246;11211.663650090184;11323.377214253294;11499.022583080532 03:06:58 INFO - PROCESS | 1669 | |3;mootools.html;1410.9294535273234;1587.2381256996637;1610.7020201262765;1811.9184636691348;1929.9324523641676;7936.722214722486;8843.336749743763;9036.638534458614;9037.18665320121;9043.954780226106;8638.827223455528;8882.822343553125;8902.020777714462;8904.332175086862;8914.242289405409;2163.8593491423085;2409.650600662903;2444.181199298237;2484.5155194736944;2498.475320182764;2101.2855629086107;2494.064725727852;2517.5216708825355;2530.8861101250486;2532.468181681849;2280.5974325789684;2283.9885800570974;2431.513697260546;2475.0718480569785;2483.6026235802274;2132.1897678882083;2205.922792702264;2211.977880221193;2215.4682876109778;2440.4996975620043;2103.1797598936428;2391.234804862445;2476.7894728948063;2487.2413913756295;2494.738052504479;8775.034746177915;8829.20537151653;8832.735017949472;8833.734987950373;8835.028146903835;4280.015596412843;5185.714785686786;5456.61803673743;5465.699386533739;5468.26178465906;3484.1463841359027;3573.910652233689;3649.033006253345;3661.6155303693263;3691.243295124493;1031.3038698878227;1050.5902697947806;1052.5631862776938;1054.0671505717419;1072.1476426241156;9551.761205969837;12161.148719589632;12177.878221217763;12181.269123852597;12188.329641869605;4796.880077998008;4850.490698476679;4926.802927882916;4958.355413796184;4971.477994810565;10252.538635761408;10270;10293.588256469808;10294.536745846455;10308.381497110197;5386.326709161355;5481.972590137024;5529.474699903502;5531.778728850801;5533.08704063833;4425.690201685851;4582.991741816795;4595.793189306489;4816.735079570657;4987.526185012418;3955.861544845917;3987.7009224308295;4037.515498140242;4081.571434999342;4116.1767646470225;1115.9497822598003;1342.214804339453;1346.6094832498466;1348;1353.7157196988546;809.9018964041967;837.9539125348042;872.2019352292581;906.3066753933247;944.8015916657441;4715.3869996900185;5782.855428614234;6044.274687037584;6054.485368743706;6080.209572755513;926.1664501948301;941.7551436626527;943.8489841625307;950.4439902656885;954.5418199264392;3826.6556009959236;3834.69322454203;3938.15329704115;3964.682825373963;3996.980015099906;10287.639932602324;10848.023677868872;10897.073748731287;10900.672979810619;10910.563577456813;10998.06516446111;11085.27945683528;11116.221864469571;11128.49921753523;11128.66614001565;8039.5176289422825;8268.751937441886;8424.578771061422;8475.533845638549;8486.957565212135;4911.58251548608;5783.624064435799;5784.5083167929515;5875.823725288248;5910.3271039697975;10052.899471005196;10286.742831429274;10517.631882884063;10530.947345263225;10539.94730026345;10195.031472010147;10286.228532859916;10512.684619461428;10687.572497100029;10694.518746656575;7072.469564782765;7978.720744773905;8393.790155246168;8415.831683366176;8436.95781521113;8112;8131.634076466691;8199.97500312461;8202.220789025034;8210.917890821016;7326.523775954546;7436.516626419266;7492.662830172546;7511.3615342696485;7512.399008079231;7282.053333066668;7388.556686598821;7400.296971787671;7542.321191092608;7624.580648064188;7092.04257425241;7181.605011724405;7184.640767961477;7200.099987501562;7218.061651985208;4524.389207456951;4639.884002899955;4690.460597031385;4777.880552986203;4961.08219979305;4878.195097808822;4969;5055.6713813602;5137.383513978347;5202.661826981234 03:06:58 INFO - PROCESS | 1669 | |4;prototype.html;311.97348225400856;320.9566708494353;326.8398484742476;327.18530858163183;328.44492807155905;5073.23901414788;5260.947390526094;5282.788688452462;5299.867503312409;5302.443243459435;3632.582253040901;4969.850904472863;4973.278874563186;5192.221166824968;5277.736113194334;3394.3550725362225;4021.919561608766;4067.227226826908;4079.286124928126;4290.377895205193;4052.257529262416;4089.6484521400166;4099.795010249491;4109.506859176903;4115.238680844039;3234.8867789627366;3846.4038074098557;3880.8745463815458;3965.9008524786823;4114.280000999828;3181.552393660879;3677.9264414711824;3893.8831835044994;3994.9800250998705;4049.777262250575;3542.822858857073;3547.94678079829;3561.1809283864595;3564.198055437532;3568.3220188164423;4602.930956035662;4924;5106.259592359087;5184.326037615124;5186.325777648882;3917.4319723640197;4090.550039495653;4132.904780233241;4295;4681.414823147106;4451.888702782424;5182.274481572583;5540.814031931655;5602.187682786014;5625.831225063213;3824.9426258606145;3935.862244821446;3952.4268980997595;3959.425883246942;3962.9009274768073;4901.828436004723;5499.432661691399;5828.417158284137;5840.211571437844;5842.231760514487;3386.7967921924765;3953.8418463261146;4002.53843886008;4002.639762421396;4010.5588385277597;3620.968024113115;3679.5584529856587;3816.5610954740005;4067.7355971861734;4069.9389509157386;4267.423897773823;4348.847790327323;4386.846460373871;4626.699264547794;4682.882927926829;4305.784710764449;5588.776448942078;5603.579731520153;5645;5652.399896047864;1308.8822006019504;1413.2015411292791;1453.8255409350945;1482.7182835261267;1511.214168632305;805.7784109369853;995.9438987440803;1010.5351538292321;1017.2269075502671;1021.9795604087876;5225.242339860699;5585.184814935163;5645.491905728504;5699.74351154199;5743.6266642668925;4896.387951506062;4992.525710057539;6204.821083994027;6331.620102793755;6341.302456729756;4284.935725964113;4407;5070.706969722697;5673.6028478006965;5691.321060287225;3313.094970392034;3315.8507867146036;3331.5502407175204;3336.249343897652;3336.332733453319;6384.585001974857;6595;6596.175478065242;6599.934000660028;6609.900851487231;5501.284832971768;6414.454771344394;6458.321876202931;6526.510511711641;6566.080748695186;5197.480251974772;6168.444839964335;6559.704813283413;6580.638064906476;6592.30780768022;6020.8795824084145;6262.248530176408;6311.400416960381;6317.178766760292;6326.398992095744;4948.703077815343;5262.394824595221;5269.262303277467;6000.909986350121;6198.132261483306;5680.943190568207;6360.823247699191;6558.967205163944;6571.441427478718;6793.626350550767;3481.6170221275133;4101.856435024879;4111.60939710727;4118.876433706993;4162.209180255742;5483.122700367922;5496.917546236889;6296.937030629635;6334.4932405406535;6345.397187267202;4851.126797176542;5204.401493828258;5492.588055895744;5617.382087970238;5663.150527420919;4879.926801097916;5563.525665698674;5591.776328946797;5606.159076138611;5612.691301978431;4566.497685254686;5294.996228564124;5466.098093814476;5472;5479.671219726829;5625.240592519957;5635.774569017193;5638.351589567253;5645.774169033357;5669.064604340238;4599.609033232213;4925.679830810986;5484.2047903053035;5523.723813809213;5541.501264886179 03:06:58 INFO - PROCESS | 1669 | |5;yui.html;632.4845251120337;642.6658137768361;676.2628734679199;706.8091615263879;739.9297066778654;14281.357338919748;14393.447980470686;14693;14742.33659485323;14893.851061489382;13983.720325593507;14319.78520322196;14359.138451692907;14365.92817035916;14377.92811035946;725.2783480436973;748.3040772081962;749.4828568287891;900.4867225681348;922.4880191493718;747.4095464582973;753.3408267765705;753.3822265742093;886.162576365335;942.2556180617304;937.5468024561936;979.3095867413457;983.0071627656083;983.4787562591838;986.309583291695;973.3770386952356;977.9511024448784;978.0903759503659;978.3738407419257;979.1921664626676;567.8892615939893;579.8144593730008;582.5921854701726;583.9737211825478;729.0376702752369;14633.926830365886;15113.54659360221;15146.16696081715;15152.242387880562;15160.469383571632;12363.62909112719;12450.688732781753;12473.126881118325;12563;12772.105952583323;9930.354526955725;10076.345037572535;10233;10425.270231083827;10455.58177672892;3478.9652103479148;3481.651834816523;3485.372632926072;3531.8410671519587;3732.5894151643297;75563.11092222683;77228;77327.6133619334;77459;77583.22416775816;9563.187129093965;9765.218782497383;9909.306348555676;10027.279367555891;10148.238882083873;21840.781592183874;22023.559528809656;22261.777382226293;22285.331440056823;22294.777052229594;7657.1959944205055;7755.069391672922;7803;7827.921720782833;7949.483283586664;4134.793260336971;4195.8741237762915;4214.30463973441;4336.6530677545725;4340.023494713664;3546.911327216789;3576.141725985745;3608.9819550902075;3609.296187243462;3610.6208848070573;638.7349250061266;650.9381608747161;653.3564439027554;653.4902775834856;673.5285300289817;546.2407253917058;559.2366419836893;562.190445758107;575.1803679756331;582.2983305117324;7721.227877212234;7742.14836367999;7829.412794040356;7919.960400197962;7940.444168908237;490.45069522135435;548.6927320700421;549.5027000564495;554.282204545107;562.2971285892634;3593.443016332473;3695.7228207883977;3695.8336874840697;3738.9439158412647;3877.7673339599714;79686.6015669918;80523;82571.3485730294;82638;90673.54663226641;75072;75302;75432.62283688546;77061.22938770652;77288.22711772923;68911;69313.96029059569;69740;69921.65039174771;70528.94206586905;6718;8168.83662326762;8205.712800052208;8312.418130731032;8383.832323353621;42565;42904;43969.680909572766;44204;44500;47110.76444617755;62129;62359.37640623717;62364.12907612779;62540.37459625528;10734.785304294026;10851.457427128455;11055.72360690989;11142.387168705798;11209.831852522055;11823.763524729286;11837.704057398634;12067.939660301643;12198.75602487963;12370.62888113358;11105.888941110485;11225.943870280596;11484.368359740294;11585.478653460783;11651.47568359409;11193.328400296008;11364.829527556927;11442.656720298404;11801.35092569917;12286.508539658313;10979.39613321243;11395.829062564235;11413.942930285295;11427.942860285646;11496.310221386744;9409.811803763749;9573.377730447499;9611.711648650551;9683.854742178732;9688.46713430768;9380.81450304967;9535.332526723063;9581.760455988657;9606.231501479726;9618.422894626345 03:06:58 INFO - PROCESS | 1669 | __end_tp_report 03:06:58 INFO - PROCESS | 1669 | __start_cc_report 03:06:58 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,1060 03:06:58 INFO - PROCESS | 1669 | __end_cc_report 03:06:58 INFO - PROCESS | 1669 | __startTimestamp1461665218900__endTimestamp 03:06:58 INFO - PROCESS | 1669 | 03:06:58 INFO - PROCESS | 1669 | ------- Summary: start ------- 03:06:58 INFO - PROCESS | 1669 | Number of tests: 6 03:06:58 INFO - PROCESS | 1669 | 03:06:58 INFO - PROCESS | 1669 | [#0] dojo.html Cycles:165 Average:5670.09 Median:5785.00 stddev:655.36 (11.3%) stddev-sans-first:619.45 03:06:58 INFO - PROCESS | 1669 | Values: 2869.3 3361.2 3450.2 3461.5 3467.7 5644.9 5659.8 5665.0 5673.1 5680.1 5633.7 5670.0 5679.5 5692.1 5785.5 5071.6 5129.8 5136.4 5159.7 5208.5 5208.3 5222.3 5224.1 5227.7 5231.7 5142.5 5174.9 5209.2 5212.7 5232.2 5203.3 5242.3 5253.7 5261.7 5310.9 5023.7 5054.9 5074.1 5150.0 5176.2 4591.8 5453.4 5464.7 5537.7 5557.9 5091.5 6154.4 6162.7 6163.6 6164.5 5538.1 6108.3 6134.3 6256.0 6265.8 5780.1 5783.7 5784.1 5785.0 5812.1 6321.4 6323.1 6326.4 6328.2 6348.1 5525.5 5915.2 6021.2 6068.3 6131.9 6106.4 6242.8 6256.3 6260.7 6264.1 6113.8 6148.5 6191.1 6193.3 6195.6 5977.6 6111.8 6221.5 6232.3 6245.0 4634.1 4855.3 4867.7 4868.5 4873.3 4411.8 4435.2 4438.0 4471.2 4471.7 5588.9 5699.6 5704.9 5713.7 5713.9 5621.9 5753.5 6265.5 6266.0 6279.9 5661.8 5667.8 5672.7 5680.6 5683.0 5253.2 5341.3 5345.3 5422.1 5445.4 6282.3 6315.4 6317.5 6325.1 6343.8 6302.9 6314.5 6333.3 6335.2 6339.6 6229.4 6244.0 6285.8 6306.7 6322.0 6175.9 6179.7 6181.8 6181.9 6184.2 5138.8 6138.1 6266.9 6286.7 6299.8 5875.5 6027.6 6115.9 6118.6 6142.1 4698.6 6012.1 6059.0 6064.2 6081.2 5695.3 5825.9 5886.6 5894.6 5895.6 5589.4 6111.4 6114.0 6115.1 6140.6 4960.8 5922.3 5946.5 5967.2 5968.9 03:06:58 INFO - PROCESS | 1669 | 03:06:58 INFO - PROCESS | 1669 | [#1] ext.html Cycles:180 Average:69632.90 Median:35407.29 stddev:152028.04 (429.4%) stddev-sans-first:152365.88 03:06:58 INFO - PROCESS | 1669 | Values: 490.6 523.0 541.1 554.4 562.2 28020.6 30954.7 38323.4 39008.0 39177.2 43476.8 44269.0 44273.6 44368.8 44371.3 8477.4 9466.1 9708.7 9713.7 9725.4 7336.6 8678.4 8679.6 8684.7 11189.7 11388.3 11488.2 11500.7 11503.5 11510.2 10024.6 11331.7 11424.5 11585.7 11587.0 8368.4 11074.9 11149.6 11402.5 11413.0 38706.4 39342.6 39385.8 39431.0 39556.6 3206.0 3208.4 3262.3 3321.6 3332.8 26586.5 27322.5 28016.6 28066.7 28946.4 2688.5 2729.8 2739.1 2742.5 2771.4 475143.0 478618.0 480662.0 481644.0 481647.6 61540.7 61911.7 61965.0 62053.0 62061.4 65230.0 65321.7 65371.7 65420.7 65455.3 16919.6 17524.4 17533.3 17569.0 17610.0 13457.6 13545.7 13572.0 13596.9 13608.8 14044.7 14292.5 14658.0 15835.9 16219.2 6977.9 7380.5 7399.2 7407.1 7463.4 856.4 856.9 942.2 1017.5 1098.9 27997.4 28686.1 29038.9 29613.0 29700.4 1168.6 1185.8 1192.8 1221.5 1232.2 15021.6 15051.2 15133.2 15217.9 15486.8 632703.0 638139.0 639975.0 641127.0 641979.0 58905.0 60474.0 60501.0 60539.1 60724.7 1484.4 1486.5 1496.2 1498.1 1587.2 28272.0 28641.3 29008.1 29307.1 29324.7 50696.2 51099.2 51198.7 51217.0 51319.7 564218.0 578282.1 578897.0 579778.0 581830.0 24620.6 30464.8 35423.8 39778.6 40199.6 39529.2 39899.7 42031.8 42755.8 42986.1 34610.8 34706.7 35415.5 35540.9 36249.1 35039.3 35263.0 35399.1 35854.1 37235.4 36043.8 36367.9 36681.6 36685.8 36863.8 29630.3 31501.8 31929.0 31938.7 32070.5 23411.2 23997.5 24198.4 24252.0 24565.3 03:06:58 INFO - PROCESS | 1669 | 03:06:58 INFO - PROCESS | 1669 | [#2] jquery.html Cycles:180 Average:35304.97 Median:20685.07 stddev:103621.44 (500.9%) stddev-sans-first:103878.93 03:06:58 INFO - PROCESS | 1669 | Values: 373.3 398.5 401.8 403.4 417.2 14802.4 19454.0 20658.6 20711.6 20715.9 17233.6 19928.2 20172.6 20198.1 20411.0 4167.0 5741.7 5923.4 5971.5 5986.4 3934.5 4429.6 4429.7 4436.6 4438.8 5707.8 6176.5 6512.9 6514.6 6529.0 5998.5 6008.3 6042.4 6050.5 6089.3 5679.3 5691.3 5783.5 5820.2 5827.4 12821.7 13143.8 13308.3 13310.0 13465.4 2091.2 2099.1 2102.1 2345.5 2583.4 9690.0 9709.0 9778.9 10107.5 10140.9 1658.1 1731.1 1744.3 1744.5 1892.0 123068.4 126760.0 128896.0 129708.4 131531.0 9996.3 11610.1 11651.9 11700.9 11816.1 13334.4 14383.2 15170.4 15265.9 15272.4 7692.5 8505.0 8815.6 8831.4 8968.1 8771.2 8777.9 8787.4 8880.1 8905.6 4016.5 4511.6 4534.0 4595.4 4598.6 1669.9 1922.1 1964.6 1987.6 2016.5 716.5 895.3 923.7 977.2 1004.0 19243.2 20021.3 20153.4 20277.7 20314.1 1003.3 1036.4 1049.4 1057.6 1064.9 10268.3 10716.1 10724.0 10741.4 10770.8 611953.0 613715.0 619254.0 622617.0 628976.0 67560.0 130468.0 145692.0 146421.0 147101.0 1011.5 1045.7 1061.4 1061.8 1065.3 14205.1 17567.4 17949.2 18248.0 18553.8 38956.8 51540.7 52989.0 54323.0 54826.7 76719.8 78475.2 79040.0 80046.0 80917.2 7764.1 7835.9 7988.4 8210.8 8268.6 7925.3 9940.6 12753.5 13071.2 13246.4 9467.9 10301.5 10730.5 11189.8 11599.2 10034.5 10884.5 10997.9 11053.6 11368.6 10155.4 11172.7 11237.8 11573.9 11736.6 8112.8 8220.7 8253.4 8484.4 8688.0 10696.9 11020.3 11211.7 11323.4 11499.0 03:06:58 INFO - PROCESS | 1669 | 03:06:58 INFO - PROCESS | 1669 | [#3] mootools.html Cycles:180 Average:5789.34 Median:4702.92 stddev:3286.16 (69.9%) stddev-sans-first:3278.90 03:06:58 INFO - PROCESS | 1669 | Values: 1410.9 1587.2 1610.7 1811.9 1929.9 7936.7 8843.3 9036.6 9037.2 9044.0 8638.8 8882.8 8902.0 8904.3 8914.2 2163.9 2409.7 2444.2 2484.5 2498.5 2101.3 2494.1 2517.5 2530.9 2532.5 2280.6 2284.0 2431.5 2475.1 2483.6 2132.2 2205.9 2212.0 2215.5 2440.5 2103.2 2391.2 2476.8 2487.2 2494.7 8775.0 8829.2 8832.7 8833.7 8835.0 4280.0 5185.7 5456.6 5465.7 5468.3 3484.1 3573.9 3649.0 3661.6 3691.2 1031.3 1050.6 1052.6 1054.1 1072.1 9551.8 12161.1 12177.9 12181.3 12188.3 4796.9 4850.5 4926.8 4958.4 4971.5 10252.5 10270.0 10293.6 10294.5 10308.4 5386.3 5482.0 5529.5 5531.8 5533.1 4425.7 4583.0 4595.8 4816.7 4987.5 3955.9 3987.7 4037.5 4081.6 4116.2 1115.9 1342.2 1346.6 1348.0 1353.7 809.9 838.0 872.2 906.3 944.8 4715.4 5782.9 6044.3 6054.5 6080.2 926.2 941.8 943.8 950.4 954.5 3826.7 3834.7 3938.2 3964.7 3997.0 10287.6 10848.0 10897.1 10900.7 10910.6 10998.1 11085.3 11116.2 11128.5 11128.7 8039.5 8268.8 8424.6 8475.5 8487.0 4911.6 5783.6 5784.5 5875.8 5910.3 10052.9 10286.7 10517.6 10530.9 10539.9 10195.0 10286.2 10512.7 10687.6 10694.5 7072.5 7978.7 8393.8 8415.8 8437.0 8112.0 8131.6 8200.0 8202.2 8210.9 7326.5 7436.5 7492.7 7511.4 7512.4 7282.1 7388.6 7400.3 7542.3 7624.6 7092.0 7181.6 7184.6 7200.1 7218.1 4524.4 4639.9 4690.5 4777.9 4961.1 4878.2 4969.0 5055.7 5137.4 5202.7 03:06:58 INFO - PROCESS | 1669 | 03:06:58 INFO - PROCESS | 1669 | [#4] prototype.html Cycles:180 Average:4649.34 Median:5031.62 stddev:1479.82 (29.4%) stddev-sans-first:1447.72 03:06:58 INFO - PROCESS | 1669 | Values: 312.0 321.0 326.8 327.2 328.4 5073.2 5260.9 5282.8 5299.9 5302.4 3632.6 4969.9 4973.3 5192.2 5277.7 3394.4 4021.9 4067.2 4079.3 4290.4 4052.3 4089.6 4099.8 4109.5 4115.2 3234.9 3846.4 3880.9 3965.9 4114.3 3181.6 3677.9 3893.9 3995.0 4049.8 3542.8 3547.9 3561.2 3564.2 3568.3 4602.9 4924.0 5106.3 5184.3 5186.3 3917.4 4090.6 4132.9 4295.0 4681.4 4451.9 5182.3 5540.8 5602.2 5625.8 3824.9 3935.9 3952.4 3959.4 3962.9 4901.8 5499.4 5828.4 5840.2 5842.2 3386.8 3953.8 4002.5 4002.6 4010.6 3621.0 3679.6 3816.6 4067.7 4069.9 4267.4 4348.8 4386.8 4626.7 4682.9 4305.8 5588.8 5603.6 5645.0 5652.4 1308.9 1413.2 1453.8 1482.7 1511.2 805.8 995.9 1010.5 1017.2 1022.0 5225.2 5585.2 5645.5 5699.7 5743.6 4896.4 4992.5 6204.8 6331.6 6341.3 4284.9 4407.0 5070.7 5673.6 5691.3 3313.1 3315.9 3331.6 3336.2 3336.3 6384.6 6595.0 6596.2 6599.9 6609.9 5501.3 6414.5 6458.3 6526.5 6566.1 5197.5 6168.4 6559.7 6580.6 6592.3 6020.9 6262.2 6311.4 6317.2 6326.4 4948.7 5262.4 5269.3 6000.9 6198.1 5680.9 6360.8 6559.0 6571.4 6793.6 3481.6 4101.9 4111.6 4118.9 4162.2 5483.1 5496.9 6296.9 6334.5 6345.4 4851.1 5204.4 5492.6 5617.4 5663.2 4879.9 5563.5 5591.8 5606.2 5612.7 4566.5 5295.0 5466.1 5472.0 5479.7 5625.2 5635.8 5638.4 5645.8 5669.1 4599.6 4925.7 5484.2 5523.7 5541.5 03:06:58 INFO - PROCESS | 1669 | 03:06:58 INFO - PROCESS | 1669 | [#5] yui.html Cycles:180 Average:17485.20 Median:571.53 stddev:23958.21 (4191.9%) stddev-sans-first:23992.00 03:06:58 INFO - PROCESS | 1669 | Values: 632.5 642.7 676.3 706.8 739.9 14281.4 14393.4 14693.0 14742.3 14893.9 13983.7 14319.8 14359.1 14365.9 14377.9 725.3 748.3 749.5 900.5 922.5 747.4 753.3 753.4 886.2 942.3 937.5 979.3 983.0 983.5 986.3 973.4 978.0 978.1 978.4 979.2 567.9 579.8 582.6 584.0 729.0 14633.9 15113.5 15146.2 15152.2 15160.5 12363.6 12450.7 12473.1 12563.0 12772.1 9930.4 10076.3 10233.0 10425.3 10455.6 3479.0 3481.7 3485.4 3531.8 3732.6 75563.1 77228.0 77327.6 77459.0 77583.2 9563.2 9765.2 9909.3 10027.3 10148.2 21840.8 22023.6 22261.8 22285.3 22294.8 7657.2 7755.1 7803.0 7827.9 7949.5 4134.8 4195.9 4214.3 4336.7 4340.0 3546.9 3576.1 3609.0 3609.3 3610.6 638.7 650.9 653.4 653.5 673.5 546.2 559.2 562.2 575.2 582.3 7721.2 7742.1 7829.4 7920.0 7940.4 490.5 548.7 549.5 554.3 562.3 3593.4 3695.7 3695.8 3738.9 3877.8 79686.6 80523.0 82571.3 82638.0 90673.5 75072.0 75302.0 75432.6 77061.2 77288.2 68911.0 69314.0 69740.0 69921.7 70528.9 6718.0 8168.8 8205.7 8312.4 8383.8 42565.0 42904.0 43969.7 44204.0 44500.0 47110.8 62129.0 62359.4 62364.1 62540.4 10734.8 10851.5 11055.7 11142.4 11209.8 11823.8 11837.7 12067.9 12198.8 12370.6 11105.9 11225.9 11484.4 11585.5 11651.5 11193.3 11364.8 11442.7 11801.4 12286.5 10979.4 11395.8 11413.9 11427.9 11496.3 9409.8 9573.4 9611.7 9683.9 9688.5 9380.8 9535.3 9581.8 9606.2 9618.4 03:06:58 INFO - PROCESS | 1669 | -------- Summary: end -------- 03:06:58 INFO - PROCESS | 1669 | 03:06:59 INFO - TEST-INFO | 1669: exit 0 03:06:59 INFO - TEST-OK | dromaeo_css | took 1115562ms 03:06:59 INFO - TEST-START | kraken 03:06:59 INFO - operating with platform_type : mac_ 03:06:59 INFO - Initialising browser for kraken test... 03:06:59 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/tmpptcXaV/profile http://localhost:49280/getInfo.html) 03:07:01 INFO - PROCESS | 1679 | __metrics Screen width/height:1600/1200 03:07:01 INFO - PROCESS | 1679 | colorDepth:24 03:07:01 INFO - PROCESS | 1679 | Browser inner width/height: 1024/689 03:07:01 INFO - PROCESS | 1679 | __metrics 03:07:01 INFO - PROCESS | 1679 | 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:07:01 ERROR - PROCESS | 1679 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:07:02 INFO - TEST-INFO | 1679: exit 0 03:07:02 INFO - Browser initialized. 03:07:02 INFO - Running cycle 1/1 for kraken test... 03:07:02 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/tmpptcXaV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:07:14 INFO - PROCESS | 1682 | RSS: Main: 211890176 03:07:14 INFO - PROCESS | 1682 | 03:07:17 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection) 03:07:18 INFO - PROCESS | 1682 | RSS: Main: 270929920 03:07:18 INFO - PROCESS | 1682 | 03:07:20 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-dft) 03:07:21 INFO - PROCESS | 1682 | RSS: Main: 284409856 03:07:21 INFO - PROCESS | 1682 | 03:07:24 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-fft) 03:07:25 INFO - PROCESS | 1682 | RSS: Main: 371625984 03:07:25 INFO - PROCESS | 1682 | 03:07:27 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator) 03:07:27 INFO - PROCESS | 1682 | RSS: Main: 277999616 03:07:27 INFO - PROCESS | 1682 | 03:07:30 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:07:30 INFO - PROCESS | 1682 | RSS: Main: 277585920 03:07:30 INFO - PROCESS | 1682 | 03:07:33 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom) 03:07:34 INFO - PROCESS | 1682 | RSS: Main: 318210048 03:07:34 INFO - PROCESS | 1682 | 03:07:37 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate) 03:07:37 INFO - PROCESS | 1682 | RSS: Main: 317034496 03:07:37 INFO - PROCESS | 1682 | 03:07:40 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial) 03:07:41 INFO - PROCESS | 1682 | RSS: Main: 316076032 03:07:41 INFO - PROCESS | 1682 | 03:07:42 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:07:43 INFO - PROCESS | 1682 | RSS: Main: 307683328 03:07:43 INFO - PROCESS | 1682 | 03:07:45 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:07:45 INFO - PROCESS | 1682 | RSS: Main: 313188352 03:07:45 INFO - PROCESS | 1682 | 03:07:47 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:07:48 INFO - PROCESS | 1682 | RSS: Main: 317505536 03:07:48 INFO - PROCESS | 1682 | 03:07:51 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:07:52 INFO - PROCESS | 1682 | RSS: Main: 322732032 03:07:52 INFO - PROCESS | 1682 | 03:07:55 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:07:55 INFO - PROCESS | 1682 | RSS: Main: 320307200 03:07:55 INFO - PROCESS | 1682 | 03:07:57 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49280/tests/kraken/driver.html?testName=ai-astar) 03:07:57 INFO - PROCESS | 1682 | RSS: Main: 384589824 03:07:57 INFO - PROCESS | 1682 | 03:07:57 INFO - PROCESS | 1682 | __start_tp_report 03:07:57 INFO - PROCESS | 1682 | _x_x_mozilla_page_load 03:07:57 INFO - PROCESS | 1682 | _x_x_mozilla_page_load_details 03:07:57 INFO - PROCESS | 1682 | |i|pagename|runs| 03:07:57 INFO - PROCESS | 1682 | |0;ai-astar;115;114;108;114;113;116;116;114;108;114 03:07:57 INFO - PROCESS | 1682 | |1;audio-beat-detection;150;120;146;187;148;171;139;123;160;140 03:07:57 INFO - PROCESS | 1682 | |2;audio-dft;186;187;187;186;186;186;185;185;186;187 03:07:57 INFO - PROCESS | 1682 | |3;audio-fft;74;77;72;148;55;54;59;65;136;57 03:07:57 INFO - PROCESS | 1682 | |4;audio-oscillator;89;90;141;76;80;85;126;80;76;124 03:07:57 INFO - PROCESS | 1682 | |5;imaging-gaussian-blur;89;85;89;86;88;86;94;129;135;84 03:07:57 INFO - PROCESS | 1682 | |6;imaging-darkroom;87;130;148;113;92;95;95;95;94;93 03:07:57 INFO - PROCESS | 1682 | |7;imaging-desaturate;86;85;85;83;86;85;86;85;83;84 03:07:57 INFO - PROCESS | 1682 | |8;json-parse-financial;61;64;64;65;65;65;64;82;60;59 03:07:57 INFO - PROCESS | 1682 | |9;json-stringify-tinderbox;64;64;74;69;74;73;67;68;70;67 03:07:57 INFO - PROCESS | 1682 | |10;stanford-crypto-aes;83;80;81;82;80;81;80;81;85;85 03:07:57 INFO - PROCESS | 1682 | |11;stanford-crypto-ccm;137;134;135;136;135;134;135;136;178;136 03:07:57 INFO - PROCESS | 1682 | |12;stanford-crypto-pbkdf2;179;170;233;176;169;168;172;173;201;169 03:07:57 INFO - PROCESS | 1682 | |13;stanford-crypto-sha256-iterative;69;70;70;71;68;68;69;70;71;92 03:07:57 INFO - PROCESS | 1682 | __end_tp_report 03:07:57 INFO - PROCESS | 1682 | __start_cc_report 03:07:57 INFO - PROCESS | 1682 | _x_x_mozilla_cycle_collect,2021 03:07:57 INFO - PROCESS | 1682 | __end_cc_report 03:07:57 INFO - PROCESS | 1682 | __startTimestamp1461665277720__endTimestamp 03:07:57 INFO - PROCESS | 1682 | 03:07:57 INFO - PROCESS | 1682 | ------- Summary: start ------- 03:07:57 INFO - PROCESS | 1682 | Number of tests: 14 03:07:57 INFO - PROCESS | 1682 | 03:07:57 INFO - PROCESS | 1682 | [#0] ai-astar Cycles:10 Average:113.20 Median:114.00 stddev:2.90 (2.5%) stddev-sans-first:3.00 03:07:57 INFO - PROCESS | 1682 | Values: 115.0 114.0 108.0 114.0 113.0 116.0 116.0 114.0 108.0 114.0 03:07:57 INFO - PROCESS | 1682 | 03:07:57 INFO - PROCESS | 1682 | [#1] audio-beat-detection Cycles:10 Average:148.40 Median:149.00 stddev:20.42 (13.7%) stddev-sans-first:21.66 03:07:57 INFO - PROCESS | 1682 | Values: 150.0 120.0 146.0 187.0 148.0 171.0 139.0 123.0 160.0 140.0 03:07:57 INFO - PROCESS | 1682 | 03:07:57 INFO - PROCESS | 1682 | [#2] audio-dft Cycles:10 Average:186.10 Median:186.00 stddev:0.74 (0.4%) stddev-sans-first:0.78 03:07:57 INFO - PROCESS | 1682 | Values: 186.0 187.0 187.0 186.0 186.0 186.0 185.0 185.0 186.0 187.0 03:07:57 INFO - PROCESS | 1682 | 03:07:57 INFO - PROCESS | 1682 | [#3] audio-fft Cycles:10 Average:79.70 Median:62.00 stddev:33.93 (54.7%) stddev-sans-first:35.93 03:07:57 INFO - PROCESS | 1682 | Values: 74.0 77.0 72.0 148.0 55.0 54.0 59.0 65.0 136.0 57.0 03:07:57 INFO - PROCESS | 1682 | 03:07:57 INFO - PROCESS | 1682 | [#4] audio-oscillator Cycles:10 Average:96.70 Median:80.00 stddev:24.09 (30.1%) stddev-sans-first:25.39 03:07:57 INFO - PROCESS | 1682 | Values: 89.0 90.0 141.0 76.0 80.0 85.0 126.0 80.0 76.0 124.0 03:07:57 INFO - PROCESS | 1682 | 03:07:57 INFO - PROCESS | 1682 | [#5] imaging-gaussian-blur Cycles:10 Average:96.50 Median:87.00 stddev:18.97 (21.8%) stddev-sans-first:19.92 03:07:57 INFO - PROCESS | 1682 | Values: 89.0 85.0 89.0 86.0 88.0 86.0 94.0 129.0 135.0 84.0 03:07:57 INFO - PROCESS | 1682 | 03:07:57 INFO - PROCESS | 1682 | [#6] imaging-darkroom Cycles:10 Average:104.20 Median:93.50 stddev:19.97 (21.4%) stddev-sans-first:20.19 03:07:57 INFO - PROCESS | 1682 | Values: 87.0 130.0 148.0 113.0 92.0 95.0 95.0 95.0 94.0 93.0 03:07:57 INFO - PROCESS | 1682 | 03:07:57 INFO - PROCESS | 1682 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.00 stddev:1.14 (1.3%) stddev-sans-first:1.12 03:07:57 INFO - PROCESS | 1682 | Values: 86.0 85.0 85.0 83.0 86.0 85.0 86.0 85.0 83.0 84.0 03:07:57 INFO - PROCESS | 1682 | 03:07:57 INFO - PROCESS | 1682 | [#8] json-parse-financial Cycles:10 Average:64.90 Median:64.50 stddev:6.40 (9.9%) stddev-sans-first:6.63 03:07:57 INFO - PROCESS | 1682 | Values: 61.0 64.0 64.0 65.0 65.0 65.0 64.0 82.0 60.0 59.0 03:07:57 INFO - PROCESS | 1682 | 03:07:57 INFO - PROCESS | 1682 | [#9] json-stringify-tinderbox Cycles:10 Average:69.00 Median:69.50 stddev:3.74 (5.4%) stddev-sans-first:3.50 03:07:57 INFO - PROCESS | 1682 | Values: 64.0 64.0 74.0 69.0 74.0 73.0 67.0 68.0 70.0 67.0 03:07:57 INFO - PROCESS | 1682 | 03:07:57 INFO - PROCESS | 1682 | [#10] stanford-crypto-aes Cycles:10 Average:81.80 Median:81.50 stddev:1.93 (2.4%) stddev-sans-first:2.00 03:07:57 INFO - PROCESS | 1682 | Values: 83.0 80.0 81.0 82.0 80.0 81.0 80.0 81.0 85.0 85.0 03:07:57 INFO - PROCESS | 1682 | 03:07:57 INFO - PROCESS | 1682 | [#11] stanford-crypto-ccm Cycles:10 Average:139.60 Median:136.00 stddev:13.53 (9.9%) stddev-sans-first:14.31 03:07:57 INFO - PROCESS | 1682 | Values: 137.0 134.0 135.0 136.0 135.0 134.0 135.0 136.0 178.0 136.0 03:07:57 INFO - PROCESS | 1682 | 03:07:57 INFO - PROCESS | 1682 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.00 Median:174.50 stddev:20.70 (11.9%) stddev-sans-first:21.94 03:07:57 INFO - PROCESS | 1682 | Values: 179.0 170.0 233.0 176.0 169.0 168.0 172.0 173.0 201.0 169.0 03:07:57 INFO - PROCESS | 1682 | 03:07:57 INFO - PROCESS | 1682 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.80 Median:70.00 stddev:7.18 (10.3%) stddev-sans-first:7.54 03:07:57 INFO - PROCESS | 1682 | Values: 69.0 70.0 70.0 71.0 68.0 68.0 69.0 70.0 71.0 92.0 03:07:57 INFO - PROCESS | 1682 | -------- Summary: end -------- 03:07:57 INFO - PROCESS | 1682 | 03:07:58 INFO - TEST-INFO | 1682: exit 0 03:07:58 INFO - TEST-OK | kraken | took 59036ms 03:07:58 INFO - SUITE-END | took 1174s 03:07:58 INFO - Completed test suite (00:19:34) 03:07:58 INFO - javascript benchmark 03:07:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2869.2826793301674, 3361.1933136047346, 3450.2064525159203, 3461.5499985001948, 3467.7399195060375, 5644.915326270104, 5659.801906933259, 5664.971675141629, 5673.092305231164, 5680.147977803332, 5633.690147041911, 5669.979403707331, 5679.545636349092, 5692.089265717486, 5785.479306862381, 5071.568916642072, 5129.820456284021, 5136.43499215086, 5159.664621799589, 5208.505192006773, 5208.348956380452, 5222.321098257221, 5224.1119009768245, 5227.686338819683, 5231.65994210377, 5142.537171654551, 5174.948250517485, 5209.244659524366, 5212.661177023481, 5232.241325007872, 5203.297554830087, 5242.344706911636, 5253.711045892474, 5261.710605916673, 5310.867228319285, 5023.673461225008, 5054.898902021976, 5074.06129865976, 5150.0, 5176.171812510018, 4591.83928562502, 5453.372862120827, 5464.6994415307145, 5537.723113844291, 5557.916631250535, 5091.541761241506, 6154.384561543855, 6162.7226774795245, 6163.599366041193, 6164.476019538344, 5538.141588053858, 6108.267007959029, 6134.344744984521, 6256.0, 6265.8120256392385, 5780.075187969905, 5783.652980821122, 5784.1034639630925, 5785.0, 5812.128180772918, 6321.3678632137335, 6323.083152942798, 6326.367363263636, 6328.208973878265, 6348.079528468346, 5525.502704756591, 5915.1718759373725, 6021.2473440819895, 6068.271807383142, 6131.938680613226, 6106.369660900722, 6242.75028998844, 6256.280527739368, 6260.718267677966, 6264.123022776815, 6113.84715382119, 6148.508119350441, 6191.133241346215, 6193.349698281616, 6195.566310358232, 5977.6114552553945, 6111.755529778759, 6221.5333849961435, 6232.283287421914, 6245.0, 4634.096351211549, 4855.271709243641, 4867.683600565952, 4868.537488938545, 4873.293372460973, 4411.801468933906, 4435.246008178618, 4438.001449673862, 4471.1728330259, 4471.709338892962, 5588.91616625751, 5699.629524080921, 5704.8859022819315, 5713.685747283939, 5713.94292055971, 5621.8594535136945, 5753.539716822645, 6265.467435268053, 6266.0, 6279.905801412982, 5661.830145095653, 5667.773289068474, 5672.716364181774, 5680.602357834994, 5683.0, 5253.23828044939, 5341.332333458317, 5345.33183352081, 5422.105352616774, 5445.401005889349, 6282.3089460158435, 6315.40003699657, 6317.526185536103, 6325.14610527582, 6343.841403964845, 6302.936970630235, 6314.494840412848, 6333.2716737575765, 6335.208098987627, 6339.619622822645, 6229.3770622937345, 6244.0, 6285.842853928689, 6306.65313407767, 6321.96839015802, 6175.9073613894925, 6179.69101544912, 6181.81454556364, 6181.9490686582485, 6184.226971628546, 5138.845834624967, 6138.14066030765, 6266.874662506815, 6286.748530058747, 6299.842503937255, 5875.529957603296, 6027.57806953509, 6115.877682446415, 6118.571699980958, 6142.078688196806, 4698.600618947427, 6012.068129439944, 6059.0, 6064.151018857276, 6081.179040829608, 5695.288088988877, 5825.912611310919, 5886.58793884424, 5894.587378883437, 5895.587308888336, 5589.385167631476, 6111.449969502766, 6113.969430152821, 6115.143879856734, 6140.600860944024, 4960.751962401938, 5922.289325280994, 5946.464818166212, 5967.1944287522365, 5968.880622387441], "value": 5629.594558432701, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [490.5560467776661, 522.9686218826869, 541.0801637216731, 554.437246195112, 562.2072877243087, 28020.57969130462, 30954.690453095463, 38323.425148622795, 39008.0, 39177.21645567087, 43476.78261608695, 44269.0, 44273.55726442735, 44368.778156109176, 44371.33442998357, 8477.406581539295, 9466.100720431565, 9708.708738737849, 9713.660021899235, 9725.367851089692, 7336.596487193202, 8678.392512524126, 8679.566021698922, 8684.652613895436, 11189.664310070712, 11388.316700997966, 11488.195826292163, 11500.712482187928, 11503.482343294572, 11510.194286399956, 10024.59901603935, 11331.716707082307, 11424.485898134604, 11585.710357241052, 11587.0, 8368.372372072057, 11074.889251107466, 11149.554017839277, 11402.543898244061, 11413.029892459152, 38706.41940370897, 39342.60657393447, 39385.80307098446, 39431.01422464433, 39556.615518457, 3205.951910721341, 3208.3904058229095, 3262.2822978944596, 3321.618013928381, 3332.8333583320737, 26586.46827063448, 27322.45355092887, 28016.579751303543, 28066.719332806613, 28946.42107157845, 2688.526555568881, 2729.7952653551065, 2739.1097893184797, 2742.5474796658527, 2771.3764403009163, 475142.99999999657, 478618.0, 480662.0, 481644.0, 481647.55057348334, 61540.69229653823, 61911.69044154751, 61965.0, 62053.0, 62061.448463788765, 65230.02154967679, 65321.673391632736, 65371.69256614841, 65420.67289663521, 65455.34544654588, 16919.577010574587, 17524.386646467312, 17533.29866805339, 17569.0, 17610.031448270212, 13457.596272111852, 13545.729085418236, 13572.049956503148, 13596.932015340059, 13608.795868061987, 14044.71910561783, 14292.499762508261, 14658.047226930215, 15835.891487595989, 16219.189040547926, 6977.860442791116, 7380.520266182681, 7399.186089530147, 7407.074115735533, 7463.365613922769, 856.3919617071824, 856.9459564735286, 942.1614762861059, 1017.5370206556147, 1098.906592939593, 27997.44005119886, 28686.139415817557, 29038.854805725838, 29613.0, 29700.40599188004, 1168.5617893290016, 1185.8339832423467, 1192.773373059116, 1221.4930803716359, 1232.2236990695803, 15021.624459388384, 15051.247437628075, 15133.167675777939, 15217.9239103806, 15486.767698484531, 632703.0, 638139.0, 639975.0, 641127.0, 641979.0, 58905.0, 60474.0, 60501.0, 60539.091913621334, 60724.69637651872, 1484.3617244584932, 1486.4648726458684, 1496.2219645784348, 1498.1385703220474, 1587.2222610920796, 28272.0, 28641.28396790097, 29008.09555934783, 29307.12078637559, 29324.706752933052, 50696.23955640742, 51099.23351149661, 51198.74400627973, 51217.0, 51319.74340128276, 564218.0, 578282.1085894543, 578897.0, 579778.0, 581830.0, 24620.6306905393, 30464.78140874429, 35423.82288088543, 39778.60221397749, 40199.59800402076, 39529.209415812096, 39899.698796684665, 42031.7898410506, 42755.786221068694, 42986.140277193655, 34610.826945866116, 34706.652933470345, 35415.46876796902, 35540.933771986885, 36249.09377265589, 35039.299214016086, 35263.0, 35399.115022123624, 35854.10364740902, 37235.44146837745, 36043.81978090093, 36367.90896273116, 36681.63318366889, 36685.81657091698, 36863.81568092143, 29630.259243519085, 31501.842490788316, 31929.042128736175, 31938.6806131945, 32070.518942215418, 23411.180608678613, 23997.52004959926, 24198.395040124138, 24252.0, 24565.263042108767], "value": 19905.77408566865, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.32241980804855, 398.5217738713544, 401.7609522334212, 403.4472772301945, 417.21771678103556, 14802.407903683854, 19454.0, 20658.586828263426, 20711.55019148662, 20715.896420517875, 17233.56916077099, 19928.202871885107, 20172.59654806903, 20198.091085901135, 20411.0, 4166.9791651041705, 5741.655500669952, 5923.437273459014, 5971.4625683688455, 5986.431289027535, 3934.4688467056867, 4429.60133587977, 4429.6899217054815, 4436.645068394537, 4438.800253988562, 5707.771689132451, 6176.536759743015, 6512.902306465407, 6514.609123452585, 6529.0, 5998.520118390518, 6008.309044459856, 6042.395760423967, 6050.485708714765, 6089.269287685506, 5679.290088738908, 5691.345495268056, 5783.450572195677, 5820.214271073393, 5827.388124246935, 12821.67945801353, 13143.802842957364, 13308.268045257508, 13310.0, 13465.394057267447, 2091.184438069154, 2099.071392314346, 2102.0750869617473, 2345.5074434368803, 2583.3929026678716, 9690.012847923252, 9709.029097090235, 9778.853317200248, 10107.545160467796, 10140.949295253551, 1658.1294820219362, 1731.117130263562, 1744.2761254079542, 1744.459217642535, 1891.9527011824678, 123068.38465807613, 126760.0, 128896.0, 129708.3514582421, 131531.0, 9996.300258981946, 11610.129240307011, 11651.883481165249, 11700.94149529264, 11816.113791465674, 13334.399952002183, 14383.208923509308, 15170.393184272727, 15265.923670381577, 15272.389104435699, 7692.461527693008, 8505.02192247887, 8815.647374104963, 8831.425957312755, 8968.10318968098, 8771.16673915977, 8777.868331975025, 8787.428817126865, 8880.067592902653, 8905.599248033725, 4016.5180178378782, 4511.616512596466, 4533.954660453419, 4595.356650068993, 4598.632109431237, 1669.9248533815764, 1922.1350392323511, 1964.557974455736, 1987.5826076523804, 2016.4757163137397, 716.4985537887834, 895.2658819767859, 923.6951805904035, 977.2231076294363, 1004.0, 19243.230270789292, 20021.29925452631, 20153.39539813808, 20277.69583456249, 20314.085866136058, 1003.2826529031777, 1036.429963520061, 1049.443794788748, 1057.5822550092823, 1064.914806815453, 10268.281220314506, 10716.089132423831, 10723.981221783917, 10741.355518668905, 10770.78458430827, 611953.0, 613715.0, 619254.0, 622617.0, 628976.0, 67560.0, 130468.0, 145692.0, 146421.0, 147101.0, 1011.5043628622069, 1045.6915210013065, 1061.4480470155631, 1061.8407238914224, 1065.2862582070031, 14205.076670016417, 17567.385141520495, 17949.192286346886, 18247.996360199784, 18553.81446185575, 38956.80521597374, 51540.74229628828, 52989.0, 54323.0, 54826.72586637041, 76719.84920226313, 78475.21524784908, 79040.0, 80046.0, 80917.19082809097, 7764.107127680389, 7835.902973583817, 7988.440809143304, 8210.794730131556, 8268.586570671563, 7925.28672419485, 9940.602375904882, 12753.48986040585, 13071.215727056408, 13246.403911824184, 9467.857982130135, 10301.484925753832, 10730.463476826284, 11189.832152517556, 11599.188056835941, 10034.548445320124, 10884.45577721095, 10997.945010274898, 11053.557857685924, 11368.602098926498, 10155.39067655943, 11172.664820055412, 11237.831432528683, 11573.942130289295, 11736.647900562997, 8112.797180070545, 8220.712275070344, 8253.422260441712, 8484.406091573777, 8688.044315125457, 10696.89303106959, 11020.338779673246, 11211.663650090184, 11323.377214253294, 11499.022583080532], "value": 9291.711737195354, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1410.9294535273234, 1587.2381256996637, 1610.7020201262765, 1811.9184636691348, 1929.9324523641676, 7936.722214722486, 8843.336749743763, 9036.638534458614, 9037.18665320121, 9043.954780226106, 8638.827223455528, 8882.822343553125, 8902.020777714462, 8904.332175086862, 8914.242289405409, 2163.8593491423085, 2409.650600662903, 2444.181199298237, 2484.5155194736944, 2498.475320182764, 2101.2855629086107, 2494.064725727852, 2517.5216708825355, 2530.8861101250486, 2532.468181681849, 2280.5974325789684, 2283.9885800570974, 2431.513697260546, 2475.0718480569785, 2483.6026235802274, 2132.1897678882083, 2205.922792702264, 2211.977880221193, 2215.4682876109778, 2440.4996975620043, 2103.1797598936428, 2391.234804862445, 2476.7894728948063, 2487.2413913756295, 2494.738052504479, 8775.034746177915, 8829.20537151653, 8832.735017949472, 8833.734987950373, 8835.028146903835, 4280.015596412843, 5185.714785686786, 5456.61803673743, 5465.699386533739, 5468.26178465906, 3484.1463841359027, 3573.910652233689, 3649.033006253345, 3661.6155303693263, 3691.243295124493, 1031.3038698878227, 1050.5902697947806, 1052.5631862776938, 1054.0671505717419, 1072.1476426241156, 9551.761205969837, 12161.148719589632, 12177.878221217763, 12181.269123852597, 12188.329641869605, 4796.880077998008, 4850.490698476679, 4926.802927882916, 4958.355413796184, 4971.477994810565, 10252.538635761408, 10270.0, 10293.588256469808, 10294.536745846455, 10308.381497110197, 5386.326709161355, 5481.972590137024, 5529.474699903502, 5531.778728850801, 5533.08704063833, 4425.690201685851, 4582.991741816795, 4595.793189306489, 4816.735079570657, 4987.526185012418, 3955.861544845917, 3987.7009224308295, 4037.515498140242, 4081.571434999342, 4116.1767646470225, 1115.9497822598003, 1342.214804339453, 1346.6094832498466, 1348.0, 1353.7157196988546, 809.9018964041967, 837.9539125348042, 872.2019352292581, 906.3066753933247, 944.8015916657441, 4715.3869996900185, 5782.855428614234, 6044.274687037584, 6054.485368743706, 6080.209572755513, 926.1664501948301, 941.7551436626527, 943.8489841625307, 950.4439902656885, 954.5418199264392, 3826.6556009959236, 3834.69322454203, 3938.15329704115, 3964.682825373963, 3996.980015099906, 10287.639932602324, 10848.023677868872, 10897.073748731287, 10900.672979810619, 10910.563577456813, 10998.06516446111, 11085.27945683528, 11116.221864469571, 11128.49921753523, 11128.66614001565, 8039.5176289422825, 8268.751937441886, 8424.578771061422, 8475.533845638549, 8486.957565212135, 4911.58251548608, 5783.624064435799, 5784.5083167929515, 5875.823725288248, 5910.3271039697975, 10052.899471005196, 10286.742831429274, 10517.631882884063, 10530.947345263225, 10539.94730026345, 10195.031472010147, 10286.228532859916, 10512.684619461428, 10687.572497100029, 10694.518746656575, 7072.469564782765, 7978.720744773905, 8393.790155246168, 8415.831683366176, 8436.95781521113, 8112.0, 8131.634076466691, 8199.97500312461, 8202.220789025034, 8210.917890821016, 7326.523775954546, 7436.516626419266, 7492.662830172546, 7511.3615342696485, 7512.399008079231, 7282.053333066668, 7388.556686598821, 7400.296971787671, 7542.321191092608, 7624.580648064188, 7092.04257425241, 7181.605011724405, 7184.640767961477, 7200.099987501562, 7218.061651985208, 4524.389207456951, 4639.884002899955, 4690.460597031385, 4777.880552986203, 4961.08219979305, 4878.195097808822, 4969.0, 5055.6713813602, 5137.383513978347, 5202.661826981234], "value": 4634.865255944818, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.97348225400856, 320.9566708494353, 326.8398484742476, 327.18530858163183, 328.44492807155905, 5073.23901414788, 5260.947390526094, 5282.788688452462, 5299.867503312409, 5302.443243459435, 3632.582253040901, 4969.850904472863, 4973.278874563186, 5192.221166824968, 5277.736113194334, 3394.3550725362225, 4021.919561608766, 4067.227226826908, 4079.286124928126, 4290.377895205193, 4052.257529262416, 4089.6484521400166, 4099.795010249491, 4109.506859176903, 4115.238680844039, 3234.8867789627366, 3846.4038074098557, 3880.8745463815458, 3965.9008524786823, 4114.280000999828, 3181.552393660879, 3677.9264414711824, 3893.8831835044994, 3994.9800250998705, 4049.777262250575, 3542.822858857073, 3547.94678079829, 3561.1809283864595, 3564.198055437532, 3568.3220188164423, 4602.930956035662, 4924.0, 5106.259592359087, 5184.326037615124, 5186.325777648882, 3917.4319723640197, 4090.550039495653, 4132.904780233241, 4295.0, 4681.414823147106, 4451.888702782424, 5182.274481572583, 5540.814031931655, 5602.187682786014, 5625.831225063213, 3824.9426258606145, 3935.862244821446, 3952.4268980997595, 3959.425883246942, 3962.9009274768073, 4901.828436004723, 5499.432661691399, 5828.417158284137, 5840.211571437844, 5842.231760514487, 3386.7967921924765, 3953.8418463261146, 4002.53843886008, 4002.639762421396, 4010.5588385277597, 3620.968024113115, 3679.5584529856587, 3816.5610954740005, 4067.7355971861734, 4069.9389509157386, 4267.423897773823, 4348.847790327323, 4386.846460373871, 4626.699264547794, 4682.882927926829, 4305.784710764449, 5588.776448942078, 5603.579731520153, 5645.0, 5652.399896047864, 1308.8822006019504, 1413.2015411292791, 1453.8255409350945, 1482.7182835261267, 1511.214168632305, 805.7784109369853, 995.9438987440803, 1010.5351538292321, 1017.2269075502671, 1021.9795604087876, 5225.242339860699, 5585.184814935163, 5645.491905728504, 5699.74351154199, 5743.6266642668925, 4896.387951506062, 4992.525710057539, 6204.821083994027, 6331.620102793755, 6341.302456729756, 4284.935725964113, 4407.0, 5070.706969722697, 5673.6028478006965, 5691.321060287225, 3313.094970392034, 3315.8507867146036, 3331.5502407175204, 3336.249343897652, 3336.332733453319, 6384.585001974857, 6595.0, 6596.175478065242, 6599.934000660028, 6609.900851487231, 5501.284832971768, 6414.454771344394, 6458.321876202931, 6526.510511711641, 6566.080748695186, 5197.480251974772, 6168.444839964335, 6559.704813283413, 6580.638064906476, 6592.30780768022, 6020.8795824084145, 6262.248530176408, 6311.400416960381, 6317.178766760292, 6326.398992095744, 4948.703077815343, 5262.394824595221, 5269.262303277467, 6000.909986350121, 6198.132261483306, 5680.943190568207, 6360.823247699191, 6558.967205163944, 6571.441427478718, 6793.626350550767, 3481.6170221275133, 4101.856435024879, 4111.60939710727, 4118.876433706993, 4162.209180255742, 5483.122700367922, 5496.917546236889, 6296.937030629635, 6334.4932405406535, 6345.397187267202, 4851.126797176542, 5204.401493828258, 5492.588055895744, 5617.382087970238, 5663.150527420919, 4879.926801097916, 5563.525665698674, 5591.776328946797, 5606.159076138611, 5612.691301978431, 4566.497685254686, 5294.996228564124, 5466.098093814476, 5472.0, 5479.671219726829, 5625.240592519957, 5635.774569017193, 5638.351589567253, 5645.774169033357, 5669.064604340238, 4599.609033232213, 4925.679830810986, 5484.2047903053035, 5523.723813809213, 5541.501264886179], "value": 4198.759050084525, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [632.4845251120337, 642.6658137768361, 676.2628734679199, 706.8091615263879, 739.9297066778654, 14281.357338919748, 14393.447980470686, 14693.0, 14742.33659485323, 14893.851061489382, 13983.720325593507, 14319.78520322196, 14359.138451692907, 14365.92817035916, 14377.92811035946, 725.2783480436973, 748.3040772081962, 749.4828568287891, 900.4867225681348, 922.4880191493718, 747.4095464582973, 753.3408267765705, 753.3822265742093, 886.162576365335, 942.2556180617304, 937.5468024561936, 979.3095867413457, 983.0071627656083, 983.4787562591838, 986.309583291695, 973.3770386952356, 977.9511024448784, 978.0903759503659, 978.3738407419257, 979.1921664626676, 567.8892615939893, 579.8144593730008, 582.5921854701726, 583.9737211825478, 729.0376702752369, 14633.926830365886, 15113.54659360221, 15146.16696081715, 15152.242387880562, 15160.469383571632, 12363.62909112719, 12450.688732781753, 12473.126881118325, 12563.0, 12772.105952583323, 9930.354526955725, 10076.345037572535, 10233.0, 10425.270231083827, 10455.58177672892, 3478.9652103479148, 3481.651834816523, 3485.372632926072, 3531.8410671519587, 3732.5894151643297, 75563.11092222683, 77228.0, 77327.6133619334, 77459.0, 77583.22416775816, 9563.187129093965, 9765.218782497383, 9909.306348555676, 10027.279367555891, 10148.238882083873, 21840.781592183874, 22023.559528809656, 22261.777382226293, 22285.331440056823, 22294.777052229594, 7657.1959944205055, 7755.069391672922, 7803.0, 7827.921720782833, 7949.483283586664, 4134.793260336971, 4195.8741237762915, 4214.30463973441, 4336.6530677545725, 4340.023494713664, 3546.911327216789, 3576.141725985745, 3608.9819550902075, 3609.296187243462, 3610.6208848070573, 638.7349250061266, 650.9381608747161, 653.3564439027554, 653.4902775834856, 673.5285300289817, 546.2407253917058, 559.2366419836893, 562.190445758107, 575.1803679756331, 582.2983305117324, 7721.227877212234, 7742.14836367999, 7829.412794040356, 7919.960400197962, 7940.444168908237, 490.45069522135435, 548.6927320700421, 549.5027000564495, 554.282204545107, 562.2971285892634, 3593.443016332473, 3695.7228207883977, 3695.8336874840697, 3738.9439158412647, 3877.7673339599714, 79686.6015669918, 80523.0, 82571.3485730294, 82638.0, 90673.54663226641, 75072.0, 75302.0, 75432.62283688546, 77061.22938770652, 77288.22711772923, 68911.0, 69313.96029059569, 69740.0, 69921.65039174771, 70528.94206586905, 6718.0, 8168.83662326762, 8205.712800052208, 8312.418130731032, 8383.832323353621, 42565.0, 42904.0, 43969.680909572766, 44204.0, 44500.0, 47110.76444617755, 62129.0, 62359.37640623717, 62364.12907612779, 62540.37459625528, 10734.785304294026, 10851.457427128455, 11055.72360690989, 11142.387168705798, 11209.831852522055, 11823.763524729286, 11837.704057398634, 12067.939660301643, 12198.75602487963, 12370.62888113358, 11105.888941110485, 11225.943870280596, 11484.368359740294, 11585.478653460783, 11651.47568359409, 11193.328400296008, 11364.829527556927, 11442.656720298404, 11801.35092569917, 12286.508539658313, 10979.39613321243, 11395.829062564235, 11413.942930285295, 11427.942860285646, 11496.310221386744, 9409.811803763749, 9573.377730447499, 9611.711648650551, 9683.854742178732, 9688.46713430768, 9380.81450304967, 9535.332526723063, 9581.760455988657, 9606.231501479726, 9618.422894626345], "value": 6751.862855049676, "unit": "score"}], "name": "dromaeo_css", "value": 7178.432550020086, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 108.0, 114.0, 113.0, 116.0, 116.0, 114.0, 108.0, 114.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 120.0, 146.0, 187.0, 148.0, 171.0, 139.0, 123.0, 160.0, 140.0], "value": 148.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 187.0, 187.0, 186.0, 186.0, 186.0, 185.0, 185.0, 186.0, 187.0], "value": 186.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 72.0, 148.0, 55.0, 54.0, 59.0, 65.0, 136.0, 57.0], "value": 79.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 141.0, 76.0, 80.0, 85.0, 126.0, 80.0, 76.0, 124.0], "value": 96.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 89.0, 86.0, 88.0, 86.0, 94.0, 129.0, 135.0, 84.0], "value": 96.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 130.0, 148.0, 113.0, 92.0, 95.0, 95.0, 95.0, 94.0, 93.0], "value": 104.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 85.0, 83.0, 86.0, 85.0, 86.0, 85.0, 83.0, 84.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 64.0, 65.0, 65.0, 65.0, 64.0, 82.0, 60.0, 59.0], "value": 64.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 74.0, 69.0, 74.0, 73.0, 67.0, 68.0, 70.0, 67.0], "value": 69.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 80.0, 81.0, 82.0, 80.0, 81.0, 80.0, 81.0, 85.0, 85.0], "value": 81.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 134.0, 135.0, 136.0, 135.0, 134.0, 135.0, 136.0, 178.0, 136.0], "value": 139.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 170.0, 233.0, 176.0, 169.0, 168.0, 172.0, 173.0, 201.0, 169.0], "value": 181.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 70.0, 71.0, 68.0, 68.0, 69.0, 70.0, 71.0, 92.0], "value": 71.8, "unit": "score"}], "name": "kraken", "value": 1517.6999999999998, "alertThreshold": 2.0}]} 03:07:58 INFO - Return code: 0 03:07:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:07:58 ERROR - # TBPL SUCCESS # 03:07:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:07:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:07:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:07:59 INFO - 2016-04-26 03:07:59.003 screenresolution[1686:9700] starting screenresolution argv=screenresolution get 03:07:59 INFO - 2016-04-26 03:07:59.006 screenresolution[1686:9700] Display 0: 1600x1200x32@60 03:07:59 INFO - 2016-04-26 03:07:59.012 screenresolution[1687:9703] starting screenresolution argv=screenresolution list 03:07:59 INFO - Available Modes on Display 0 03:07:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:07:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:07:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:07:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:07:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:07:59 INFO - Intel Iris: 03:07:59 INFO - Chipset Model: Intel Iris 03:07:59 INFO - Type: GPU 03:07:59 INFO - Bus: Built-In 03:07:59 INFO - VRAM (Dynamic, Max): 1536 MB 03:07:59 INFO - Vendor: Intel (0x8086) 03:07:59 INFO - Device ID: 0x0a2e 03:07:59 INFO - Revision ID: 0x0009 03:07:59 INFO - Displays: 03:07:59 INFO - Display: 03:07:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:07:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:07:59 INFO - Display Serial Number: 1600x1200 60 03:07:59 INFO - Main Display: Yes 03:07:59 INFO - Mirror: Off 03:07:59 INFO - Online: Yes 03:07:59 INFO - Rotation: Supported 03:07:59 INFO - Return code: 0 03:07:59 INFO - Running post-action listener: _resource_record_post_action 03:07:59 INFO - Running post-run listener: _resource_record_post_run 03:08:00 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 37934592; Write bytes: 654105600; Read time: 401; Write time: 1834 03:08:00 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 354527744; Write bytes: 355554816; Read time: 27853; Write time: 662 03:08:00 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 36120064; Write bytes: 297551360; Read time: 378; Write time: 1169 03:08:00 INFO - Running post-run listener: _upload_blobber_files 03:08:00 INFO - Blob upload gear active. 03:08:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:08:00 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:08:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:08:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:08:00 INFO - (blobuploader) - INFO - Open directory for files ... 03:08:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:08:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:08:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:08:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:08:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:08:01 INFO - (blobuploader) - INFO - Done attempting. 03:08:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:08:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:08:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:08:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:08:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:08:02 INFO - (blobuploader) - INFO - Done attempting. 03:08:02 INFO - (blobuploader) - INFO - Iteration through files over. 03:08:02 INFO - Return code: 0 03:08:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:08:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:08:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ec1f1e39521a4eac21a7ebd73713a83953570c56d9f0bfbdb5faba5eefaa0ee647e21669cbc5a8a1c217327bbb010c3b0fcbd3c900133fef009cfffe9083655f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aef45deca89105903a61275bf6c0183c3fccf2a820e0ec01abc632e5b2619c412c3ffdb9dd58ef7348c2d36a3c320a834822fe5d6b6a9531857cf9d10a8f2b4b"} 03:08:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:08:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:08:02 INFO - Contents: 03:08:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ec1f1e39521a4eac21a7ebd73713a83953570c56d9f0bfbdb5faba5eefaa0ee647e21669cbc5a8a1c217327bbb010c3b0fcbd3c900133fef009cfffe9083655f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aef45deca89105903a61275bf6c0183c3fccf2a820e0ec01abc632e5b2619c412c3ffdb9dd58ef7348c2d36a3c320a834822fe5d6b6a9531857cf9d10a8f2b4b"} 03:08:02 INFO - Running post-run listener: copy_logs_to_upload_dir 03:08:02 INFO - Copying logs to upload dir... 03:08:02 INFO - mkdir: /builds/slave/test/build/upload/logs 03:08:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.447331 ========= master_lag: 12.86 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-04-26 03:08:15.602868) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 8 secs) (at 2016-04-26 03:08:15.607726) ========= 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.BUMwjIKqyq/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.rx5SGQMzif/Listeners TMPDIR=/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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/ec1f1e39521a4eac21a7ebd73713a83953570c56d9f0bfbdb5faba5eefaa0ee647e21669cbc5a8a1c217327bbb010c3b0fcbd3c900133fef009cfffe9083655f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aef45deca89105903a61275bf6c0183c3fccf2a820e0ec01abc632e5b2619c412c3ffdb9dd58ef7348c2d36a3c320a834822fe5d6b6a9531857cf9d10a8f2b4b"} build_url:https://queue.taskcluster.net/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011618 build_url: 'https://queue.taskcluster.net/v1/task/HOEL3zTzTre5xQ4eikKsFA/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/ec1f1e39521a4eac21a7ebd73713a83953570c56d9f0bfbdb5faba5eefaa0ee647e21669cbc5a8a1c217327bbb010c3b0fcbd3c900133fef009cfffe9083655f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aef45deca89105903a61275bf6c0183c3fccf2a820e0ec01abc632e5b2619c412c3ffdb9dd58ef7348c2d36a3c320a834822fe5d6b6a9531857cf9d10a8f2b4b"}' ========= master_lag: 8.33 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 8 secs) (at 2016-04-26 03:08:23.946927) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 03:08:23.949793) ========= 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.BUMwjIKqyq/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.rx5SGQMzif/Listeners TMPDIR=/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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.005205 ========= master_lag: 0.12 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 03:08:24.073156) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 03:08:24.073528) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 03:08:24.119561) ========= ========= Total master_lag: 21.37 =========