builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0180 starttime: 1462452351.57 results: success (0) buildid: 20160505030327 builduid: ca051577918442339c331ae12a805704 revision: 369a5ee3a2880a4a98df3a00bf3db8d8f36b181b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-05 05:45:51.575335) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-05 05:45:51.576009) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-05 05:45:51.576482) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-05 05:45:51.598800) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 05:45:51.599408) ========= 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.XJaqX2Yxcy/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.Ca3mnil4RT/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-05 05:45:51-- 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% 64.0M=0s 2016-05-05 05:45:51 (64.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.118593 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 05:45:51.736704) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 05:45:51.737028) ========= 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.XJaqX2Yxcy/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.Ca3mnil4RT/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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.026398 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 05:45:51.785364) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-05 05:45:51.785744) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 369a5ee3a2880a4a98df3a00bf3db8d8f36b181b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 369a5ee3a2880a4a98df3a00bf3db8d8f36b181b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XJaqX2Yxcy/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.Ca3mnil4RT/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-05 05:45:51,854 truncating revision to first 12 chars 2016-05-05 05:45:51,854 Setting DEBUG logging. 2016-05-05 05:45:51,854 attempt 1/10 2016-05-05 05:45:51,854 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/369a5ee3a288?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-05 05:45:53,522 unpacking tar archive at: mozilla-central-369a5ee3a288/testing/mozharness/ program finished with exit code 0 elapsedTime=1.914847 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-05 05:45:53.720590) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-05 05:45:53.720915) ========= script_repo_revision: 369a5ee3a2880a4a98df3a00bf3db8d8f36b181b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-05 05:45:53.721292) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-05 05:45:53.721584) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-05 05:45:53.735525) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-05-05 05:45:53.735986) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XJaqX2Yxcy/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.Ca3mnil4RT/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 05:45:53 INFO - MultiFileLogger online at 20160505 05:45:53 in /builds/slave/test 05:45:53 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:45:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:45:53 INFO - {'append_to_log': False, 05:45:53 INFO - 'base_work_dir': '/builds/slave/test', 05:45:53 INFO - 'blob_upload_branch': 'Firefox', 05:45:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:45:53 INFO - 'branch': 'Firefox', 05:45:53 INFO - 'buildbot_json_path': 'buildprops.json', 05:45:53 INFO - 'config_files': ('talos/mac_config.py',), 05:45:53 INFO - 'default_actions': ('clobber', 05:45:53 INFO - 'read-buildbot-config', 05:45:53 INFO - 'download-and-extract', 05:45:53 INFO - 'populate-webroot', 05:45:53 INFO - 'create-virtualenv', 05:45:53 INFO - 'install', 05:45:53 INFO - 'run-tests'), 05:45:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:45:53 INFO - 'download_minidump_stackwalk': True, 05:45:53 INFO - 'download_symbols': 'ondemand', 05:45:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:45:53 INFO - 'tooltool.py': '/tools/tooltool.py', 05:45:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:45:53 INFO - '/tools/misc-python/virtualenv.py')}, 05:45:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:45:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:45:53 INFO - 'installer_path': 'installer.exe', 05:45:53 INFO - 'log_level': 'info', 05:45:53 INFO - 'log_name': 'talos', 05:45:53 INFO - 'log_to_console': True, 05:45:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:45:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:45:53 INFO - 'opt_config_files': (), 05:45:53 INFO - 'pip_index': False, 05:45:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:45:53 INFO - 'cmd': ('bash', 05:45:53 INFO - '-c', 05:45:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:45:53 INFO - 'enabled': True, 05:45:53 INFO - 'halt_on_failure': False, 05:45:53 INFO - 'name': 'check_screen_resolution'},), 05:45:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:45:53 INFO - 'cmd': ('bash', 05:45:53 INFO - '-c', 05:45:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:45:53 INFO - 'enabled': True, 05:45:53 INFO - 'halt_on_failure': False, 05:45:53 INFO - 'name': 'check_screen_resolution'},), 05:45:53 INFO - 'run_cmd_checks_enabled': True, 05:45:53 INFO - 'sps_profile': False, 05:45:53 INFO - 'sps_profile_interval': 0, 05:45:53 INFO - 'suite': 'dromaeojs', 05:45:53 INFO - 'system_bits': '32', 05:45:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:45:53 INFO - 'title': 't-yosemite-r7-0180', 05:45:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:45:53 INFO - 'use_talos_json': True, 05:45:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:45:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:45:53 INFO - 'work_dir': 'build'} 05:45:53 INFO - ##### 05:45:53 INFO - ##### Running clobber step. 05:45:53 INFO - ##### 05:45:53 INFO - Running pre-action listener: _resource_record_pre_action 05:45:53 INFO - Running main action method: clobber 05:45:53 INFO - rmtree: /builds/slave/test/build 05:45:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:45:55 INFO - Running post-action listener: _resource_record_post_action 05:45:55 INFO - ##### 05:45:55 INFO - ##### Running read-buildbot-config step. 05:45:55 INFO - ##### 05:45:55 INFO - Running pre-action listener: _resource_record_pre_action 05:45:55 INFO - Running main action method: read_buildbot_config 05:45:55 INFO - Using buildbot properties: 05:45:55 INFO - { 05:45:55 INFO - "project": "", 05:45:55 INFO - "product": "firefox", 05:45:55 INFO - "script_repo_revision": "production", 05:45:55 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:45:55 INFO - "repository": "", 05:45:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:45:55 INFO - "buildid": "20160505030327", 05:45:55 INFO - "stage_platform": "macosx64", 05:45:55 INFO - "basedir": "/builds/slave/test", 05:45:55 INFO - "nightly_build": "True", 05:45:55 INFO - "buildnumber": 248, 05:45:55 INFO - "slavename": "t-yosemite-r7-0180", 05:45:55 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 05:45:55 INFO - "platform": "yosemite_r7", 05:45:55 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:45:55 INFO - "branch": "mozilla-central", 05:45:55 INFO - "repo_path": "mozilla-central", 05:45:55 INFO - "revision": "369a5ee3a2880a4a98df3a00bf3db8d8f36b181b", 05:45:55 INFO - "pgo_build": "False", 05:45:55 INFO - "builduid": "ca051577918442339c331ae12a805704", 05:45:55 INFO - "slavebuilddir": "test" 05:45:55 INFO - } 05:45:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 05:45:55 INFO - Running post-action listener: _resource_record_post_action 05:45:55 INFO - ##### 05:45:55 INFO - ##### Running download-and-extract step. 05:45:55 INFO - ##### 05:45:55 INFO - Running pre-action listener: _resource_record_pre_action 05:45:55 INFO - Running main action method: download_and_extract 05:45:55 INFO - mkdir: /builds/slave/test/build/tests 05:45:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:45:55 INFO - https://queue.taskcluster.net/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:45:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:45:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 05:45:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:45:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 05:46:04 INFO - Downloaded 1199 bytes. 05:46:04 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 05:46:04 INFO - Using the following test package requirements: 05:46:04 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:46:04 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:46:04 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 05:46:04 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:46:04 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 05:46:04 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:46:04 INFO - u'jsshell-mac.zip'], 05:46:04 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:46:04 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 05:46:04 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 05:46:04 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:46:04 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 05:46:04 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:46:04 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 05:46:04 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:46:04 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 05:46:04 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 05:46:04 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 05:46:04 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:46: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')]} 05:46:04 INFO - https://queue.taskcluster.net/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:46:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:46:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:46:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:46:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:46:05 INFO - Downloaded 18131141 bytes. 05:46:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:46:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:46:09 INFO - Return code: 0 05:46:09 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:46: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')]} 05:46:09 INFO - https://queue.taskcluster.net/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:46:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:46:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:46:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 05:46:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:46:11 INFO - Downloaded 18131141 bytes. 05:46:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:46:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:46:14 INFO - Return code: 0 05:46:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:14 INFO - https://queue.taskcluster.net/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:46:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:46:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:46:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 05:46:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:46:16 INFO - Downloaded 10840152 bytes. 05:46:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:46:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:46:16 INFO - Return code: 0 05:46:16 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')]} 05:46:16 INFO - https://queue.taskcluster.net/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:46:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:46:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:46:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 05:46:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 05:46:21 INFO - Downloaded 110611023 bytes. 05:46:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:46:21 INFO - mkdir: /builds/slave/test/properties 05:46:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:46:21 INFO - Writing to file /builds/slave/test/properties/build_url 05:46:21 INFO - Contents: 05:46:21 INFO - build_url:https://queue.taskcluster.net/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 05:46:22 INFO - Running post-action listener: _resource_record_post_action 05:46:22 INFO - Running post-action listener: set_extra_try_arguments 05:46:22 INFO - ##### 05:46:22 INFO - ##### Running populate-webroot step. 05:46:22 INFO - ##### 05:46:22 INFO - Running pre-action listener: _resource_record_pre_action 05:46:22 INFO - Running main action method: populate_webroot 05:46:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:46:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:46:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:46:22 INFO - '--tppagecycles', 05:46:22 INFO - '7'], 05:46:22 INFO - 'tests': ['tsvgm']}}, 05:46:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:46:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:46:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:46:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:46:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:46:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:46:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:22 INFO - 'talos_options': ['--disable-e10s'], 05:46:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:46:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:46:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:22 INFO - 'talos_options': ['--disable-e10s'], 05:46:22 INFO - 'tests': ['damp', 'tps']}, 05:46:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:22 INFO - 'tests': ['damp', 'tps']}, 05:46:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:46:22 INFO - 'tests': ['dromaeo_dom']}, 05:46:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:46:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:46:22 INFO - 'tests': ['a11yr', 05:46:22 INFO - 'ts_paint', 05:46:22 INFO - 'tpaint', 05:46:22 INFO - 'sessionrestore', 05:46:22 INFO - 'sessionrestore_no_auto_restore', 05:46:22 INFO - 'tabpaint']}, 05:46:22 INFO - 'other-e10s': {'tests': ['a11yr', 05:46:22 INFO - 'ts_paint', 05:46:22 INFO - 'tpaint', 05:46:22 INFO - 'sessionrestore', 05:46:22 INFO - 'sessionrestore_no_auto_restore', 05:46:22 INFO - 'tabpaint']}, 05:46:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:46:22 INFO - 'ts_paint', 05:46:22 INFO - 'tpaint', 05:46:22 INFO - 'sessionrestore', 05:46:22 INFO - 'sessionrestore_no_auto_restore', 05:46:22 INFO - 'tabpaint']}, 05:46:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:46:22 INFO - 'ts_paint', 05:46:22 INFO - 'tpaint', 05:46:22 INFO - 'sessionrestore', 05:46:22 INFO - 'sessionrestore_no_auto_restore', 05:46:22 INFO - 'tabpaint']}, 05:46:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:46:22 INFO - 'tests': ['a11yr', 05:46:22 INFO - 'ts_paint', 05:46:22 INFO - 'tpaint', 05:46:22 INFO - 'sessionrestore', 05:46:22 INFO - 'sessionrestore_no_auto_restore', 05:46:22 INFO - 'tabpaint']}, 05:46:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:46:22 INFO - 'tests': ['a11yr', 05:46:22 INFO - 'ts_paint', 05:46:22 INFO - 'tpaint', 05:46:22 INFO - 'sessionrestore', 05:46:22 INFO - 'sessionrestore_no_auto_restore', 05:46:22 INFO - 'tabpaint']}, 05:46:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:46:22 INFO - 'tests': ['tsvgx', 05:46:22 INFO - 'tsvgr_opacity', 05:46:22 INFO - 'tart', 05:46:22 INFO - 'tscrollx', 05:46:22 INFO - 'cart']}, 05:46:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:46:22 INFO - 'tsvgr_opacity', 05:46:22 INFO - 'tart', 05:46:22 INFO - 'tscrollx', 05:46:22 INFO - 'cart']}, 05:46:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:22 INFO - 'talos_options': ['--disable-e10s'], 05:46:22 INFO - 'tests': ['tp5o']}, 05:46:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:22 INFO - 'tests': ['tp5o']}, 05:46:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:22 INFO - 'talos_options': ['--disable-e10s', 05:46:22 INFO - '--xperf_path', 05:46:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:46:22 INFO - 'tests': ['tp5n']}, 05:46:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:22 INFO - 'talos_options': ['--xperf_path', 05:46:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:46:22 INFO - 'tests': ['tp5n']}}, 05:46:22 INFO - 'talos.zip': {'path': '', 05:46:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:46:22 INFO - Running post-action listener: _resource_record_post_action 05:46:22 INFO - ##### 05:46:22 INFO - ##### Running create-virtualenv step. 05:46:22 INFO - ##### 05:46:22 INFO - Running pre-action listener: _resource_record_pre_action 05:46:22 INFO - Running main action method: create_virtualenv 05:46:22 INFO - Creating virtualenv /builds/slave/test/build/venv 05:46:22 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 05:46:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:46:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:46:23 INFO - Using real prefix '/tools/python27' 05:46:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:46:23 INFO - Installing distribute.............................................................................................................................................................................................done. 05:46:26 INFO - Installing pip.................done. 05:46:26 INFO - Return code: 0 05:46:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:46:26 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')]} 05:46:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:26 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')]} 05:46:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:26 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 0x10c51fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c52f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95c04b9390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3bee00>, '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 0x10c1e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c3b9b20>, '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.XJaqX2Yxcy/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.Ca3mnil4RT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 05:46:26 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 05:46:26 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 05:46:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XJaqX2Yxcy/Render', 05:46:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:26 INFO - 'HOME': '/Users/cltbld', 05:46:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:26 INFO - 'LOGNAME': 'cltbld', 05:46:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:26 INFO - 'MOZ_NO_REMOTE': '1', 05:46:26 INFO - 'NO_EM_RESTART': '1', 05:46:26 INFO - 'PAGER': '/bin/cat', 05:46:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:26 INFO - 'PWD': '/builds/slave/test', 05:46:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:26 INFO - 'SHELL': '/bin/bash', 05:46:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ca3mnil4RT/Listeners', 05:46:26 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 05:46:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:26 INFO - 'USER': 'cltbld', 05:46:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:26 INFO - 'XPC_FLAGS': '0x0', 05:46:26 INFO - 'XPC_SERVICE_NAME': '0', 05:46:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:26 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 05:46:26 INFO - Cleaning up... 05:46:26 INFO - Return code: 0 05:46:26 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:46:26 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')]} 05:46:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:26 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')]} 05:46:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:26 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 0x10c51fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c52f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95c04b9390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3bee00>, '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 0x10c1e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c3b9b20>, '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.XJaqX2Yxcy/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.Ca3mnil4RT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 05:46:26 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 05:46:26 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 05:46:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XJaqX2Yxcy/Render', 05:46:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:26 INFO - 'HOME': '/Users/cltbld', 05:46:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:26 INFO - 'LOGNAME': 'cltbld', 05:46:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:26 INFO - 'MOZ_NO_REMOTE': '1', 05:46:26 INFO - 'NO_EM_RESTART': '1', 05:46:26 INFO - 'PAGER': '/bin/cat', 05:46:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:26 INFO - 'PWD': '/builds/slave/test', 05:46:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:26 INFO - 'SHELL': '/bin/bash', 05:46:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ca3mnil4RT/Listeners', 05:46:26 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 05:46:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:26 INFO - 'USER': 'cltbld', 05:46:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:26 INFO - 'XPC_FLAGS': '0x0', 05:46:26 INFO - 'XPC_SERVICE_NAME': '0', 05:46:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:27 INFO - Downloading/unpacking psutil>=0.7.1 05:46: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 05:46: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 05:46: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 05:46: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 05:46: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 05:46: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 05:46:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:46:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:46:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:46:29 INFO - Installing collected packages: psutil 05:46:29 INFO - Running setup.py install for psutil 05:46:29 INFO - building 'psutil._psutil_osx' extension 05:46:29 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 05:46:30 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 05:46:30 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 05:46:30 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 05:46:30 INFO - building 'psutil._psutil_posix' extension 05:46:30 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 05:46:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:46:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:46:30 INFO - ^ 05:46:30 INFO - 1 warning generated. 05:46:30 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 05:46:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:46:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:46:31 INFO - Successfully installed psutil 05:46:31 INFO - Cleaning up... 05:46:31 INFO - Return code: 0 05:46:31 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:46:31 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')]} 05:46:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:31 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')]} 05:46:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:31 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 0x10c51fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c52f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95c04b9390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3bee00>, '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 0x10c1e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c3b9b20>, '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.XJaqX2Yxcy/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.Ca3mnil4RT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 05:46:31 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 05:46:31 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 05:46:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XJaqX2Yxcy/Render', 05:46:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:31 INFO - 'HOME': '/Users/cltbld', 05:46:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:31 INFO - 'LOGNAME': 'cltbld', 05:46:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:31 INFO - 'MOZ_NO_REMOTE': '1', 05:46:31 INFO - 'NO_EM_RESTART': '1', 05:46:31 INFO - 'PAGER': '/bin/cat', 05:46:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:31 INFO - 'PWD': '/builds/slave/test', 05:46:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:31 INFO - 'SHELL': '/bin/bash', 05:46:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ca3mnil4RT/Listeners', 05:46:31 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 05:46:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:31 INFO - 'USER': 'cltbld', 05:46:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:31 INFO - 'XPC_FLAGS': '0x0', 05:46:31 INFO - 'XPC_SERVICE_NAME': '0', 05:46:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:31 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:46:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:33 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:46:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:46:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:46:33 INFO - Installing collected packages: mozsystemmonitor 05:46:33 INFO - Running setup.py install for mozsystemmonitor 05:46:34 INFO - Successfully installed mozsystemmonitor 05:46:34 INFO - Cleaning up... 05:46:34 INFO - Return code: 0 05:46:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:46:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:34 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 0x10c51fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c52f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95c04b9390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3bee00>, '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 0x10c1e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c3b9b20>, '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.XJaqX2Yxcy/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.Ca3mnil4RT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 05:46:34 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 05:46:34 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 05:46:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XJaqX2Yxcy/Render', 05:46:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:34 INFO - 'HOME': '/Users/cltbld', 05:46:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:34 INFO - 'LOGNAME': 'cltbld', 05:46:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:34 INFO - 'MOZ_NO_REMOTE': '1', 05:46:34 INFO - 'NO_EM_RESTART': '1', 05:46:34 INFO - 'PAGER': '/bin/cat', 05:46:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:34 INFO - 'PWD': '/builds/slave/test', 05:46:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:34 INFO - 'SHELL': '/bin/bash', 05:46:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ca3mnil4RT/Listeners', 05:46:34 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 05:46:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:34 INFO - 'USER': 'cltbld', 05:46:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:34 INFO - 'XPC_FLAGS': '0x0', 05:46:34 INFO - 'XPC_SERVICE_NAME': '0', 05:46:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:34 INFO - Downloading/unpacking blobuploader==1.2.4 05:46:34 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 05:46:34 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 05:46:34 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 05:46:34 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 05:46:34 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 05:46:34 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 05:46:36 INFO - Downloading blobuploader-1.2.4.tar.gz 05:46:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:46:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:46:36 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 05:46:36 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 05:46:36 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 05:46:36 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 05:46:36 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 05:46:36 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 05:46:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:46:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:46:37 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 05:46:37 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 05:46:37 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 05:46:37 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 05:46:37 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 05:46:37 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 05:46:37 INFO - Downloading docopt-0.6.1.tar.gz 05:46:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:46:38 INFO - Installing collected packages: blobuploader, requests, docopt 05:46:38 INFO - Running setup.py install for blobuploader 05:46:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:46:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:46:38 INFO - Running setup.py install for requests 05:46:38 INFO - Running setup.py install for docopt 05:46:38 INFO - Successfully installed blobuploader requests docopt 05:46:38 INFO - Cleaning up... 05:46:39 INFO - Return code: 0 05:46:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:46:39 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')]} 05:46:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:39 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')]} 05:46:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:39 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 0x10c51fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c52f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95c04b9390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3bee00>, '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 0x10c1e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c3b9b20>, '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.XJaqX2Yxcy/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.Ca3mnil4RT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 05:46:39 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 05:46:39 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 05:46:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XJaqX2Yxcy/Render', 05:46:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:39 INFO - 'HOME': '/Users/cltbld', 05:46:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:39 INFO - 'LOGNAME': 'cltbld', 05:46:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:39 INFO - 'MOZ_NO_REMOTE': '1', 05:46:39 INFO - 'NO_EM_RESTART': '1', 05:46:39 INFO - 'PAGER': '/bin/cat', 05:46:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:39 INFO - 'PWD': '/builds/slave/test', 05:46:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:39 INFO - 'SHELL': '/bin/bash', 05:46:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ca3mnil4RT/Listeners', 05:46:39 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 05:46:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:39 INFO - 'USER': 'cltbld', 05:46:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:39 INFO - 'XPC_FLAGS': '0x0', 05:46:39 INFO - 'XPC_SERVICE_NAME': '0', 05:46:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:46:39 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-iH_eLP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:46:39 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-_kM09h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:46:39 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-a1mnAX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:46:39 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-qMb6gy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:46:39 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-EzZxDf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:46:39 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-mpd7R_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:46:39 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-7oHojZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:46:39 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-hTMEvQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:46:40 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-9wZ71R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:46:40 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-vPZE6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:46:40 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-2tx0eO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:46:40 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-nMYRtW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:46:40 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-JMe5EA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:46:40 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-IMo7wN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:46:40 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-knvUY2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:46:40 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-dsOsoa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:46:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:46:40 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-Slu4wo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:46:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:46:41 INFO - Running setup.py install for manifestparser 05:46:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:46:41 INFO - Running setup.py install for mozcrash 05:46:41 INFO - Running setup.py install for mozdebug 05:46:41 INFO - Running setup.py install for mozdevice 05:46:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:46:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:46:41 INFO - Running setup.py install for mozfile 05:46:41 INFO - Running setup.py install for mozhttpd 05:46:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:46:41 INFO - Running setup.py install for mozinfo 05:46:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:46:42 INFO - Running setup.py install for mozInstall 05:46:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:46:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:46:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:46:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:46:42 INFO - Running setup.py install for mozleak 05:46:42 INFO - Running setup.py install for mozlog 05:46:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:46:42 INFO - Running setup.py install for moznetwork 05:46:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:46:42 INFO - Running setup.py install for mozprocess 05:46:42 INFO - Running setup.py install for mozprofile 05:46:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:46:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:46:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:46:42 INFO - Running setup.py install for mozrunner 05:46:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:46:43 INFO - Running setup.py install for mozscreenshot 05:46:43 INFO - Running setup.py install for moztest 05:46:43 INFO - Running setup.py install for mozversion 05:46:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:46:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:46:43 INFO - Cleaning up... 05:46:43 INFO - Return code: 0 05:46:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:46:43 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')]} 05:46:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:43 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')]} 05:46:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:43 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 0x10c51fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c52f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95c04b9390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3bee00>, '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 0x10c1e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c3b9b20>, '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.XJaqX2Yxcy/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.Ca3mnil4RT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 05:46:43 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 05:46:43 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 05:46:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XJaqX2Yxcy/Render', 05:46:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:43 INFO - 'HOME': '/Users/cltbld', 05:46:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:43 INFO - 'LOGNAME': 'cltbld', 05:46:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:43 INFO - 'MOZ_NO_REMOTE': '1', 05:46:43 INFO - 'NO_EM_RESTART': '1', 05:46:43 INFO - 'PAGER': '/bin/cat', 05:46:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:43 INFO - 'PWD': '/builds/slave/test', 05:46:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:43 INFO - 'SHELL': '/bin/bash', 05:46:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ca3mnil4RT/Listeners', 05:46:43 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 05:46:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:43 INFO - 'USER': 'cltbld', 05:46:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:43 INFO - 'XPC_FLAGS': '0x0', 05:46:43 INFO - 'XPC_SERVICE_NAME': '0', 05:46:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:46:43 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-lA4GDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:46:43 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)) 05:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:46:43 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-20_8ba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:46:44 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)) 05:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:46:44 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-vUwkHW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:46:44 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)) 05:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:46:44 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-4Il6X2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:46:44 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)) 05:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:46:44 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-6Jzp7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:46:44 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)) 05:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:46:44 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-IVxf2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:46:44 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)) 05:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:46:44 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-VsusdO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:46:44 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)) 05:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:46:44 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-RarO7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:46:44 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)) 05:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:46:44 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-g38qVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:46:44 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)) 05:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:46:44 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-3152A1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:46:44 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)) 05:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:46:44 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-haluiy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:46:45 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)) 05:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:46:45 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-b513Hl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:46:45 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)) 05:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:46:45 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-FuvsBu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:46:45 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)) 05:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:46:45 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-8Wa6cq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:46:45 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)) 05:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:46:45 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-IZZxoU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:46:45 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)) 05:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:46:45 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-zp2z67-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:46:45 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)) 05:46:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:46:45 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-VId661-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:46:45 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)) 05:46:45 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)) 05:46:45 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)) 05:46:45 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)) 05:46:45 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)) 05:46:45 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)) 05:46:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:46: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 05:46: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 05:46: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 05:46: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 05:46: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 05:46: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 05:46:48 INFO - Downloading blessings-1.6.tar.gz 05:46:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:46:48 INFO - Installing collected packages: blessings 05:46:48 INFO - Running setup.py install for blessings 05:46:48 INFO - Successfully installed blessings 05:46:48 INFO - Cleaning up... 05:46:48 INFO - Return code: 0 05:46:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:46:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:46:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:46:48 INFO - Reading from file tmpfile_stdout 05:46:48 INFO - Current package versions: 05:46:48 INFO - blessings == 1.6 05:46:48 INFO - blobuploader == 1.2.4 05:46:48 INFO - docopt == 0.6.1 05:46:48 INFO - manifestparser == 1.1 05:46:48 INFO - mozInstall == 1.12 05:46:48 INFO - mozcrash == 0.17 05:46:48 INFO - mozdebug == 0.1 05:46:48 INFO - mozdevice == 0.48 05:46:48 INFO - mozfile == 1.2 05:46:48 INFO - mozhttpd == 0.7 05:46:48 INFO - mozinfo == 0.9 05:46:48 INFO - mozleak == 0.1 05:46:48 INFO - mozlog == 3.1 05:46:48 INFO - moznetwork == 0.27 05:46:48 INFO - mozprocess == 0.22 05:46:48 INFO - mozprofile == 0.28 05:46:48 INFO - mozrunner == 6.11 05:46:48 INFO - mozscreenshot == 0.1 05:46:48 INFO - mozsystemmonitor == 0.0 05:46:48 INFO - moztest == 0.7 05:46:48 INFO - mozversion == 1.4 05:46:48 INFO - psutil == 3.1.1 05:46:48 INFO - requests == 1.2.3 05:46:48 INFO - wsgiref == 0.1.2 05:46:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:46:48 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')]} 05:46:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:48 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')]} 05:46:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:48 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 0x10c51fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c52f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95c04b9390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3bee00>, '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 0x10c1e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c3b9b20>, '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.XJaqX2Yxcy/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.Ca3mnil4RT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 05:46:48 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 05:46:48 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 05:46:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XJaqX2Yxcy/Render', 05:46:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:48 INFO - 'HOME': '/Users/cltbld', 05:46:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:48 INFO - 'LOGNAME': 'cltbld', 05:46:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:48 INFO - 'MOZ_NO_REMOTE': '1', 05:46:48 INFO - 'NO_EM_RESTART': '1', 05:46:48 INFO - 'PAGER': '/bin/cat', 05:46:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:48 INFO - 'PWD': '/builds/slave/test', 05:46:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:48 INFO - 'SHELL': '/bin/bash', 05:46:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ca3mnil4RT/Listeners', 05:46:48 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 05:46:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:48 INFO - 'USER': 'cltbld', 05:46:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:48 INFO - 'XPC_FLAGS': '0x0', 05:46:48 INFO - 'XPC_SERVICE_NAME': '0', 05:46:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:48 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)) 05:46:48 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)) 05:46:48 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)) 05:46:48 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)) 05:46:48 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)) 05:46:48 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)) 05:46:48 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)) 05:46:48 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)) 05:46:48 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)) 05:46:48 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)) 05:46:48 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)) 05:46:49 INFO - Cleaning up... 05:46:49 INFO - Return code: 0 05:46:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:46:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:49 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 0x10c51fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c52f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95c04b9390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3bee00>, '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 0x10c1e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c3b9b20>, '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.XJaqX2Yxcy/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.Ca3mnil4RT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 05:46:49 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 05:46:49 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 05:46:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XJaqX2Yxcy/Render', 05:46:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:49 INFO - 'HOME': '/Users/cltbld', 05:46:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:49 INFO - 'LOGNAME': 'cltbld', 05:46:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:49 INFO - 'MOZ_NO_REMOTE': '1', 05:46:49 INFO - 'NO_EM_RESTART': '1', 05:46:49 INFO - 'PAGER': '/bin/cat', 05:46:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:49 INFO - 'PWD': '/builds/slave/test', 05:46:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:49 INFO - 'SHELL': '/bin/bash', 05:46:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ca3mnil4RT/Listeners', 05:46:49 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 05:46:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:49 INFO - 'USER': 'cltbld', 05:46:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:49 INFO - 'XPC_FLAGS': '0x0', 05:46:49 INFO - 'XPC_SERVICE_NAME': '0', 05:46:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:49 INFO - Downloading/unpacking jsonschema 05:46: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 05:46: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 05:46: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 05:46: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 05:46: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 05:46: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 05:46:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:46:52 INFO - Downloading/unpacking functools32 (from jsonschema) 05:46:52 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 05:46:52 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 05:46:52 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 05:46:52 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 05:46:52 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 05:46:52 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 05:46:52 INFO - Downloading functools32-3.2.3-2.tar.gz 05:46:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:46:52 INFO - warning: no files found matching '*.txt' 05:46:52 INFO - no previously-included directories found matching 'build' 05:46:52 INFO - no previously-included directories found matching 'dist' 05:46:52 INFO - no previously-included directories found matching '.git*' 05:46:53 INFO - Installing collected packages: jsonschema, functools32 05:46:53 INFO - Running setup.py install for functools32 05:46:53 INFO - warning: no files found matching '*.txt' 05:46:53 INFO - no previously-included directories found matching 'build' 05:46:53 INFO - no previously-included directories found matching 'dist' 05:46:53 INFO - no previously-included directories found matching '.git*' 05:46:53 INFO - Successfully installed jsonschema functools32 05:46:53 INFO - Cleaning up... 05:46:53 INFO - Return code: 0 05:46:53 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:46:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:53 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 0x10c51fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c52f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95c04b9390>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c3bee00>, '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 0x10c1e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c3b9b20>, '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.XJaqX2Yxcy/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.Ca3mnil4RT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 05:46:53 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 05:46:53 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 05:46:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XJaqX2Yxcy/Render', 05:46:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:53 INFO - 'HOME': '/Users/cltbld', 05:46:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:53 INFO - 'LOGNAME': 'cltbld', 05:46:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:53 INFO - 'MOZ_NO_REMOTE': '1', 05:46:53 INFO - 'NO_EM_RESTART': '1', 05:46:53 INFO - 'PAGER': '/bin/cat', 05:46:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:53 INFO - 'PWD': '/builds/slave/test', 05:46:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:53 INFO - 'SHELL': '/bin/bash', 05:46:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ca3mnil4RT/Listeners', 05:46:53 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 05:46:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:53 INFO - 'USER': 'cltbld', 05:46:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:53 INFO - 'XPC_FLAGS': '0x0', 05:46:53 INFO - 'XPC_SERVICE_NAME': '0', 05:46:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:53 INFO - Downloading/unpacking flake8 05:46:53 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 05:46:53 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 05:46:53 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 05:46:53 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 05:46:53 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 05:46:53 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 05:46:56 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:46:56 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:46:56 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 05:46:56 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 05:46:56 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 05:46:56 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 05:46:56 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 05:46:56 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 05:46:56 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:46:56 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:46:56 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 05:46:56 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 05:46:56 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 05:46:56 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 05:46:56 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 05:46:56 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 05:46:57 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:46:57 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 05:46:57 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 05:46:57 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 05:46:57 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 05:46:57 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 05:46:57 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 05:46:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:46:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:46:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:46:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:46:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:46:57 INFO - no previously-included directories found matching 'docs/_build' 05:46:57 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:46:57 INFO - Running setup.py install for pep8 05:46:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:46:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:46:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:46:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:46:57 INFO - no previously-included directories found matching 'docs/_build' 05:46:57 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:46:57 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:46:57 INFO - Cleaning up... 05:46:58 INFO - Return code: 0 05:46:58 INFO - Running post-action listener: _resource_record_post_action 05:46:58 INFO - Running post-action listener: _start_resource_monitoring 05:46:58 INFO - Starting resource monitoring. 05:46:58 INFO - ##### 05:46:58 INFO - ##### Running install step. 05:46:58 INFO - ##### 05:46:58 INFO - Running pre-action listener: _resource_record_pre_action 05:46:58 INFO - Running main action method: install 05:46:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:46:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:46:58 INFO - Reading from file tmpfile_stdout 05:46:58 INFO - Detecting whether we're running mozinstall >=1.0... 05:46:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:46:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:46:58 INFO - Reading from file tmpfile_stdout 05:46:58 INFO - Output received: 05:46:58 INFO - Usage: mozinstall [options] installer 05:46:58 INFO - Options: 05:46:58 INFO - -h, --help show this help message and exit 05:46:58 INFO - -d DEST, --destination=DEST 05:46:58 INFO - Directory to install application into. [default: 05:46:58 INFO - "/builds/slave/test"] 05:46:58 INFO - --app=APP Application being installed. [default: firefox] 05:46:58 INFO - mkdir: /builds/slave/test/build/application 05:46:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:46:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:47:32 INFO - Reading from file tmpfile_stdout 05:47:32 INFO - Output received: 05:47:32 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:47:32 INFO - Running post-action listener: _resource_record_post_action 05:47:32 INFO - ##### 05:47:32 INFO - ##### Running run-tests step. 05:47:32 INFO - ##### 05:47:32 INFO - Running pre-action listener: _resource_record_pre_action 05:47:32 INFO - Running main action method: run_tests 05:47:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:47:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:47:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:47:32 INFO - 2016-05-05 05:47:32.083 screenresolution[1649:7533] starting screenresolution argv=screenresolution get 05:47:32 INFO - 2016-05-05 05:47:32.085 screenresolution[1649:7533] Display 0: 1600x1200x32@60 05:47:32 INFO - 2016-05-05 05:47:32.090 screenresolution[1650:7536] starting screenresolution argv=screenresolution list 05:47:32 INFO - Available Modes on Display 0 05:47:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:47:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:47:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:47:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:47:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:47:32 INFO - Intel Iris: 05:47:32 INFO - Chipset Model: Intel Iris 05:47:32 INFO - Type: GPU 05:47:32 INFO - Bus: Built-In 05:47:32 INFO - VRAM (Dynamic, Max): 1536 MB 05:47:32 INFO - Vendor: Intel (0x8086) 05:47:32 INFO - Device ID: 0x0a2e 05:47:32 INFO - Revision ID: 0x0009 05:47:32 INFO - Displays: 05:47:32 INFO - Display: 05:47:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:47:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:47:32 INFO - Display Serial Number: 1600x1200 60 05:47:32 INFO - Main Display: Yes 05:47:32 INFO - Mirror: Off 05:47:32 INFO - Online: Yes 05:47:32 INFO - Rotation: Supported 05:47:32 INFO - Return code: 0 05:47:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:47:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:47:32 INFO - Python 2.7.3 05:47:32 INFO - Return code: 0 05:47:32 INFO - grabbing minidump binary from tooltool 05: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')]} 05:47:32 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 0x10c3bee00>, '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 0x10c1e7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c3b9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:47:32 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 05:47:32 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 05:47:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:47:32 INFO - Return code: 0 05:47:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:47:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:47:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:47:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:47:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:47:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:47:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:47:33 INFO - Return code: 0 05:47:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0180', '--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 05:47:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0180 --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 05:47:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XJaqX2Yxcy/Render', 05:47:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:33 INFO - 'HOME': '/Users/cltbld', 05:47:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:33 INFO - 'LOGNAME': 'cltbld', 05:47:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:47:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:47:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:33 INFO - 'MOZ_NO_REMOTE': '1', 05:47:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:47:33 INFO - 'NO_EM_RESTART': '1', 05:47:33 INFO - 'PAGER': '/bin/cat', 05:47:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:33 INFO - 'PWD': '/builds/slave/test', 05:47:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:47:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:33 INFO - 'SHELL': '/bin/bash', 05:47:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ca3mnil4RT/Listeners', 05:47:33 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 05:47:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:33 INFO - 'USER': 'cltbld', 05:47:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:33 INFO - 'XPC_FLAGS': '0x0', 05:47:33 INFO - 'XPC_SERVICE_NAME': '0', 05:47:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0180', '--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 05:47:33 INFO - mozversion application_buildid: 20160505030327 05:47:33 INFO - mozversion application_changeset: 369a5ee3a2880a4a98df3a00bf3db8d8f36b181b 05:47:33 INFO - mozversion application_display_name: Nightly 05:47:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:47:33 INFO - mozversion application_name: Firefox 05:47:33 INFO - mozversion application_remotingname: firefox 05:47:33 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:47:33 INFO - mozversion application_vendor: Mozilla 05:47:33 INFO - mozversion application_version: 49.0a1 05:47:33 INFO - mozversion platform_buildid: 20160505030327 05:47:33 INFO - mozversion platform_changeset: 369a5ee3a2880a4a98df3a00bf3db8d8f36b181b 05:47:33 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:47:33 INFO - mozversion platform_version: 49.0a1 05:47:33 INFO - using testdate: 1462452453 05:47:33 INFO - actual date: 1462452453 05:47:33 INFO - starting webserver on 'localhost:49271' 05:47:33 INFO - SUITE-START | Running 2 tests 05:47:33 INFO - TEST-START | dromaeo_css 05:47:33 INFO - operating with platform_type : mac_ 05:47:33 INFO - Initialising browser for dromaeo_css test... 05:47:33 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpy5wytU/profile http://localhost:49271/getInfo.html) 05:47:36 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 05:47:36 INFO - PROCESS | 1668 | colorDepth:24 05:47:36 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 05:47:36 INFO - PROCESS | 1668 | __metrics 05:47:36 INFO - PROCESS | 1668 | 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" 05:47:36 ERROR - PROCESS | 1668 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:47:37 INFO - TEST-INFO | 1668: exit 0 05:47:37 INFO - Browser initialized. 05:47:37 INFO - Running cycle 1/1 for dromaeo_css test... 05:47:37 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpy5wytU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:47:49 INFO - PROCESS | 1671 | RSS: Main: 239579136 05:47:49 INFO - PROCESS | 1671 | 05:48:40 INFO - PROCESS | 1671 | 1462452520297 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] 05:50:50 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 05:50:51 INFO - PROCESS | 1671 | RSS: Main: 329175040 05:50:51 INFO - PROCESS | 1671 | 05:53:53 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 05:53:54 INFO - PROCESS | 1671 | RSS: Main: 375386112 05:53:54 INFO - PROCESS | 1671 | 05:56:56 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 05:56:57 INFO - PROCESS | 1671 | RSS: Main: 369635328 05:56:57 INFO - PROCESS | 1671 | 05:59:59 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 06:00:00 INFO - PROCESS | 1671 | RSS: Main: 369909760 06:00:00 INFO - PROCESS | 1671 | 06:03:03 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 06:03:04 INFO - PROCESS | 1671 | RSS: Main: 334569472 06:03:04 INFO - PROCESS | 1671 | 06:06:07 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 06:06:07 INFO - PROCESS | 1671 | RSS: Main: 375611392 06:06:07 INFO - PROCESS | 1671 | 06:06:07 INFO - PROCESS | 1671 | __start_tp_report 06:06:07 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 06:06:07 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 06:06:07 INFO - PROCESS | 1671 | |i|pagename|runs| 06:06:07 INFO - PROCESS | 1671 | |0;dojo.html;2889.422115576885;3367.10771645514;3417.026147547948;3454.032870796178;3483.4426491761305;5517.337919449662;5593;5593.272874526311;5595.972020139899;5613.4105918878495;5630.042892708239;5639.351474580424;5692.57305702072;5754.65472071675;5756.625819321741;4635.771520547058;4670.929936050945;5035.144025515671;5116.232565115225;5261.184516399945;5159.1229490986625;5179.585633149339;5192.844214673566;5207.427183009866;5212.035773381917;4958.900821983558;5017.422996355411;5058.721770302632;5133.768980395891;5138.794448222066;5072;5125;5135.8202462914;5171.663841850268;5186.870328241786;5057.4689657585795;5090.9236361454605;5107.7446127693465;5124.103281925648;5172.508611681884;5419.593530485229;5527.50252477275;5542.140968149944;5545.279113715191;5546.750396232139;4951.43058548268;6097.146399504073;6146.78486252979;6153.230846144232;6167.229096362955;6147.293061297919;6166.07508873668;6197.96901015492;6214.0989556514505;6216.254049514042;5683.976884160849;5706.087026075808;5706.115552089433;5711.171880077365;5750.367459579526;6101.85309287306;6176.4441200292185;6204.5967012144065;6264.749410023639;6278.340774218733;5932.436418540232;5978.192943952511;6013.428724271187;6025.0359942409;6067.696615169312;6074.878502430015;6136.171616831671;6140.109684095781;6154.630722156685;6155.938440615537;5888.528917686661;5989.221401217814;6045.546584006217;6083.239595050619;6117.979653188387;6085.786997455157;6104.755809767648;6134.877302454015;6143.1706719592285;6144.2319710036245;4795.832145874948;4796.56830885222;4799.808007679723;4803.135435621552;4870.853874383774;4315.5900189481945;4362.520122786491;4374.081442896963;4408.140412619572;4428.3800267962515;5479.4520547945685;5555.8888822223325;5582.246396736388;5594.1608758686525;5619.353774315924;6116.981545479012;6130.979101202072;6133.916185115316;6135.233095863017;6143.941779503916;5556.388797232301;5569.248151499577;5577.44225577439;5580.051391263495;5591.30108736408;5174.53429191367;5187.559057480156;5192.870178245574;5233.816816411483;5242.16125419931;6265.686715664289;6272.090546870678;6274.466670332981;6280.214973128359;6286.779962701375;6143.907841382294;6153.600015998945;6154.415330543591;6155.815325540241;6169.413905678954;6149.292831324455;6199.56603037783;6222.315545289924;6244.718987645658;6285.811425657238;6028.2766068072115;6042.093685947143;6061.818145455643;6070.575059745775;6088.55262568827;6180.567360284737;6183.752649894134;6185.84535386619;6193.256809182927;6195.132681424694;5837.503812176013;5914.497267732291;5987.670678112571;5989.880202396015;6042.395760423922;5732.140178973187;5896.764129434775;5898.085796701518;5905.232319798399;5920.37836027211;5648.2092507049865;5744.224529688438;5748.396418376177;5762.510186634016;5773.769049237983;5937.079752638348;5939.3466718661775;5967.791127310524;5975.43233392827;5996.190514280516;5788.8552786179;5790.4076378996115;5807.680577568274;5814.5639077068545;5851.356350801323 06:06:07 INFO - PROCESS | 1671 | |1;ext.html;496.5927939089947;512.5929324005605;525.72924943654;586.1178925716796;625.4996003197447;37332.626673733255;38956.415653765216;38974.8051259744;39018.80490597546;39056.60943390565;43079.35380969288;43735.90660233496;43759.78120109403;43779.90550236245;43784.343234851505;9487.098725621072;9751.658691945784;9753.902460975405;9763.707088787347;9764.170045546138;9588.856167157499;9729.659461918834;9738.026197380275;9742.902570974305;9769.609215631366;9697.466639334834;9769.951150244275;9774.511274436285;9775.364601300927;9776.16902563283;9645;9696.757581060494;9713.32006759527;9721.951390243003;9732;9453.243740500744;9597.992210817833;9599.616015359377;9608.2793790466;9613.471259080748;39732.80133599343;39791.60208397908;40389.79805100956;40396.79801601003;40634.59365406338;3186.2193762528095;3252.9512057319157;3337.599488061424;3661.7986010769396;3695.9678542300794;27972.720272797214;28892;29789.255268618243;29833;29930.802767889258;2682.865856707157;2769.2246171072134;2807.7894157938235;2828.3918957424253;2922.0649392194505;489637;492676;493107.534462329;493558;494028;62482.68758656178;62866;63258.36741632617;63338;63374;65439;65627;65627;65640.67179664072;65685.68628627497;16912.746308805377;17147.571310717332;17148;17150.828491715172;17159.828401716073;13051.804222936664;13181.802272965913;13188.274644894624;13194.934025330003;13212.405441755143;15585.766213506806;16802.411915582896;16827.906186097865;16927;16953.40663076811;5491.9725401373535;5784.566157538117;6767.154105736783;6867.622280774505;7015.403690686348;798.62863768348;937.4984383355009;1014.9140419750796;1020.7856350166549;1070.202698989258;25778.613320800203;26584.468310633678;28593.14220573386;28963.855180724382;29030.41939161247;1095.5508241621058;1141.4406940599047;1170.6371024982284;1223.1315765806198;1292.5605294200018;14037;14673.559793206221;14732.212031089939;14980.62548436276;15214.011089279162;640600;650428;650960;651910;652920;60030.69984650136;61869;61984.69007655023;62025;62101.75796484135;1383.889288856889;1385.0858433433884;1425.5509514502899;1515.0682330366744;1532.578540901261;23381.883090584437;27685.723142768315;28524.57213141763;29315.560266595592;29908.700912990593;53671;53986.92026159533;54274.185887212516;54277;54322.91354172974;363428;366430.16784915904;366542;368039;369230.15384922904;37111.24775659777;37736.81131594325;38654.42018369783;38727.6127238724;38795.418068728424;44426.333604996595;44788.552114478436;44902.32646510239;45149.77425112985;46489.30266045944;37558;37615.81192094022;37660.43509347307;38071.11137603208;38594;36650.63349366579;37057.62942370542;37643.811780942015;37658.8117059413;37728.622713773606;35975.820120899225;36397.45403818892;36790.26419471649;37116.81441592775;37613.623863761015;31359.21601959969;32253.51619725659;32299;32355.19112022218;32552.674473254963;18915.064230431944;23798.524029518965;24607;24631.87684061568;24778.663733846144 06:06:07 INFO - PROCESS | 1671 | |2;jquery.html;350.7054074577355;397.7136461747543;399.33710041331375;400.27350359098233;407.5330082190708;13949.721005579882;20237.797622023776;20563.588728225463;21119.472013199676;21134.260300889473;15174.696506069871;17197.656046879085;17489.950602963832;20264.797352026475;20873.582528349423;5745.425457454253;5997.430244126801;6055.697215139247;6061.242344706911;6063.969680151593;5428.511433970942;6013.789517366893;6055.122007308938;6058.818235452944;7863.606819658994;4534.31985202219;4842.782074806642;7370.594617296047;7778.377729781604;7785.883211751829;7716.228377162239;7749.535027898316;7769.223077692242;7779.260970207878;7797.025371828521;5463.863403414906;7257.963710181468;7461.925380746177;7469.962650186714;7517.210692877278;28823.85588072046;28917.710822891713;29227.41545169106;29917.700822991927;30202.395952080835;2229.230915334207;2230.7657695942016;2288.7940085392393;2349.5300939812105;2422.685050943366;15699.764503532342;15755.684886302324;15814.367425302975;15965.521034368987;16650.58373540671;1753.1935309757528;1849.537615596101;1918.808119188084;1945.3969269526492;1947.094601010537;149342;149366.25316873347;149918.25040874726;152028.47971520253;154100.99999999886;16647.50057498277;21471;22022.559548808935;22357.10571577151;22723.772762272496;37379.69171079054;37380.252394951945;37464;37527;37541.436878446846;11059.944700276446;11287.153463490107;11310.151738619472;11358.375289359165;11438.142139339583;9135;9136.08639136081;9154.221891139328;9155.908440915638;9278.443293402417;7754.651040703069;9775.51122443875;9953.203743700487;9954.104130628279;9959.850602240973;3091.969080309168;3634.945475817812;3805.790681512488;3868.2843673920415;3896.649301562873;889.3418870036256;912.9543522823832;927.5436920594475;1009.0666133826181;1034.2760067952463;19588.314408995902;20531.178752850017;20743.896280518802;20777.896110519352;21000;953.7472569769017;989.1938070472542;998.4308943901906;1005.2360206243308;1047.2198212331862;10472.005159509834;11036;11086.833697494543;11101.167412443938;11127.276727012719;543393;544481;545885;596524;604081;107288.33596879237;133413.66586334206;138697.91953120712;139236;139806;1027.2655051637964;1030.0729343590829;1054.0671505717496;1055.8046761349176;1057.8201705710048;18181.909090454465;18493.075346232905;18660.34688785886;18799.154038068595;18918.621627567645;39085.04574771307;39877.2024559513;40178.59821401749;49637.75181124071;51888.48111518836;72783.63608181925;73514;73688.52622947618;74208;75582.86625700729;7544.47188696786;8203.753887383387;8375.664973401239;8468.872966905377;8520.105388934073;7195.13899262279;12130.272183669009;12667.366631668565;13095.41070651804;13104.803427948398;7334.633268336668;11064.668059958214;11191.944040280072;11332.320060796379;11337.773244534897;9401.34190606651;10056.899431005597;10834.729131771768;10995.725106872393;11028.117750579773;10883.23817332779;11124.61063862761;11273.985341319321;11286.774264514828;11301.830472543083;8155.469894456842;8259.917400825914;8371.832563348577;8382.413231073766;8403.663853445794;9529;10206.285560010729;10868.728281793019;11330.433478326215;11362 06:06:07 INFO - PROCESS | 1671 | |3;mootools.html;1418.5815184520566;1593.984060159398;1619.522240938923;1851;1975.9407217783462;7807.843843123142;8602.956985215073;8848.646054157842;8850.468971861692;8863.734087977355;7991.400644951623;8692.521911294876;8705.521196334199;8716.91283087169;8732.257758090554;2155.299527653519;2196.6814811852273;2435.305937807725;2478.50429914017;2480.7395223501544;2064.112431654388;2183.6570509136845;2378.286514045788;2407.6388541718793;2407.6990376202975;2148.452144703106;2414.070582825616;2442.3527765142253;2450.473148273121;2453.2272334214754;2086.0821238654944;2164.047818959653;2168.6963825064504;2424.32721422219;2452.6443665668467;2199.747029091659;2365.44412063165;2445.2297526279167;2448.9387765305833;2455.656208130863;7214.386777123951;8661.350398720058;8663;8666.566671666455;8669.869951950726;5156.613254005927;5371.650842695212;5400.540954018913;5413.296271484721;5419.051665958442;3489.145159435929;3546.66306700863;3621.474886141521;3626.1659818241687;3644.1436262478296;1044.7335929337971;1053.8946105389477;1053.9736506587396;1061.076863129075;1063.9361638301727;11701.70745731355;11716;11732.061435085172;11732.413379331;11738.530458781639;4766.904661906742;4779.845302100597;4907.067657145131;4934.580560652313;4977.875553111201;9938.453385063747;9964;9977.151942085004;10003.79992400148;10030.64892728751;5467.972660136674;5473.124300112043;5478.287822583039;5486.149646804752;5488.765027868698;4383.123375324884;4559.658025648089;4837.129316722953;4892.951070489249;5002.1496345621335;4014.3376342903093;4031.133306339151;4102.405151253111;4142.896427589334;4163.625273725379;1333.6799168199561;1355.9322033898266;1359.6872719274622;1365.6858922447893;1367.2890097149427;732.8204589875407;874.2525141004477;915.4964769376816;933.2533972821851;978.0366339155926;5522.6134170608475;5720.885582288331;5810.622309549865;5931.762729490858;5980.162777211194;459.91951408503377;469.572688853142;471.24130150457734;478.1990166471147;483.23889873449457;3180.3003339265324;3874.5737968823405;3891.1828516011387;4088.9386659200136;4115.32097203958;8902.154295341932;10388.896111038794;10564.471776411148;10620.575176992834;10711.946440267904;10403.47982600867;10635.680929572125;10649.893501065046;10678.252522323362;10678.878717734682;7843.450958432855;8000.35997120229;8129.105798362176;8181.71364002269;8210.30212431938;5890.823275301576;5949.672767997802;5967.343592204941;6056.848578785565;6071.483923866521;9846.015398460384;10004.749881253027;10016.449095299537;10097.192224621866;10106.949465252921;9680.854787178057;9832.508374581099;10292.845607315747;10304.587816487556;10317.380957142595;6821.761238356832;7106.182788979127;7253.419726421768;7406.814829629302;7550.395968322631;8200.58997050157;8239.958800206201;8276.172382761675;8283.792905177419;8300.294474969694;7290.927090729025;7399.297066778648;7404.296591823768;7520.548767073993;7578.12851522082;7238.23998480152;7251.5286506379025;7253.238409966877;7341.963290183515;7411.295926886937;5854.619449735754;6801.353871382211;6966.616836074065;6970.721171153097;6988.475864310095;4572.245579479349;4600.240960241523;4644.047970166175;4705.835295764632;4840.34655321527;4917.213245880642;4970.950290497049;5000.224965130265;5013.047520971004;5208.5312321891215 06:06:07 INFO - PROCESS | 1671 | |4;prototype.html;319.76497274503237;319.80332095761105;328.55808936979753;328.97861638993464;329.9158714527795;4382.671299652523;4596.310553416989;4623.468301145369;5276.894462110756;5356.214768320476;4807.735574543399;5214.113600687882;5233.1103712368895;5262.289590905227;5307.628466007381;3143.779935404523;3879.1078052048047;3897.2196968940084;4028;4078.449409329747;3379.8479068441857;3469.930601387971;4028.6575641070544;4096.508418989725;4107.794610269475;3392.0841372829323;4007.418924255981;4060.939085913714;4076.266272071026;4128.318827393477;3171.4767063434506;3398.167448974992;3879.8060096995177;4060.512738471373;4099.9180016399805;2755.3249453883727;3250.951235731466;3594.892153235381;3597.1007248187952;3605.405108157151;5038.722870242135;5066.594672426197;5143.254228136937;5181.818636347747;5183.870403239949;3584.4945123048383;3976.960230397688;4176.872244494;4253.553376895444;4459.799309031101;4788.515560176322;4815.422149342101;4882.707037577757;5543.805966791143;5544.528715059225;3152.984235078833;3297.9835100824585;3931.2137572485617;3939.606039396066;3941.408788681692;5365.061114304981;5453;5743.569232307594;5754.108113242454;5800.941990580082;3666.944995825065;3974.4833171687496;4002.5597184309704;4004.01901534126;4007.8396864125107;3531.629178936175;3608.242269123461;3869.8645547405704;3925.5878132796215;4066.4916885389325;3717.7955212463035;3916.4712763776524;4061.289274376972;4252.681048921343;4409.919569705378;4802.111609352281;5303.549198318186;5478.287822583039;5561.610687251854;5565.6104072714525;1230.839990801208;1466.9266536673124;1477.1580199286518;1480.3116550803927;1481.1927499512792;771.6296177834558;925.726910561386;933.7385532051037;954.1412728544366;987.9774433461333;4894.632902532324;4933.692571468564;5136.409312928986;5632.127020311858;5670.347909990322;6018.307894592091;6089;6104.359042300494;6132.386761323832;6206.937930620727;4621.630269578425;5460.645058071213;5577.498025177672;5598.832035038955;5656.349519805192;2792.7346902044274;3018.8075710094827;3233.7898036627544;3253.511973203991;3274.361499507573;5781.653100813965;5870.853228669232;6464.127342808661;6464.127342808754;6465.224173099258;5937.317208521075;6225.066240063936;6270.7491700331475;6295.464885484694;6311.242650881832;5925.52595792327;6357.618542887441;6364.618122912548;6377.649229292433;6421.4541763949655;4897.706137631753;5282.630215884851;5836.9493491172;6104.633721976696;6190.288116866618;4946.837493189169;5505.559555235492;5605;6000.189974353581;6017.578769486094;5668.943310567007;5811.970940145272;5887.735051922588;5987.191729116687;6201.441870231701;3881.84472621092;3889.688824893945;3898;3926.489556357655;3927.194925040417;5045.596352291735;5674.347450043133;5715.485606295453;5718.456016875503;5790.986577349031;4620.722756634613;4780.6653534253655;4786.755443584623;5201.193814958688;5614.382417934105;5299.788008479618;5613.014759520623;5619.8413174249945;5623.606347555796;5630.8175283190985;4416.138852923649;5354.036273470812;5418.891622167614;5531.391546929754;5532.2254884317035;4362.0839623679385;5274.65277951308;5492.917606235989;5645.572403866377;5690.516306113861;5040.974795126148;5054.16606259963;5219.712915789668;5427.294451721251;5557.44425557441 06:06:07 INFO - PROCESS | 1671 | |5;yui.html;657.8125234308224;673.4578664175339;725.5972935021065;762.1007211490439;764.705588348486;14927.179005154725;15241.16173610451;15245.009074410184;15257.389704411824;15263.923680381611;14003.789943150861;14064.22646754428;14068.648283792885;14086.436542538311;14089.64775880601;908.2098574240387;947.7062110745691;950.0546955779009;951.4861974533743;952.7094236257939;935.0602644342426;941.4586612697692;944.8157609266195;945.4941606240662;950.1875896108832;998.4059484606647;1001.7445551384424;1002.6390499420203;1004.678502879079;1006;979.7893452907659;984.3109823123806;984.4880662055691;991.990080099197;996.118435184863;738.9113306403266;739.1204466684682;740.9925900740977;741.3772431157853;744.9553026818354;13299.800502992462;15359.539213823604;15362.769558456632;15381.846181538152;15393.999390039611;12215.755684886253;12309.384530773426;12332.321722305269;12477.401440314854;12921.483140674361;9894;10040.196784257241;10065.647702330456;10280.485975701185;10377.221708371902;3422.247105636756;3514.3147086318177;3529.9117522061897;3647.379945409287;3673.834677439522;55760.442395576334;58116.12825807616;60941.69529152326;67892.3026924326;81582;9241.907580924239;9310.906890931004;9327.486988215713;9478.194353479894;9940.055694709135;20412.15215624479;20627.278045268344;20751.169953201705;20785.960701964843;21243;7455.888161677579;7460.776176714708;7473.6263186841525;7493.668866776926;7523.661435235427;3630.4009838376373;4028.5971402860064;4052.8378864845668;4063.817128229237;4093.8976525586504;3387.508811222359;3439.2605589798313;3445.913852153666;3450.2581944881467;3456.654334566523;540.5756481162348;644.407145426209;646.9094326794252;650.8600650860088;674.2684187656448;546.2625455634941;577.5264283287664;601.5368166511848;611.547454883392;613.2916481463916;7899.881501777478;8186.672533098609;8244.83510329802;8385.706500272583;8431.853001502055;550.4797965922245;572.2903599536545;599.9460048595647;601.1073555769678;628.0798630006971;3643.617420170897;3900.8829735107993;3960.148568057881;4000.3799411091327;4105.67154627629;76257;78007.21992780112;84717.57641211878;85546;89877;73823;74806.90879096136;74835.25164748392;75761;75965.62017189879;54235.72882135643;62783.6860815693;65896.35259118656;68713.65643171853;71533.56932861418;8139.918600814153;8237.876431853647;8276.751697449086;8357.289630381485;8420.031696354752;42164.36753448762;43500.564994349654;44128;44348.33477497905;44826.327605085295;43741.125177496906;44574.3313850299;56051.43948560462;61800.381996179465;62239.688801555705;10870.728231794268;11100.777984440427;11154.107671386431;11441.42792860337;11976.880231197925;12061.698457538632;12193;12248.938755306166;12263.570775022832;12358.876411236131;11250.66248012561;11338;11380.317180969168;11785.116116291141;11871.16901816899;11297.71755706114;11346;11575.363355015219;11601.825972610586;11850.288982661068;9953.850692239477;10003.671618390032;10882.61910833117;10959.83560246581;12197.329146897006;9322.347435679709;9538.809223815346;9639.257924643975;9711.098540109899;10137;9315.208207302456;9542.618295268388;9557;9798.363106398061;9857.556409961426 06:06:07 INFO - PROCESS | 1671 | __end_tp_report 06:06:07 INFO - PROCESS | 1671 | __start_cc_report 06:06:07 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1157 06:06:07 INFO - PROCESS | 1671 | __end_cc_report 06:06:07 INFO - PROCESS | 1671 | __startTimestamp1462453567393__endTimestamp 06:06:07 INFO - PROCESS | 1671 | 06:06:07 INFO - PROCESS | 1671 | ------- Summary: start ------- 06:06:07 INFO - PROCESS | 1671 | Number of tests: 6 06:06:07 INFO - PROCESS | 1671 | 06:06:07 INFO - PROCESS | 1671 | [#0] dojo.html Cycles:165 Average:5628.90 Median:5788.86 stddev:636.17 (11.0%) stddev-sans-first:600.73 06:06:07 INFO - PROCESS | 1671 | Values: 2889.4 3367.1 3417.0 3454.0 3483.4 5517.3 5593.0 5593.3 5596.0 5613.4 5630.0 5639.4 5692.6 5754.7 5756.6 4635.8 4670.9 5035.1 5116.2 5261.2 5159.1 5179.6 5192.8 5207.4 5212.0 4958.9 5017.4 5058.7 5133.8 5138.8 5072.0 5125.0 5135.8 5171.7 5186.9 5057.5 5090.9 5107.7 5124.1 5172.5 5419.6 5527.5 5542.1 5545.3 5546.8 4951.4 6097.1 6146.8 6153.2 6167.2 6147.3 6166.1 6198.0 6214.1 6216.3 5684.0 5706.1 5706.1 5711.2 5750.4 6101.9 6176.4 6204.6 6264.7 6278.3 5932.4 5978.2 6013.4 6025.0 6067.7 6074.9 6136.2 6140.1 6154.6 6155.9 5888.5 5989.2 6045.5 6083.2 6118.0 6085.8 6104.8 6134.9 6143.2 6144.2 4795.8 4796.6 4799.8 4803.1 4870.9 4315.6 4362.5 4374.1 4408.1 4428.4 5479.5 5555.9 5582.2 5594.2 5619.4 6117.0 6131.0 6133.9 6135.2 6143.9 5556.4 5569.2 5577.4 5580.1 5591.3 5174.5 5187.6 5192.9 5233.8 5242.2 6265.7 6272.1 6274.5 6280.2 6286.8 6143.9 6153.6 6154.4 6155.8 6169.4 6149.3 6199.6 6222.3 6244.7 6285.8 6028.3 6042.1 6061.8 6070.6 6088.6 6180.6 6183.8 6185.8 6193.3 6195.1 5837.5 5914.5 5987.7 5989.9 6042.4 5732.1 5896.8 5898.1 5905.2 5920.4 5648.2 5744.2 5748.4 5762.5 5773.8 5937.1 5939.3 5967.8 5975.4 5996.2 5788.9 5790.4 5807.7 5814.6 5851.4 06:06:07 INFO - PROCESS | 1671 | 06:06:07 INFO - PROCESS | 1671 | [#1] ext.html Cycles:180 Average:64947.90 Median:38624.21 stddev:137838.73 (356.9%) stddev-sans-first:138140.46 06:06:07 INFO - PROCESS | 1671 | Values: 496.6 512.6 525.7 586.1 625.5 37332.6 38956.4 38974.8 39018.8 39056.6 43079.4 43735.9 43759.8 43779.9 43784.3 9487.1 9751.7 9753.9 9763.7 9764.2 9588.9 9729.7 9738.0 9742.9 9769.6 9697.5 9770.0 9774.5 9775.4 9776.2 9645.0 9696.8 9713.3 9722.0 9732.0 9453.2 9598.0 9599.6 9608.3 9613.5 39732.8 39791.6 40389.8 40396.8 40634.6 3186.2 3253.0 3337.6 3661.8 3696.0 27972.7 28892.0 29789.3 29833.0 29930.8 2682.9 2769.2 2807.8 2828.4 2922.1 489637.0 492676.0 493107.5 493558.0 494028.0 62482.7 62866.0 63258.4 63338.0 63374.0 65439.0 65627.0 65627.0 65640.7 65685.7 16912.7 17147.6 17148.0 17150.8 17159.8 13051.8 13181.8 13188.3 13194.9 13212.4 15585.8 16802.4 16827.9 16927.0 16953.4 5492.0 5784.6 6767.2 6867.6 7015.4 798.6 937.5 1014.9 1020.8 1070.2 25778.6 26584.5 28593.1 28963.9 29030.4 1095.6 1141.4 1170.6 1223.1 1292.6 14037.0 14673.6 14732.2 14980.6 15214.0 640600.0 650428.0 650960.0 651910.0 652920.0 60030.7 61869.0 61984.7 62025.0 62101.8 1383.9 1385.1 1425.6 1515.1 1532.6 23381.9 27685.7 28524.6 29315.6 29908.7 53671.0 53986.9 54274.2 54277.0 54322.9 363428.0 366430.2 366542.0 368039.0 369230.2 37111.2 37736.8 38654.4 38727.6 38795.4 44426.3 44788.6 44902.3 45149.8 46489.3 37558.0 37615.8 37660.4 38071.1 38594.0 36650.6 37057.6 37643.8 37658.8 37728.6 35975.8 36397.5 36790.3 37116.8 37613.6 31359.2 32253.5 32299.0 32355.2 32552.7 18915.1 23798.5 24607.0 24631.9 24778.7 06:06:07 INFO - PROCESS | 1671 | 06:06:07 INFO - PROCESS | 1671 | [#2] jquery.html Cycles:180 Average:36245.40 Median:28870.78 stddev:95778.42 (331.7%) stddev-sans-first:96009.19 06:06:07 INFO - PROCESS | 1671 | Values: 350.7 397.7 399.3 400.3 407.5 13949.7 20237.8 20563.6 21119.5 21134.3 15174.7 17197.7 17490.0 20264.8 20873.6 5745.4 5997.4 6055.7 6061.2 6064.0 5428.5 6013.8 6055.1 6058.8 7863.6 4534.3 4842.8 7370.6 7778.4 7785.9 7716.2 7749.5 7769.2 7779.3 7797.0 5463.9 7258.0 7461.9 7470.0 7517.2 28823.9 28917.7 29227.4 29917.7 30202.4 2229.2 2230.8 2288.8 2349.5 2422.7 15699.8 15755.7 15814.4 15965.5 16650.6 1753.2 1849.5 1918.8 1945.4 1947.1 149342.0 149366.3 149918.3 152028.5 154101.0 16647.5 21471.0 22022.6 22357.1 22723.8 37379.7 37380.3 37464.0 37527.0 37541.4 11059.9 11287.2 11310.2 11358.4 11438.1 9135.0 9136.1 9154.2 9155.9 9278.4 7754.7 9775.5 9953.2 9954.1 9959.9 3092.0 3634.9 3805.8 3868.3 3896.6 889.3 913.0 927.5 1009.1 1034.3 19588.3 20531.2 20743.9 20777.9 21000.0 953.7 989.2 998.4 1005.2 1047.2 10472.0 11036.0 11086.8 11101.2 11127.3 543393.0 544481.0 545885.0 596524.0 604081.0 107288.3 133413.7 138697.9 139236.0 139806.0 1027.3 1030.1 1054.1 1055.8 1057.8 18181.9 18493.1 18660.3 18799.2 18918.6 39085.0 39877.2 40178.6 49637.8 51888.5 72783.6 73514.0 73688.5 74208.0 75582.9 7544.5 8203.8 8375.7 8468.9 8520.1 7195.1 12130.3 12667.4 13095.4 13104.8 7334.6 11064.7 11191.9 11332.3 11337.8 9401.3 10056.9 10834.7 10995.7 11028.1 10883.2 11124.6 11274.0 11286.8 11301.8 8155.5 8259.9 8371.8 8382.4 8403.7 9529.0 10206.3 10868.7 11330.4 11362.0 06:06:07 INFO - PROCESS | 1671 | 06:06:07 INFO - PROCESS | 1671 | [#3] mootools.html Cycles:180 Average:5659.77 Median:2660.18 stddev:3177.56 (119.4%) stddev-sans-first:3170.48 06:06:07 INFO - PROCESS | 1671 | Values: 1418.6 1594.0 1619.5 1851.0 1975.9 7807.8 8603.0 8848.6 8850.5 8863.7 7991.4 8692.5 8705.5 8716.9 8732.3 2155.3 2196.7 2435.3 2478.5 2480.7 2064.1 2183.7 2378.3 2407.6 2407.7 2148.5 2414.1 2442.4 2450.5 2453.2 2086.1 2164.0 2168.7 2424.3 2452.6 2199.7 2365.4 2445.2 2448.9 2455.7 7214.4 8661.4 8663.0 8666.6 8669.9 5156.6 5371.7 5400.5 5413.3 5419.1 3489.1 3546.7 3621.5 3626.2 3644.1 1044.7 1053.9 1054.0 1061.1 1063.9 11701.7 11716.0 11732.1 11732.4 11738.5 4766.9 4779.8 4907.1 4934.6 4977.9 9938.5 9964.0 9977.2 10003.8 10030.6 5468.0 5473.1 5478.3 5486.1 5488.8 4383.1 4559.7 4837.1 4893.0 5002.1 4014.3 4031.1 4102.4 4142.9 4163.6 1333.7 1355.9 1359.7 1365.7 1367.3 732.8 874.3 915.5 933.3 978.0 5522.6 5720.9 5810.6 5931.8 5980.2 459.9 469.6 471.2 478.2 483.2 3180.3 3874.6 3891.2 4088.9 4115.3 8902.2 10388.9 10564.5 10620.6 10711.9 10403.5 10635.7 10649.9 10678.3 10678.9 7843.5 8000.4 8129.1 8181.7 8210.3 5890.8 5949.7 5967.3 6056.8 6071.5 9846.0 10004.7 10016.4 10097.2 10106.9 9680.9 9832.5 10292.8 10304.6 10317.4 6821.8 7106.2 7253.4 7406.8 7550.4 8200.6 8240.0 8276.2 8283.8 8300.3 7290.9 7399.3 7404.3 7520.5 7578.1 7238.2 7251.5 7253.2 7342.0 7411.3 5854.6 6801.4 6966.6 6970.7 6988.5 4572.2 4600.2 4644.0 4705.8 4840.3 4917.2 4971.0 5000.2 5013.0 5208.5 06:06:07 INFO - PROCESS | 1671 | 06:06:07 INFO - PROCESS | 1671 | [#4] prototype.html Cycles:180 Average:4546.63 Median:5049.88 stddev:1447.85 (28.7%) stddev-sans-first:1416.72 06:06:07 INFO - PROCESS | 1671 | Values: 319.8 319.8 328.6 329.0 329.9 4382.7 4596.3 4623.5 5276.9 5356.2 4807.7 5214.1 5233.1 5262.3 5307.6 3143.8 3879.1 3897.2 4028.0 4078.4 3379.8 3469.9 4028.7 4096.5 4107.8 3392.1 4007.4 4060.9 4076.3 4128.3 3171.5 3398.2 3879.8 4060.5 4099.9 2755.3 3251.0 3594.9 3597.1 3605.4 5038.7 5066.6 5143.3 5181.8 5183.9 3584.5 3977.0 4176.9 4253.6 4459.8 4788.5 4815.4 4882.7 5543.8 5544.5 3153.0 3298.0 3931.2 3939.6 3941.4 5365.1 5453.0 5743.6 5754.1 5800.9 3666.9 3974.5 4002.6 4004.0 4007.8 3531.6 3608.2 3869.9 3925.6 4066.5 3717.8 3916.5 4061.3 4252.7 4409.9 4802.1 5303.5 5478.3 5561.6 5565.6 1230.8 1466.9 1477.2 1480.3 1481.2 771.6 925.7 933.7 954.1 988.0 4894.6 4933.7 5136.4 5632.1 5670.3 6018.3 6089.0 6104.4 6132.4 6206.9 4621.6 5460.6 5577.5 5598.8 5656.3 2792.7 3018.8 3233.8 3253.5 3274.4 5781.7 5870.9 6464.1 6464.1 6465.2 5937.3 6225.1 6270.7 6295.5 6311.2 5925.5 6357.6 6364.6 6377.6 6421.5 4897.7 5282.6 5836.9 6104.6 6190.3 4946.8 5505.6 5605.0 6000.2 6017.6 5668.9 5812.0 5887.7 5987.2 6201.4 3881.8 3889.7 3898.0 3926.5 3927.2 5045.6 5674.3 5715.5 5718.5 5791.0 4620.7 4780.7 4786.8 5201.2 5614.4 5299.8 5613.0 5619.8 5623.6 5630.8 4416.1 5354.0 5418.9 5531.4 5532.2 4362.1 5274.7 5492.9 5645.6 5690.5 5041.0 5054.2 5219.7 5427.3 5557.4 06:06:07 INFO - PROCESS | 1671 | 06:06:07 INFO - PROCESS | 1671 | [#5] yui.html Cycles:180 Average:16794.71 Median:28311.86 stddev:22594.14 (79.8%) stddev-sans-first:22625.03 06:06:07 INFO - PROCESS | 1671 | Values: 657.8 673.5 725.6 762.1 764.7 14927.2 15241.2 15245.0 15257.4 15263.9 14003.8 14064.2 14068.6 14086.4 14089.6 908.2 947.7 950.1 951.5 952.7 935.1 941.5 944.8 945.5 950.2 998.4 1001.7 1002.6 1004.7 1006.0 979.8 984.3 984.5 992.0 996.1 738.9 739.1 741.0 741.4 745.0 13299.8 15359.5 15362.8 15381.8 15394.0 12215.8 12309.4 12332.3 12477.4 12921.5 9894.0 10040.2 10065.6 10280.5 10377.2 3422.2 3514.3 3529.9 3647.4 3673.8 55760.4 58116.1 60941.7 67892.3 81582.0 9241.9 9310.9 9327.5 9478.2 9940.1 20412.2 20627.3 20751.2 20786.0 21243.0 7455.9 7460.8 7473.6 7493.7 7523.7 3630.4 4028.6 4052.8 4063.8 4093.9 3387.5 3439.3 3445.9 3450.3 3456.7 540.6 644.4 646.9 650.9 674.3 546.3 577.5 601.5 611.5 613.3 7899.9 8186.7 8244.8 8385.7 8431.9 550.5 572.3 599.9 601.1 628.1 3643.6 3900.9 3960.1 4000.4 4105.7 76257.0 78007.2 84717.6 85546.0 89877.0 73823.0 74806.9 74835.3 75761.0 75965.6 54235.7 62783.7 65896.4 68713.7 71533.6 8139.9 8237.9 8276.8 8357.3 8420.0 42164.4 43500.6 44128.0 44348.3 44826.3 43741.1 44574.3 56051.4 61800.4 62239.7 10870.7 11100.8 11154.1 11441.4 11976.9 12061.7 12193.0 12248.9 12263.6 12358.9 11250.7 11338.0 11380.3 11785.1 11871.2 11297.7 11346.0 11575.4 11601.8 11850.3 9953.9 10003.7 10882.6 10959.8 12197.3 9322.3 9538.8 9639.3 9711.1 10137.0 9315.2 9542.6 9557.0 9798.4 9857.6 06:06:07 INFO - PROCESS | 1671 | -------- Summary: end -------- 06:06:07 INFO - PROCESS | 1671 | 06:06:07 INFO - TEST-INFO | 1671: exit 0 06:06:07 INFO - TEST-OK | dromaeo_css | took 1114246ms 06:06:07 INFO - TEST-START | kraken 06:06:07 INFO - operating with platform_type : mac_ 06:06:07 INFO - Initialising browser for kraken test... 06:06:07 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpkQlQIx/profile http://localhost:49271/getInfo.html) 06:06:10 INFO - PROCESS | 1680 | __metrics Screen width/height:1600/1200 06:06:10 INFO - PROCESS | 1680 | colorDepth:24 06:06:10 INFO - PROCESS | 1680 | Browser inner width/height: 1024/689 06:06:10 INFO - PROCESS | 1680 | __metrics 06:06:10 INFO - PROCESS | 1680 | 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" 06:06:10 ERROR - PROCESS | 1680 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:06:10 INFO - TEST-INFO | 1680: exit 0 06:06:10 INFO - Browser initialized. 06:06:10 INFO - Running cycle 1/1 for kraken test... 06:06:10 INFO - TEST-INFO | started process 1683 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpkQlQIx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:06:22 INFO - PROCESS | 1683 | RSS: Main: 239341568 06:06:22 INFO - PROCESS | 1683 | 06:06:25 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 06:06:26 INFO - PROCESS | 1683 | RSS: Main: 286806016 06:06:26 INFO - PROCESS | 1683 | 06:06:29 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 06:06:29 INFO - PROCESS | 1683 | RSS: Main: 295428096 06:06:29 INFO - PROCESS | 1683 | 06:06:33 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 06:06:33 INFO - PROCESS | 1683 | RSS: Main: 380977152 06:06:33 INFO - PROCESS | 1683 | 06:06:35 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 06:06:36 INFO - PROCESS | 1683 | RSS: Main: 279928832 06:06:36 INFO - PROCESS | 1683 | 06:06:38 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:06:39 INFO - PROCESS | 1683 | RSS: Main: 283820032 06:06:39 INFO - PROCESS | 1683 | 06:06:41 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 06:06:42 INFO - PROCESS | 1683 | RSS: Main: 321175552 06:06:42 INFO - PROCESS | 1683 | 06:06:45 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 06:06:46 INFO - PROCESS | 1683 | RSS: Main: 321126400 06:06:46 INFO - PROCESS | 1683 | 06:06:48 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 06:06:49 INFO - PROCESS | 1683 | RSS: Main: 321044480 06:06:49 INFO - PROCESS | 1683 | 06:06:51 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:06:51 INFO - PROCESS | 1683 | RSS: Main: 313192448 06:06:51 INFO - PROCESS | 1683 | 06:06:53 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:06:54 INFO - PROCESS | 1683 | RSS: Main: 320602112 06:06:54 INFO - PROCESS | 1683 | 06:06:56 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:06:57 INFO - PROCESS | 1683 | RSS: Main: 321851392 06:06:57 INFO - PROCESS | 1683 | 06:06:59 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:07:00 INFO - PROCESS | 1683 | RSS: Main: 318185472 06:07:00 INFO - PROCESS | 1683 | 06:07:03 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:07:04 INFO - PROCESS | 1683 | RSS: Main: 317902848 06:07:04 INFO - PROCESS | 1683 | 06:07:06 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 06:07:06 INFO - PROCESS | 1683 | RSS: Main: 382390272 06:07:06 INFO - PROCESS | 1683 | 06:07:06 INFO - PROCESS | 1683 | __start_tp_report 06:07:06 INFO - PROCESS | 1683 | _x_x_mozilla_page_load 06:07:06 INFO - PROCESS | 1683 | _x_x_mozilla_page_load_details 06:07:06 INFO - PROCESS | 1683 | |i|pagename|runs| 06:07:06 INFO - PROCESS | 1683 | |0;ai-astar;110;111;107;111;112;111;111;112;104;110 06:07:06 INFO - PROCESS | 1683 | |1;audio-beat-detection;153;120;143;186;148;169;148;120;172;140 06:07:06 INFO - PROCESS | 1683 | |2;audio-dft;183;185;185;187;182;183;183;181;182;180 06:07:06 INFO - PROCESS | 1683 | |3;audio-fft;78;71;88;90;59;58;58;82;90;55 06:07:06 INFO - PROCESS | 1683 | |4;audio-oscillator;97;99;136;77;77;79;123;81;77;127 06:07:06 INFO - PROCESS | 1683 | |5;imaging-gaussian-blur;86;84;87;84;87;84;86;84;86;86 06:07:06 INFO - PROCESS | 1683 | |6;imaging-darkroom;93;93;91;93;93;111;149;93;92;93 06:07:06 INFO - PROCESS | 1683 | |7;imaging-desaturate;85;87;86;86;83;85;83;84;87;83 06:07:06 INFO - PROCESS | 1683 | |8;json-parse-financial;61;63;62;59;59;58;59;76;60;57 06:07:06 INFO - PROCESS | 1683 | |9;json-stringify-tinderbox;63;71;71;75;71;66;67;69;68;69 06:07:06 INFO - PROCESS | 1683 | |10;stanford-crypto-aes;84;81;81;82;81;80;81;82;80;82 06:07:06 INFO - PROCESS | 1683 | |11;stanford-crypto-ccm;151;149;151;136;132;141;145;152;182;140 06:07:06 INFO - PROCESS | 1683 | |12;stanford-crypto-pbkdf2;174;168;213;226;186;186;220;176;171;172 06:07:06 INFO - PROCESS | 1683 | |13;stanford-crypto-sha256-iterative;68;80;96;101;96;89;69;73;90;94 06:07:06 INFO - PROCESS | 1683 | __end_tp_report 06:07:06 INFO - PROCESS | 1683 | __start_cc_report 06:07:06 INFO - PROCESS | 1683 | _x_x_mozilla_cycle_collect,1966 06:07:06 INFO - PROCESS | 1683 | __end_cc_report 06:07:06 INFO - PROCESS | 1683 | __startTimestamp1462453626484__endTimestamp 06:07:06 INFO - PROCESS | 1683 | 06:07:06 INFO - PROCESS | 1683 | ------- Summary: start ------- 06:07:06 INFO - PROCESS | 1683 | Number of tests: 14 06:07:06 INFO - PROCESS | 1683 | 06:07:06 INFO - PROCESS | 1683 | [#0] ai-astar Cycles:10 Average:109.90 Median:111.00 stddev:2.51 (2.3%) stddev-sans-first:2.67 06:07:06 INFO - PROCESS | 1683 | Values: 110.0 111.0 107.0 111.0 112.0 111.0 111.0 112.0 104.0 110.0 06:07:06 INFO - PROCESS | 1683 | 06:07:06 INFO - PROCESS | 1683 | [#1] audio-beat-detection Cycles:10 Average:149.90 Median:150.50 stddev:21.36 (14.2%) stddev-sans-first:22.63 06:07:06 INFO - PROCESS | 1683 | Values: 153.0 120.0 143.0 186.0 148.0 169.0 148.0 120.0 172.0 140.0 06:07:06 INFO - PROCESS | 1683 | 06:07:06 INFO - PROCESS | 1683 | [#2] audio-dft Cycles:10 Average:183.10 Median:183.00 stddev:2.08 (1.1%) stddev-sans-first:2.20 06:07:06 INFO - PROCESS | 1683 | Values: 183.0 185.0 185.0 187.0 182.0 183.0 183.0 181.0 182.0 180.0 06:07:06 INFO - PROCESS | 1683 | 06:07:06 INFO - PROCESS | 1683 | [#3] audio-fft Cycles:10 Average:72.90 Median:80.00 stddev:14.46 (18.1%) stddev-sans-first:15.22 06:07:06 INFO - PROCESS | 1683 | Values: 78.0 71.0 88.0 90.0 59.0 58.0 58.0 82.0 90.0 55.0 06:07:06 INFO - PROCESS | 1683 | 06:07:06 INFO - PROCESS | 1683 | [#4] audio-oscillator Cycles:10 Average:97.30 Median:78.00 stddev:23.29 (29.9%) stddev-sans-first:24.70 06:07:06 INFO - PROCESS | 1683 | Values: 97.0 99.0 136.0 77.0 77.0 79.0 123.0 81.0 77.0 127.0 06:07:06 INFO - PROCESS | 1683 | 06:07:06 INFO - PROCESS | 1683 | [#5] imaging-gaussian-blur Cycles:10 Average:85.40 Median:86.00 stddev:1.26 (1.5%) stddev-sans-first:1.32 06:07:06 INFO - PROCESS | 1683 | Values: 86.0 84.0 87.0 84.0 87.0 84.0 86.0 84.0 86.0 86.0 06:07:06 INFO - PROCESS | 1683 | 06:07:06 INFO - PROCESS | 1683 | [#6] imaging-darkroom Cycles:10 Average:100.10 Median:93.00 stddev:18.14 (19.5%) stddev-sans-first:19.06 06:07:06 INFO - PROCESS | 1683 | Values: 93.0 93.0 91.0 93.0 93.0 111.0 149.0 93.0 92.0 93.0 06:07:06 INFO - PROCESS | 1683 | 06:07:06 INFO - PROCESS | 1683 | [#7] imaging-desaturate Cycles:10 Average:84.90 Median:85.50 stddev:1.60 (1.9%) stddev-sans-first:1.69 06:07:06 INFO - PROCESS | 1683 | Values: 85.0 87.0 86.0 86.0 83.0 85.0 83.0 84.0 87.0 83.0 06:07:06 INFO - PROCESS | 1683 | 06:07:06 INFO - PROCESS | 1683 | [#8] json-parse-financial Cycles:10 Average:61.40 Median:60.50 stddev:5.44 (9.0%) stddev-sans-first:5.77 06:07:06 INFO - PROCESS | 1683 | Values: 61.0 63.0 62.0 59.0 59.0 58.0 59.0 76.0 60.0 57.0 06:07:06 INFO - PROCESS | 1683 | 06:07:06 INFO - PROCESS | 1683 | [#9] json-stringify-tinderbox Cycles:10 Average:69.00 Median:70.00 stddev:3.30 (4.7%) stddev-sans-first:2.69 06:07:06 INFO - PROCESS | 1683 | Values: 63.0 71.0 71.0 75.0 71.0 66.0 67.0 69.0 68.0 69.0 06:07:06 INFO - PROCESS | 1683 | 06:07:06 INFO - PROCESS | 1683 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:81.50 stddev:1.17 (1.4%) stddev-sans-first:0.78 06:07:06 INFO - PROCESS | 1683 | Values: 84.0 81.0 81.0 82.0 81.0 80.0 81.0 82.0 80.0 82.0 06:07:06 INFO - PROCESS | 1683 | 06:07:06 INFO - PROCESS | 1683 | [#11] stanford-crypto-ccm Cycles:10 Average:147.90 Median:150.00 stddev:13.80 (9.2%) stddev-sans-first:14.59 06:07:06 INFO - PROCESS | 1683 | Values: 151.0 149.0 151.0 136.0 132.0 141.0 145.0 152.0 182.0 140.0 06:07:06 INFO - PROCESS | 1683 | 06:07:06 INFO - PROCESS | 1683 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.20 Median:186.00 stddev:22.04 (11.8%) stddev-sans-first:22.68 06:07:06 INFO - PROCESS | 1683 | Values: 174.0 168.0 213.0 226.0 186.0 186.0 220.0 176.0 171.0 172.0 06:07:06 INFO - PROCESS | 1683 | 06:07:06 INFO - PROCESS | 1683 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:85.60 Median:89.50 stddev:12.16 (13.6%) stddev-sans-first:11.10 06:07:06 INFO - PROCESS | 1683 | Values: 68.0 80.0 96.0 101.0 96.0 89.0 69.0 73.0 90.0 94.0 06:07:06 INFO - PROCESS | 1683 | -------- Summary: end -------- 06:07:06 INFO - PROCESS | 1683 | 06:07:07 INFO - TEST-INFO | 1683: exit 0 06:07:07 INFO - TEST-OK | kraken | took 59296ms 06:07:07 INFO - SUITE-END | took 1173s 06:07:07 INFO - Completed test suite (00:19:33) 06:07:07 INFO - javascript benchmark 06:07:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2889.422115576885, 3367.10771645514, 3417.026147547948, 3454.032870796178, 3483.4426491761305, 5517.337919449662, 5593.0, 5593.272874526311, 5595.972020139899, 5613.4105918878495, 5630.042892708239, 5639.351474580424, 5692.57305702072, 5754.65472071675, 5756.625819321741, 4635.771520547058, 4670.929936050945, 5035.144025515671, 5116.232565115225, 5261.184516399945, 5159.1229490986625, 5179.585633149339, 5192.844214673566, 5207.427183009866, 5212.035773381917, 4958.900821983558, 5017.422996355411, 5058.721770302632, 5133.768980395891, 5138.794448222066, 5072.0, 5125.0, 5135.8202462914, 5171.663841850268, 5186.870328241786, 5057.4689657585795, 5090.9236361454605, 5107.7446127693465, 5124.103281925648, 5172.508611681884, 5419.593530485229, 5527.50252477275, 5542.140968149944, 5545.279113715191, 5546.750396232139, 4951.43058548268, 6097.146399504073, 6146.78486252979, 6153.230846144232, 6167.229096362955, 6147.293061297919, 6166.07508873668, 6197.96901015492, 6214.0989556514505, 6216.254049514042, 5683.976884160849, 5706.087026075808, 5706.115552089433, 5711.171880077365, 5750.367459579526, 6101.85309287306, 6176.4441200292185, 6204.5967012144065, 6264.749410023639, 6278.340774218733, 5932.436418540232, 5978.192943952511, 6013.428724271187, 6025.0359942409, 6067.696615169312, 6074.878502430015, 6136.171616831671, 6140.109684095781, 6154.630722156685, 6155.938440615537, 5888.528917686661, 5989.221401217814, 6045.546584006217, 6083.239595050619, 6117.979653188387, 6085.786997455157, 6104.755809767648, 6134.877302454015, 6143.1706719592285, 6144.2319710036245, 4795.832145874948, 4796.56830885222, 4799.808007679723, 4803.135435621552, 4870.853874383774, 4315.5900189481945, 4362.520122786491, 4374.081442896963, 4408.140412619572, 4428.3800267962515, 5479.4520547945685, 5555.8888822223325, 5582.246396736388, 5594.1608758686525, 5619.353774315924, 6116.981545479012, 6130.979101202072, 6133.916185115316, 6135.233095863017, 6143.941779503916, 5556.388797232301, 5569.248151499577, 5577.44225577439, 5580.051391263495, 5591.30108736408, 5174.53429191367, 5187.559057480156, 5192.870178245574, 5233.816816411483, 5242.16125419931, 6265.686715664289, 6272.090546870678, 6274.466670332981, 6280.214973128359, 6286.779962701375, 6143.907841382294, 6153.600015998945, 6154.415330543591, 6155.815325540241, 6169.413905678954, 6149.292831324455, 6199.56603037783, 6222.315545289924, 6244.718987645658, 6285.811425657238, 6028.2766068072115, 6042.093685947143, 6061.818145455643, 6070.575059745775, 6088.55262568827, 6180.567360284737, 6183.752649894134, 6185.84535386619, 6193.256809182927, 6195.132681424694, 5837.503812176013, 5914.497267732291, 5987.670678112571, 5989.880202396015, 6042.395760423922, 5732.140178973187, 5896.764129434775, 5898.085796701518, 5905.232319798399, 5920.37836027211, 5648.2092507049865, 5744.224529688438, 5748.396418376177, 5762.510186634016, 5773.769049237983, 5937.079752638348, 5939.3466718661775, 5967.791127310524, 5975.43233392827, 5996.190514280516, 5788.8552786179, 5790.4076378996115, 5807.680577568274, 5814.5639077068545, 5851.356350801323], "value": 5587.9388517973775, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.5927939089947, 512.5929324005605, 525.72924943654, 586.1178925716796, 625.4996003197447, 37332.626673733255, 38956.415653765216, 38974.8051259744, 39018.80490597546, 39056.60943390565, 43079.35380969288, 43735.90660233496, 43759.78120109403, 43779.90550236245, 43784.343234851505, 9487.098725621072, 9751.658691945784, 9753.902460975405, 9763.707088787347, 9764.170045546138, 9588.856167157499, 9729.659461918834, 9738.026197380275, 9742.902570974305, 9769.609215631366, 9697.466639334834, 9769.951150244275, 9774.511274436285, 9775.364601300927, 9776.16902563283, 9645.0, 9696.757581060494, 9713.32006759527, 9721.951390243003, 9732.0, 9453.243740500744, 9597.992210817833, 9599.616015359377, 9608.2793790466, 9613.471259080748, 39732.80133599343, 39791.60208397908, 40389.79805100956, 40396.79801601003, 40634.59365406338, 3186.2193762528095, 3252.9512057319157, 3337.599488061424, 3661.7986010769396, 3695.9678542300794, 27972.720272797214, 28892.0, 29789.255268618243, 29833.0, 29930.802767889258, 2682.865856707157, 2769.2246171072134, 2807.7894157938235, 2828.3918957424253, 2922.0649392194505, 489637.0, 492676.0, 493107.534462329, 493558.0, 494028.0, 62482.68758656178, 62866.0, 63258.36741632617, 63338.0, 63374.0, 65439.0, 65627.0, 65627.0, 65640.67179664072, 65685.68628627497, 16912.746308805377, 17147.571310717332, 17148.0, 17150.828491715172, 17159.828401716073, 13051.804222936664, 13181.802272965913, 13188.274644894624, 13194.934025330003, 13212.405441755143, 15585.766213506806, 16802.411915582896, 16827.906186097865, 16927.0, 16953.40663076811, 5491.9725401373535, 5784.566157538117, 6767.154105736783, 6867.622280774505, 7015.403690686348, 798.62863768348, 937.4984383355009, 1014.9140419750796, 1020.7856350166549, 1070.202698989258, 25778.613320800203, 26584.468310633678, 28593.14220573386, 28963.855180724382, 29030.41939161247, 1095.5508241621058, 1141.4406940599047, 1170.6371024982284, 1223.1315765806198, 1292.5605294200018, 14037.0, 14673.559793206221, 14732.212031089939, 14980.62548436276, 15214.011089279162, 640600.0, 650428.0, 650960.0, 651910.0, 652920.0, 60030.69984650136, 61869.0, 61984.69007655023, 62025.0, 62101.75796484135, 1383.889288856889, 1385.0858433433884, 1425.5509514502899, 1515.0682330366744, 1532.578540901261, 23381.883090584437, 27685.723142768315, 28524.57213141763, 29315.560266595592, 29908.700912990593, 53671.0, 53986.92026159533, 54274.185887212516, 54277.0, 54322.91354172974, 363428.0, 366430.16784915904, 366542.0, 368039.0, 369230.15384922904, 37111.24775659777, 37736.81131594325, 38654.42018369783, 38727.6127238724, 38795.418068728424, 44426.333604996595, 44788.552114478436, 44902.32646510239, 45149.77425112985, 46489.30266045944, 37558.0, 37615.81192094022, 37660.43509347307, 38071.11137603208, 38594.0, 36650.63349366579, 37057.62942370542, 37643.811780942015, 37658.8117059413, 37728.622713773606, 35975.820120899225, 36397.45403818892, 36790.26419471649, 37116.81441592775, 37613.623863761015, 31359.21601959969, 32253.51619725659, 32299.0, 32355.19112022218, 32552.674473254963, 18915.064230431944, 23798.524029518965, 24607.0, 24631.87684061568, 24778.663733846144], "value": 19756.920791549506, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.7054074577355, 397.7136461747543, 399.33710041331375, 400.27350359098233, 407.5330082190708, 13949.721005579882, 20237.797622023776, 20563.588728225463, 21119.472013199676, 21134.260300889473, 15174.696506069871, 17197.656046879085, 17489.950602963832, 20264.797352026475, 20873.582528349423, 5745.425457454253, 5997.430244126801, 6055.697215139247, 6061.242344706911, 6063.969680151593, 5428.511433970942, 6013.789517366893, 6055.122007308938, 6058.818235452944, 7863.606819658994, 4534.31985202219, 4842.782074806642, 7370.594617296047, 7778.377729781604, 7785.883211751829, 7716.228377162239, 7749.535027898316, 7769.223077692242, 7779.260970207878, 7797.025371828521, 5463.863403414906, 7257.963710181468, 7461.925380746177, 7469.962650186714, 7517.210692877278, 28823.85588072046, 28917.710822891713, 29227.41545169106, 29917.700822991927, 30202.395952080835, 2229.230915334207, 2230.7657695942016, 2288.7940085392393, 2349.5300939812105, 2422.685050943366, 15699.764503532342, 15755.684886302324, 15814.367425302975, 15965.521034368987, 16650.58373540671, 1753.1935309757528, 1849.537615596101, 1918.808119188084, 1945.3969269526492, 1947.094601010537, 149342.0, 149366.25316873347, 149918.25040874726, 152028.47971520253, 154100.99999999886, 16647.50057498277, 21471.0, 22022.559548808935, 22357.10571577151, 22723.772762272496, 37379.69171079054, 37380.252394951945, 37464.0, 37527.0, 37541.436878446846, 11059.944700276446, 11287.153463490107, 11310.151738619472, 11358.375289359165, 11438.142139339583, 9135.0, 9136.08639136081, 9154.221891139328, 9155.908440915638, 9278.443293402417, 7754.651040703069, 9775.51122443875, 9953.203743700487, 9954.104130628279, 9959.850602240973, 3091.969080309168, 3634.945475817812, 3805.790681512488, 3868.2843673920415, 3896.649301562873, 889.3418870036256, 912.9543522823832, 927.5436920594475, 1009.0666133826181, 1034.2760067952463, 19588.314408995902, 20531.178752850017, 20743.896280518802, 20777.896110519352, 21000.0, 953.7472569769017, 989.1938070472542, 998.4308943901906, 1005.2360206243308, 1047.2198212331862, 10472.005159509834, 11036.0, 11086.833697494543, 11101.167412443938, 11127.276727012719, 543393.0, 544481.0, 545885.0, 596524.0, 604081.0, 107288.33596879237, 133413.66586334206, 138697.91953120712, 139236.0, 139806.0, 1027.2655051637964, 1030.0729343590829, 1054.0671505717496, 1055.8046761349176, 1057.8201705710048, 18181.909090454465, 18493.075346232905, 18660.34688785886, 18799.154038068595, 18918.621627567645, 39085.04574771307, 39877.2024559513, 40178.59821401749, 49637.75181124071, 51888.48111518836, 72783.63608181925, 73514.0, 73688.52622947618, 74208.0, 75582.86625700729, 7544.47188696786, 8203.753887383387, 8375.664973401239, 8468.872966905377, 8520.105388934073, 7195.13899262279, 12130.272183669009, 12667.366631668565, 13095.41070651804, 13104.803427948398, 7334.633268336668, 11064.668059958214, 11191.944040280072, 11332.320060796379, 11337.773244534897, 9401.34190606651, 10056.899431005597, 10834.729131771768, 10995.725106872393, 11028.117750579773, 10883.23817332779, 11124.61063862761, 11273.985341319321, 11286.774264514828, 11301.830472543083, 8155.469894456842, 8259.917400825914, 8371.832563348577, 8382.413231073766, 8403.663853445794, 9529.0, 10206.285560010729, 10868.728281793019, 11330.433478326215, 11362.0], "value": 10817.658626467479, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1418.5815184520566, 1593.984060159398, 1619.522240938923, 1851.0, 1975.9407217783462, 7807.843843123142, 8602.956985215073, 8848.646054157842, 8850.468971861692, 8863.734087977355, 7991.400644951623, 8692.521911294876, 8705.521196334199, 8716.91283087169, 8732.257758090554, 2155.299527653519, 2196.6814811852273, 2435.305937807725, 2478.50429914017, 2480.7395223501544, 2064.112431654388, 2183.6570509136845, 2378.286514045788, 2407.6388541718793, 2407.6990376202975, 2148.452144703106, 2414.070582825616, 2442.3527765142253, 2450.473148273121, 2453.2272334214754, 2086.0821238654944, 2164.047818959653, 2168.6963825064504, 2424.32721422219, 2452.6443665668467, 2199.747029091659, 2365.44412063165, 2445.2297526279167, 2448.9387765305833, 2455.656208130863, 7214.386777123951, 8661.350398720058, 8663.0, 8666.566671666455, 8669.869951950726, 5156.613254005927, 5371.650842695212, 5400.540954018913, 5413.296271484721, 5419.051665958442, 3489.145159435929, 3546.66306700863, 3621.474886141521, 3626.1659818241687, 3644.1436262478296, 1044.7335929337971, 1053.8946105389477, 1053.9736506587396, 1061.076863129075, 1063.9361638301727, 11701.70745731355, 11716.0, 11732.061435085172, 11732.413379331, 11738.530458781639, 4766.904661906742, 4779.845302100597, 4907.067657145131, 4934.580560652313, 4977.875553111201, 9938.453385063747, 9964.0, 9977.151942085004, 10003.79992400148, 10030.64892728751, 5467.972660136674, 5473.124300112043, 5478.287822583039, 5486.149646804752, 5488.765027868698, 4383.123375324884, 4559.658025648089, 4837.129316722953, 4892.951070489249, 5002.1496345621335, 4014.3376342903093, 4031.133306339151, 4102.405151253111, 4142.896427589334, 4163.625273725379, 1333.6799168199561, 1355.9322033898266, 1359.6872719274622, 1365.6858922447893, 1367.2890097149427, 732.8204589875407, 874.2525141004477, 915.4964769376816, 933.2533972821851, 978.0366339155926, 5522.6134170608475, 5720.885582288331, 5810.622309549865, 5931.762729490858, 5980.162777211194, 459.91951408503377, 469.572688853142, 471.24130150457734, 478.1990166471147, 483.23889873449457, 3180.3003339265324, 3874.5737968823405, 3891.1828516011387, 4088.9386659200136, 4115.32097203958, 8902.154295341932, 10388.896111038794, 10564.471776411148, 10620.575176992834, 10711.946440267904, 10403.47982600867, 10635.680929572125, 10649.893501065046, 10678.252522323362, 10678.878717734682, 7843.450958432855, 8000.35997120229, 8129.105798362176, 8181.71364002269, 8210.30212431938, 5890.823275301576, 5949.672767997802, 5967.343592204941, 6056.848578785565, 6071.483923866521, 9846.015398460384, 10004.749881253027, 10016.449095299537, 10097.192224621866, 10106.949465252921, 9680.854787178057, 9832.508374581099, 10292.845607315747, 10304.587816487556, 10317.380957142595, 6821.761238356832, 7106.182788979127, 7253.419726421768, 7406.814829629302, 7550.395968322631, 8200.58997050157, 8239.958800206201, 8276.172382761675, 8283.792905177419, 8300.294474969694, 7290.927090729025, 7399.297066778648, 7404.296591823768, 7520.548767073993, 7578.12851522082, 7238.23998480152, 7251.5286506379025, 7253.238409966877, 7341.963290183515, 7411.295926886937, 5854.619449735754, 6801.353871382211, 6966.616836074065, 6970.721171153097, 6988.475864310095, 4572.245579479349, 4600.240960241523, 4644.047970166175, 4705.835295764632, 4840.34655321527, 4917.213245880642, 4970.950290497049, 5000.224965130265, 5013.047520971004, 5208.5312321891215], "value": 4489.882749685175, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.76497274503237, 319.80332095761105, 328.55808936979753, 328.97861638993464, 329.9158714527795, 4382.671299652523, 4596.310553416989, 4623.468301145369, 5276.894462110756, 5356.214768320476, 4807.735574543399, 5214.113600687882, 5233.1103712368895, 5262.289590905227, 5307.628466007381, 3143.779935404523, 3879.1078052048047, 3897.2196968940084, 4028.0, 4078.449409329747, 3379.8479068441857, 3469.930601387971, 4028.6575641070544, 4096.508418989725, 4107.794610269475, 3392.0841372829323, 4007.418924255981, 4060.939085913714, 4076.266272071026, 4128.318827393477, 3171.4767063434506, 3398.167448974992, 3879.8060096995177, 4060.512738471373, 4099.9180016399805, 2755.3249453883727, 3250.951235731466, 3594.892153235381, 3597.1007248187952, 3605.405108157151, 5038.722870242135, 5066.594672426197, 5143.254228136937, 5181.818636347747, 5183.870403239949, 3584.4945123048383, 3976.960230397688, 4176.872244494, 4253.553376895444, 4459.799309031101, 4788.515560176322, 4815.422149342101, 4882.707037577757, 5543.805966791143, 5544.528715059225, 3152.984235078833, 3297.9835100824585, 3931.2137572485617, 3939.606039396066, 3941.408788681692, 5365.061114304981, 5453.0, 5743.569232307594, 5754.108113242454, 5800.941990580082, 3666.944995825065, 3974.4833171687496, 4002.5597184309704, 4004.01901534126, 4007.8396864125107, 3531.629178936175, 3608.242269123461, 3869.8645547405704, 3925.5878132796215, 4066.4916885389325, 3717.7955212463035, 3916.4712763776524, 4061.289274376972, 4252.681048921343, 4409.919569705378, 4802.111609352281, 5303.549198318186, 5478.287822583039, 5561.610687251854, 5565.6104072714525, 1230.839990801208, 1466.9266536673124, 1477.1580199286518, 1480.3116550803927, 1481.1927499512792, 771.6296177834558, 925.726910561386, 933.7385532051037, 954.1412728544366, 987.9774433461333, 4894.632902532324, 4933.692571468564, 5136.409312928986, 5632.127020311858, 5670.347909990322, 6018.307894592091, 6089.0, 6104.359042300494, 6132.386761323832, 6206.937930620727, 4621.630269578425, 5460.645058071213, 5577.498025177672, 5598.832035038955, 5656.349519805192, 2792.7346902044274, 3018.8075710094827, 3233.7898036627544, 3253.511973203991, 3274.361499507573, 5781.653100813965, 5870.853228669232, 6464.127342808661, 6464.127342808754, 6465.224173099258, 5937.317208521075, 6225.066240063936, 6270.7491700331475, 6295.464885484694, 6311.242650881832, 5925.52595792327, 6357.618542887441, 6364.618122912548, 6377.649229292433, 6421.4541763949655, 4897.706137631753, 5282.630215884851, 5836.9493491172, 6104.633721976696, 6190.288116866618, 4946.837493189169, 5505.559555235492, 5605.0, 6000.189974353581, 6017.578769486094, 5668.943310567007, 5811.970940145272, 5887.735051922588, 5987.191729116687, 6201.441870231701, 3881.84472621092, 3889.688824893945, 3898.0, 3926.489556357655, 3927.194925040417, 5045.596352291735, 5674.347450043133, 5715.485606295453, 5718.456016875503, 5790.986577349031, 4620.722756634613, 4780.6653534253655, 4786.755443584623, 5201.193814958688, 5614.382417934105, 5299.788008479618, 5613.014759520623, 5619.8413174249945, 5623.606347555796, 5630.8175283190985, 4416.138852923649, 5354.036273470812, 5418.891622167614, 5531.391546929754, 5532.2254884317035, 4362.0839623679385, 5274.65277951308, 5492.917606235989, 5645.572403866377, 5690.516306113861, 5040.974795126148, 5054.16606259963, 5219.712915789668, 5427.294451721251, 5557.44425557441], "value": 4103.597799116231, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [657.8125234308224, 673.4578664175339, 725.5972935021065, 762.1007211490439, 764.705588348486, 14927.179005154725, 15241.16173610451, 15245.009074410184, 15257.389704411824, 15263.923680381611, 14003.789943150861, 14064.22646754428, 14068.648283792885, 14086.436542538311, 14089.64775880601, 908.2098574240387, 947.7062110745691, 950.0546955779009, 951.4861974533743, 952.7094236257939, 935.0602644342426, 941.4586612697692, 944.8157609266195, 945.4941606240662, 950.1875896108832, 998.4059484606647, 1001.7445551384424, 1002.6390499420203, 1004.678502879079, 1006.0, 979.7893452907659, 984.3109823123806, 984.4880662055691, 991.990080099197, 996.118435184863, 738.9113306403266, 739.1204466684682, 740.9925900740977, 741.3772431157853, 744.9553026818354, 13299.800502992462, 15359.539213823604, 15362.769558456632, 15381.846181538152, 15393.999390039611, 12215.755684886253, 12309.384530773426, 12332.321722305269, 12477.401440314854, 12921.483140674361, 9894.0, 10040.196784257241, 10065.647702330456, 10280.485975701185, 10377.221708371902, 3422.247105636756, 3514.3147086318177, 3529.9117522061897, 3647.379945409287, 3673.834677439522, 55760.442395576334, 58116.12825807616, 60941.69529152326, 67892.3026924326, 81582.0, 9241.907580924239, 9310.906890931004, 9327.486988215713, 9478.194353479894, 9940.055694709135, 20412.15215624479, 20627.278045268344, 20751.169953201705, 20785.960701964843, 21243.0, 7455.888161677579, 7460.776176714708, 7473.6263186841525, 7493.668866776926, 7523.661435235427, 3630.4009838376373, 4028.5971402860064, 4052.8378864845668, 4063.817128229237, 4093.8976525586504, 3387.508811222359, 3439.2605589798313, 3445.913852153666, 3450.2581944881467, 3456.654334566523, 540.5756481162348, 644.407145426209, 646.9094326794252, 650.8600650860088, 674.2684187656448, 546.2625455634941, 577.5264283287664, 601.5368166511848, 611.547454883392, 613.2916481463916, 7899.881501777478, 8186.672533098609, 8244.83510329802, 8385.706500272583, 8431.853001502055, 550.4797965922245, 572.2903599536545, 599.9460048595647, 601.1073555769678, 628.0798630006971, 3643.617420170897, 3900.8829735107993, 3960.148568057881, 4000.3799411091327, 4105.67154627629, 76257.0, 78007.21992780112, 84717.57641211878, 85546.0, 89877.0, 73823.0, 74806.90879096136, 74835.25164748392, 75761.0, 75965.62017189879, 54235.72882135643, 62783.6860815693, 65896.35259118656, 68713.65643171853, 71533.56932861418, 8139.918600814153, 8237.876431853647, 8276.751697449086, 8357.289630381485, 8420.031696354752, 42164.36753448762, 43500.564994349654, 44128.0, 44348.33477497905, 44826.327605085295, 43741.125177496906, 44574.3313850299, 56051.43948560462, 61800.381996179465, 62239.688801555705, 10870.728231794268, 11100.777984440427, 11154.107671386431, 11441.42792860337, 11976.880231197925, 12061.698457538632, 12193.0, 12248.938755306166, 12263.570775022832, 12358.876411236131, 11250.66248012561, 11338.0, 11380.317180969168, 11785.116116291141, 11871.16901816899, 11297.71755706114, 11346.0, 11575.363355015219, 11601.825972610586, 11850.288982661068, 9953.850692239477, 10003.671618390032, 10882.61910833117, 10959.83560246581, 12197.329146897006, 9322.347435679709, 9538.809223815346, 9639.257924643975, 9711.098540109899, 10137.0, 9315.208207302456, 9542.618295268388, 9557.0, 9798.363106398061, 9857.556409961426], "value": 6793.594738810492, "unit": "score"}], "name": "dromaeo_css", "value": 7285.209422214684, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 107.0, 111.0, 112.0, 111.0, 111.0, 112.0, 104.0, 110.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 120.0, 143.0, 186.0, 148.0, 169.0, 148.0, 120.0, 172.0, 140.0], "value": 149.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 185.0, 187.0, 182.0, 183.0, 183.0, 181.0, 182.0, 180.0], "value": 183.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 71.0, 88.0, 90.0, 59.0, 58.0, 58.0, 82.0, 90.0, 55.0], "value": 72.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 99.0, 136.0, 77.0, 77.0, 79.0, 123.0, 81.0, 77.0, 127.0], "value": 97.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 87.0, 84.0, 87.0, 84.0, 86.0, 84.0, 86.0, 86.0], "value": 85.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 91.0, 93.0, 93.0, 111.0, 149.0, 93.0, 92.0, 93.0], "value": 100.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 86.0, 86.0, 83.0, 85.0, 83.0, 84.0, 87.0, 83.0], "value": 84.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 62.0, 59.0, 59.0, 58.0, 59.0, 76.0, 60.0, 57.0], "value": 61.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 71.0, 71.0, 75.0, 71.0, 66.0, 67.0, 69.0, 68.0, 69.0], "value": 69.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 81.0, 81.0, 82.0, 81.0, 80.0, 81.0, 82.0, 80.0, 82.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 149.0, 151.0, 136.0, 132.0, 141.0, 145.0, 152.0, 182.0, 140.0], "value": 147.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 168.0, 213.0, 226.0, 186.0, 186.0, 220.0, 176.0, 171.0, 172.0], "value": 189.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 80.0, 96.0, 101.0, 96.0, 89.0, 69.0, 73.0, 90.0, 94.0], "value": 85.6, "unit": "score"}], "name": "kraken", "value": 1518.0, "alertThreshold": 2.0}]} 06:07:07 INFO - Return code: 0 06:07:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:07:07 ERROR - # TBPL SUCCESS # 06:07:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:07:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:07:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:07:07 INFO - 2016-05-05 06:07:07.760 screenresolution[1687:9698] starting screenresolution argv=screenresolution get 06:07:07 INFO - 2016-05-05 06:07:07.763 screenresolution[1687:9698] Display 0: 1600x1200x32@60 06:07:07 INFO - 2016-05-05 06:07:07.769 screenresolution[1688:9701] starting screenresolution argv=screenresolution list 06:07:07 INFO - Available Modes on Display 0 06:07:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:07:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:07:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:07:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:07:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:07:07 INFO - Intel Iris: 06:07:07 INFO - Chipset Model: Intel Iris 06:07:07 INFO - Type: GPU 06:07:07 INFO - Bus: Built-In 06:07:07 INFO - VRAM (Dynamic, Max): 1536 MB 06:07:07 INFO - Vendor: Intel (0x8086) 06:07:07 INFO - Device ID: 0x0a2e 06:07:08 INFO - Revision ID: 0x0009 06:07:08 INFO - Displays: 06:07:08 INFO - Display: 06:07:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:07:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:07:08 INFO - Display Serial Number: 1600x1200 60 06:07:08 INFO - Main Display: Yes 06:07:08 INFO - Mirror: Off 06:07:08 INFO - Online: Yes 06:07:08 INFO - Rotation: Supported 06:07:08 INFO - Return code: 0 06:07:08 INFO - Running post-action listener: _resource_record_post_action 06:07:08 INFO - Running post-run listener: _resource_record_post_run 06:07:09 INFO - Total resource usage - Wall time: 1209s; CPU: 53.0%; Read bytes: 37713408; Write bytes: 698587136; Read time: 393; Write time: 1907 06:07:09 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 376332800; Write bytes: 371521024; Read time: 28033; Write time: 749 06:07:09 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 36754944; Write bytes: 297828864; Read time: 378; Write time: 1100 06:07:09 INFO - Running post-run listener: _upload_blobber_files 06:07:09 INFO - Blob upload gear active. 06:07:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:07:09 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 06:07:09 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:07:09 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:07:09 INFO - (blobuploader) - INFO - Open directory for files ... 06:07:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:07:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:07:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:07:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:07:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:07:10 INFO - (blobuploader) - INFO - Done attempting. 06:07:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:07:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:07:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:07:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:07:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:07:11 INFO - (blobuploader) - INFO - Done attempting. 06:07:11 INFO - (blobuploader) - INFO - Iteration through files over. 06:07:11 INFO - Return code: 0 06:07:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:07:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:07:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/306170311660cdedcf06653d03100b03e4384c97a92dde4f958bf709e83f67ebc26c735a95bc55cf725227c3f14684a4c5dd3a4bedd07a99bee11dcead716f0c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ff6c8579cef9c343dde84949fa05a2c0358dbb2d1307c0e31b4f193a1287d2a6e72effe9b70336bde95d8d833472836a6e3556b01b87834f9ff59c2d5fb3f12"} 06:07:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:07:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:07:11 INFO - Contents: 06:07:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/306170311660cdedcf06653d03100b03e4384c97a92dde4f958bf709e83f67ebc26c735a95bc55cf725227c3f14684a4c5dd3a4bedd07a99bee11dcead716f0c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ff6c8579cef9c343dde84949fa05a2c0358dbb2d1307c0e31b4f193a1287d2a6e72effe9b70336bde95d8d833472836a6e3556b01b87834f9ff59c2d5fb3f12"} 06:07:11 INFO - Running post-run listener: copy_logs_to_upload_dir 06:07:11 INFO - Copying logs to upload dir... 06:07:11 INFO - mkdir: /builds/slave/test/build/upload/logs 06:07:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.694462 ========= master_lag: 0.20 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-05-05 06:07:11.627683) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-05-05 06:07:11.634261) ========= 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.XJaqX2Yxcy/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.Ca3mnil4RT/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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/Firefox/sha512/306170311660cdedcf06653d03100b03e4384c97a92dde4f958bf709e83f67ebc26c735a95bc55cf725227c3f14684a4c5dd3a4bedd07a99bee11dcead716f0c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ff6c8579cef9c343dde84949fa05a2c0358dbb2d1307c0e31b4f193a1287d2a6e72effe9b70336bde95d8d833472836a6e3556b01b87834f9ff59c2d5fb3f12"} build_url:https://queue.taskcluster.net/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010911 build_url: 'https://queue.taskcluster.net/v1/task/G_FZITMISrGCwFg0sEJlsQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/306170311660cdedcf06653d03100b03e4384c97a92dde4f958bf709e83f67ebc26c735a95bc55cf725227c3f14684a4c5dd3a4bedd07a99bee11dcead716f0c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ff6c8579cef9c343dde84949fa05a2c0358dbb2d1307c0e31b4f193a1287d2a6e72effe9b70336bde95d8d833472836a6e3556b01b87834f9ff59c2d5fb3f12"}' ========= master_lag: 1.20 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-05-05 06:07:12.847373) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 06:07:12.851878) ========= 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.XJaqX2Yxcy/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.Ca3mnil4RT/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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.005050 ========= master_lag: 0.06 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 06:07:12.919930) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-05 06:07:12.920294) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-05 06:07:12.927144) ========= ========= Total master_lag: 1.52 =========