builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0347 starttime: 1464626367.78 results: success (0) buildid: 20160530074242 builduid: 650462f7f9df42f0a8d6e7ceb81d9d23 revision: 17ae44b60a7c7f2d32078702ad0e49062560fab8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 09:39:27.777869) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 09:39:27.778311) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 09:39:27.778604) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 09:39:27.800945) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 09:39:27.801382) ========= 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.fRsyVoTnyW/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.pdWfEsvob5/Listeners TMPDIR=/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-30 09:39:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 28.8M=0s 2016-05-30 09:39:27 (28.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.107993 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 09:39:27.929508) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 09:39:27.929834) ========= 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.fRsyVoTnyW/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.pdWfEsvob5/Listeners TMPDIR=/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/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.025426 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 09:39:27.978405) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 09:39:27.978771) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 17ae44b60a7c7f2d32078702ad0e49062560fab8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 17ae44b60a7c7f2d32078702ad0e49062560fab8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fRsyVoTnyW/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.pdWfEsvob5/Listeners TMPDIR=/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-30 09:39:28,061 truncating revision to first 12 chars 2016-05-30 09:39:28,061 Setting DEBUG logging. 2016-05-30 09:39:28,061 attempt 1/10 2016-05-30 09:39:28,061 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/17ae44b60a7c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 09:39:28,365 unpacking tar archive at: mozilla-aurora-17ae44b60a7c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.569195 ========= master_lag: 0.11 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 09:39:28.658672) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 09:39:28.659002) ========= script_repo_revision: 17ae44b60a7c7f2d32078702ad0e49062560fab8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 09:39:28.659403) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 09:39:28.659688) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 09:39:28.676670) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-05-30 09:39:28.677143) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fRsyVoTnyW/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.pdWfEsvob5/Listeners TMPDIR=/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/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 09:39:28 INFO - MultiFileLogger online at 20160530 09:39:28 in /builds/slave/test 09:39:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:39:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:39:28 INFO - {'append_to_log': False, 09:39:28 INFO - 'base_work_dir': '/builds/slave/test', 09:39:28 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:39:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:39:28 INFO - 'branch': 'Mozilla-Aurora', 09:39:28 INFO - 'buildbot_json_path': 'buildprops.json', 09:39:28 INFO - 'config_files': ('talos/mac_config.py',), 09:39:28 INFO - 'default_actions': ('clobber', 09:39:28 INFO - 'read-buildbot-config', 09:39:28 INFO - 'download-and-extract', 09:39:28 INFO - 'populate-webroot', 09:39:28 INFO - 'create-virtualenv', 09:39:28 INFO - 'install', 09:39:28 INFO - 'run-tests'), 09:39:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:39:28 INFO - 'download_minidump_stackwalk': True, 09:39:28 INFO - 'download_symbols': 'ondemand', 09:39:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:39:28 INFO - 'tooltool.py': '/tools/tooltool.py', 09:39:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:39:28 INFO - '/tools/misc-python/virtualenv.py')}, 09:39:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:39:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:39:28 INFO - 'installer_path': 'installer.exe', 09:39:28 INFO - 'log_level': 'info', 09:39:28 INFO - 'log_name': 'talos', 09:39:28 INFO - 'log_to_console': True, 09:39:28 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:39:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:39:28 INFO - 'opt_config_files': (), 09:39:28 INFO - 'pip_index': False, 09:39:28 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:39:28 INFO - 'cmd': ('bash', 09:39:28 INFO - '-c', 09:39:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:39:28 INFO - 'enabled': True, 09:39:28 INFO - 'halt_on_failure': False, 09:39:28 INFO - 'name': 'check_screen_resolution'},), 09:39:28 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:39:28 INFO - 'cmd': ('bash', 09:39:28 INFO - '-c', 09:39:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:39:28 INFO - 'enabled': True, 09:39:28 INFO - 'halt_on_failure': False, 09:39:28 INFO - 'name': 'check_screen_resolution'},), 09:39:28 INFO - 'run_cmd_checks_enabled': True, 09:39:28 INFO - 'sps_profile': False, 09:39:28 INFO - 'sps_profile_interval': 0, 09:39:28 INFO - 'suite': 'dromaeojs', 09:39:28 INFO - 'system_bits': '32', 09:39:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:39:28 INFO - 'title': 't-yosemite-r7-0347', 09:39:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:39:28 INFO - 'use_talos_json': True, 09:39:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:39:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:39:28 INFO - 'work_dir': 'build'} 09:39:28 INFO - ##### 09:39:28 INFO - ##### Running clobber step. 09:39:28 INFO - ##### 09:39:28 INFO - Running pre-action listener: _resource_record_pre_action 09:39:28 INFO - Running main action method: clobber 09:39:28 INFO - rmtree: /builds/slave/test/build 09:39:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:39:31 INFO - Running post-action listener: _resource_record_post_action 09:39:31 INFO - ##### 09:39:31 INFO - ##### Running read-buildbot-config step. 09:39:31 INFO - ##### 09:39:31 INFO - Running pre-action listener: _resource_record_pre_action 09:39:31 INFO - Running main action method: read_buildbot_config 09:39:31 INFO - Using buildbot properties: 09:39:31 INFO - { 09:39:31 INFO - "project": "", 09:39:31 INFO - "product": "firefox", 09:39:31 INFO - "script_repo_revision": "production", 09:39:31 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 09:39:31 INFO - "repository": "", 09:39:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 09:39:31 INFO - "buildid": "20160530074242", 09:39:31 INFO - "pgo_build": "False", 09:39:31 INFO - "basedir": "/builds/slave/test", 09:39:31 INFO - "buildnumber": 13, 09:39:31 INFO - "slavename": "t-yosemite-r7-0347", 09:39:31 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 09:39:31 INFO - "platform": "yosemite_r7", 09:39:31 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:39:31 INFO - "branch": "mozilla-aurora", 09:39:31 INFO - "repo_path": "releases/mozilla-aurora", 09:39:31 INFO - "revision": "17ae44b60a7c7f2d32078702ad0e49062560fab8", 09:39:31 INFO - "stage_platform": "macosx64", 09:39:31 INFO - "builduid": "650462f7f9df42f0a8d6e7ceb81d9d23", 09:39:31 INFO - "slavebuilddir": "test" 09:39:31 INFO - } 09:39:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 09:39:31 INFO - Running post-action listener: _resource_record_post_action 09:39:31 INFO - ##### 09:39:31 INFO - ##### Running download-and-extract step. 09:39:31 INFO - ##### 09:39:31 INFO - Running pre-action listener: _resource_record_pre_action 09:39:31 INFO - Running main action method: download_and_extract 09:39:31 INFO - mkdir: /builds/slave/test/build/tests 09:39: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')]} 09:39:31 INFO - https://queue.taskcluster.net/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:39:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 09:39:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 09:39:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 09:39:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 09:39:32 INFO - Downloaded 1199 bytes. 09:39:32 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 09:39:32 INFO - Using the following test package requirements: 09:39:32 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 09:39:32 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:39:32 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 09:39:32 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:39:32 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 09:39:32 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:39:32 INFO - u'jsshell-mac.zip'], 09:39:32 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:39:32 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 09:39:32 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 09:39:32 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:39:32 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 09:39:32 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:39:32 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 09:39:32 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:39:32 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 09:39:32 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:39:32 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 09:39:32 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:39: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')]} 09:39:32 INFO - https://queue.taskcluster.net/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:39:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:39:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:39:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:39:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:39:34 INFO - Downloaded 18425599 bytes. 09:39:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:39:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:39:37 INFO - Return code: 0 09:39:37 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 09:39:37 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')]} 09:39:37 INFO - https://queue.taskcluster.net/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:39:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:39:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:39:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:39:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:39:38 INFO - Downloaded 18425599 bytes. 09:39:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:39:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:39:42 INFO - Return code: 0 09:39:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:39:42 INFO - https://queue.taskcluster.net/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:39:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 09:39:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 09:39:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 09:39:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:39:42 INFO - Downloaded 10840145 bytes. 09:39:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:39:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:39:43 INFO - Return code: 0 09:39: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')]} 09:39:43 INFO - https://queue.taskcluster.net/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 09:39:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 09:39:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 09:39:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 09:39:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 09:39:46 INFO - Downloaded 114107634 bytes. 09:39:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 09:39:46 INFO - mkdir: /builds/slave/test/properties 09:39:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:39:46 INFO - Writing to file /builds/slave/test/properties/build_url 09:39:46 INFO - Contents: 09:39:46 INFO - build_url:https://queue.taskcluster.net/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 09:39:47 INFO - Running post-action listener: _resource_record_post_action 09:39:47 INFO - Running post-action listener: set_extra_try_arguments 09:39:47 INFO - ##### 09:39:47 INFO - ##### Running populate-webroot step. 09:39:47 INFO - ##### 09:39:47 INFO - Running pre-action listener: _resource_record_pre_action 09:39:47 INFO - Running main action method: populate_webroot 09:39:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:39:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:39:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:39:47 INFO - '--tppagecycles', 09:39:47 INFO - '7'], 09:39:47 INFO - 'tests': ['tsvgm']}}, 09:39:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:39:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:39:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:39:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:39:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:39:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:39:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:39:47 INFO - 'talos_options': ['--disable-e10s'], 09:39:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:39:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:39:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:39:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:39:47 INFO - 'talos_options': ['--disable-e10s'], 09:39:47 INFO - 'tests': ['damp', 'tps']}, 09:39:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:39:47 INFO - 'tests': ['damp', 'tps']}, 09:39:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:39:47 INFO - 'tests': ['dromaeo_dom']}, 09:39:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:39:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:39:47 INFO - 'tests': ['a11yr', 09:39:47 INFO - 'ts_paint', 09:39:47 INFO - 'tpaint', 09:39:47 INFO - 'sessionrestore', 09:39:47 INFO - 'sessionrestore_no_auto_restore', 09:39:47 INFO - 'tabpaint']}, 09:39:47 INFO - 'other-e10s': {'tests': ['a11yr', 09:39:47 INFO - 'ts_paint', 09:39:47 INFO - 'tpaint', 09:39:47 INFO - 'sessionrestore', 09:39:47 INFO - 'sessionrestore_no_auto_restore', 09:39:47 INFO - 'tabpaint']}, 09:39:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:39:47 INFO - 'ts_paint', 09:39:47 INFO - 'tpaint', 09:39:47 INFO - 'sessionrestore', 09:39:47 INFO - 'sessionrestore_no_auto_restore', 09:39:47 INFO - 'tabpaint']}, 09:39:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:39:47 INFO - 'ts_paint', 09:39:47 INFO - 'tpaint', 09:39:47 INFO - 'sessionrestore', 09:39:47 INFO - 'sessionrestore_no_auto_restore', 09:39:47 INFO - 'tabpaint']}, 09:39:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:39:47 INFO - 'tests': ['a11yr', 09:39:47 INFO - 'ts_paint', 09:39:47 INFO - 'tpaint', 09:39:47 INFO - 'sessionrestore', 09:39:47 INFO - 'sessionrestore_no_auto_restore', 09:39:47 INFO - 'tabpaint']}, 09:39:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:39:47 INFO - 'tests': ['a11yr', 09:39:47 INFO - 'ts_paint', 09:39:47 INFO - 'tpaint', 09:39:47 INFO - 'sessionrestore', 09:39:47 INFO - 'sessionrestore_no_auto_restore', 09:39:47 INFO - 'tabpaint']}, 09:39:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:39:47 INFO - 'tests': ['tsvgx', 09:39:47 INFO - 'tsvgr_opacity', 09:39:47 INFO - 'tart', 09:39:47 INFO - 'tscrollx', 09:39:47 INFO - 'cart']}, 09:39:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:39:47 INFO - 'tsvgr_opacity', 09:39:47 INFO - 'tart', 09:39:47 INFO - 'tscrollx', 09:39:47 INFO - 'cart']}, 09:39:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:39:47 INFO - 'talos_options': ['--disable-e10s'], 09:39:47 INFO - 'tests': ['tp5o']}, 09:39:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:39:47 INFO - 'tests': ['tp5o']}, 09:39:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:39:47 INFO - 'talos_options': ['--disable-e10s', 09:39:47 INFO - '--xperf_path', 09:39:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:39:47 INFO - 'tests': ['tp5n']}, 09:39:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:39:47 INFO - 'talos_options': ['--xperf_path', 09:39:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:39:47 INFO - 'tests': ['tp5n']}}, 09:39:47 INFO - 'talos.zip': {'path': '', 09:39:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:39:47 INFO - Running post-action listener: _resource_record_post_action 09:39:47 INFO - ##### 09:39:47 INFO - ##### Running create-virtualenv step. 09:39:47 INFO - ##### 09:39:47 INFO - Running pre-action listener: _resource_record_pre_action 09:39:47 INFO - Running main action method: create_virtualenv 09:39:47 INFO - Creating virtualenv /builds/slave/test/build/venv 09:39:47 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 09:39:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:39:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:39:47 INFO - Using real prefix '/tools/python27' 09:39:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:39:48 INFO - Installing distribute.............................................................................................................................................................................................done. 09:39:51 INFO - Installing pip.................done. 09:39:51 INFO - Return code: 0 09:39:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:39:51 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')]} 09:39:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:51 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')]} 09:39:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:51 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 0x107181a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107191420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad2b68ec80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107020e00>, '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 0x106e89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10701bb20>, '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.fRsyVoTnyW/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.pdWfEsvob5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/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 09:39:51 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 09:39:51 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 09:39:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fRsyVoTnyW/Render', 09:39:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:51 INFO - 'HOME': '/Users/cltbld', 09:39:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:51 INFO - 'LOGNAME': 'cltbld', 09:39:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:51 INFO - 'MOZ_NO_REMOTE': '1', 09:39:51 INFO - 'NO_EM_RESTART': '1', 09:39:51 INFO - 'PAGER': '/bin/cat', 09:39:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:51 INFO - 'PWD': '/builds/slave/test', 09:39:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:51 INFO - 'SHELL': '/bin/bash', 09:39:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pdWfEsvob5/Listeners', 09:39:51 INFO - 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/', 09:39:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:51 INFO - 'USER': 'cltbld', 09:39:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:51 INFO - 'XPC_FLAGS': '0x0', 09:39:51 INFO - 'XPC_SERVICE_NAME': '0', 09:39:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:51 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 09:39:51 INFO - Cleaning up... 09:39:51 INFO - Return code: 0 09:39:51 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:39:51 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')]} 09:39:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:51 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')]} 09:39:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:51 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 0x107181a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107191420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad2b68ec80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107020e00>, '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 0x106e89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10701bb20>, '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.fRsyVoTnyW/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.pdWfEsvob5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/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 09:39:51 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 09:39:51 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 09:39:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fRsyVoTnyW/Render', 09:39:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:51 INFO - 'HOME': '/Users/cltbld', 09:39:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:51 INFO - 'LOGNAME': 'cltbld', 09:39:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:51 INFO - 'MOZ_NO_REMOTE': '1', 09:39:51 INFO - 'NO_EM_RESTART': '1', 09:39:51 INFO - 'PAGER': '/bin/cat', 09:39:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:51 INFO - 'PWD': '/builds/slave/test', 09:39:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:51 INFO - 'SHELL': '/bin/bash', 09:39:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pdWfEsvob5/Listeners', 09:39:51 INFO - 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/', 09:39:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:51 INFO - 'USER': 'cltbld', 09:39:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:51 INFO - 'XPC_FLAGS': '0x0', 09:39:51 INFO - 'XPC_SERVICE_NAME': '0', 09:39:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:51 INFO - Downloading/unpacking psutil>=0.7.1 09:39:51 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 09:39:51 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 09:39:51 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 09:39:51 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 09:39:51 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 09:39:51 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 09:39:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:39:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:39:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:39:54 INFO - Installing collected packages: psutil 09:39:54 INFO - Running setup.py install for psutil 09:39:54 INFO - building 'psutil._psutil_osx' extension 09:39:54 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 09:39:55 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 09:39:55 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 09:39:55 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 09:39:55 INFO - building 'psutil._psutil_posix' extension 09:39:55 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 09:39:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:39:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:39:55 INFO - ^ 09:39:55 INFO - 1 warning generated. 09:39:55 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 09:39:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:39:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:39:55 INFO - Successfully installed psutil 09:39:55 INFO - Cleaning up... 09:39:56 INFO - Return code: 0 09:39:56 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:39:56 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')]} 09:39:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:56 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')]} 09:39:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:56 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 0x107181a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107191420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad2b68ec80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107020e00>, '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 0x106e89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10701bb20>, '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.fRsyVoTnyW/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.pdWfEsvob5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/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 09:39:56 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 09:39:56 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 09:39:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fRsyVoTnyW/Render', 09:39:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:56 INFO - 'HOME': '/Users/cltbld', 09:39:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:56 INFO - 'LOGNAME': 'cltbld', 09:39:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:56 INFO - 'MOZ_NO_REMOTE': '1', 09:39:56 INFO - 'NO_EM_RESTART': '1', 09:39:56 INFO - 'PAGER': '/bin/cat', 09:39:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:56 INFO - 'PWD': '/builds/slave/test', 09:39:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:56 INFO - 'SHELL': '/bin/bash', 09:39:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pdWfEsvob5/Listeners', 09:39:56 INFO - 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/', 09:39:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:56 INFO - 'USER': 'cltbld', 09:39:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:56 INFO - 'XPC_FLAGS': '0x0', 09:39:56 INFO - 'XPC_SERVICE_NAME': '0', 09:39:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:56 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:39: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 09:39: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 09:39: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 09:39: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 09:39: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 09:39: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 09:39:58 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:39:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:39:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:39:58 INFO - Installing collected packages: mozsystemmonitor 09:39:58 INFO - Running setup.py install for mozsystemmonitor 09:39:58 INFO - Successfully installed mozsystemmonitor 09:39:58 INFO - Cleaning up... 09:39:58 INFO - Return code: 0 09:39:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:39:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:39:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:39:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107181a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107191420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad2b68ec80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107020e00>, '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 0x106e89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10701bb20>, '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.fRsyVoTnyW/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.pdWfEsvob5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/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 09:39:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:39:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:39:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fRsyVoTnyW/Render', 09:39:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:58 INFO - 'HOME': '/Users/cltbld', 09:39:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:58 INFO - 'LOGNAME': 'cltbld', 09:39:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:58 INFO - 'MOZ_NO_REMOTE': '1', 09:39:58 INFO - 'NO_EM_RESTART': '1', 09:39:58 INFO - 'PAGER': '/bin/cat', 09:39:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:58 INFO - 'PWD': '/builds/slave/test', 09:39:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:58 INFO - 'SHELL': '/bin/bash', 09:39:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pdWfEsvob5/Listeners', 09:39:58 INFO - 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/', 09:39:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:58 INFO - 'USER': 'cltbld', 09:39:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:58 INFO - 'XPC_FLAGS': '0x0', 09:39:58 INFO - 'XPC_SERVICE_NAME': '0', 09:39:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:59 INFO - Downloading/unpacking blobuploader==1.2.4 09:39:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:01 INFO - Downloading blobuploader-1.2.4.tar.gz 09:40:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:40:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:40:01 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 09:40:01 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 09:40:01 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 09:40:01 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 09:40:01 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 09:40:01 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 09:40:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:40:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:40:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:02 INFO - Downloading docopt-0.6.1.tar.gz 09:40:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:40:02 INFO - Installing collected packages: blobuploader, requests, docopt 09:40:02 INFO - Running setup.py install for blobuploader 09:40:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:40:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:40:03 INFO - Running setup.py install for requests 09:40:03 INFO - Running setup.py install for docopt 09:40:03 INFO - Successfully installed blobuploader requests docopt 09:40:03 INFO - Cleaning up... 09:40:03 INFO - Return code: 0 09:40:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:40:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:40:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:40:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:03 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 0x107181a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107191420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad2b68ec80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107020e00>, '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 0x106e89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10701bb20>, '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.fRsyVoTnyW/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.pdWfEsvob5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/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 09:40:03 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 09:40:03 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 09:40:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fRsyVoTnyW/Render', 09:40:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:03 INFO - 'HOME': '/Users/cltbld', 09:40:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:03 INFO - 'LOGNAME': 'cltbld', 09:40:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:03 INFO - 'MOZ_NO_REMOTE': '1', 09:40:03 INFO - 'NO_EM_RESTART': '1', 09:40:03 INFO - 'PAGER': '/bin/cat', 09:40:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:03 INFO - 'PWD': '/builds/slave/test', 09:40:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:03 INFO - 'SHELL': '/bin/bash', 09:40:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pdWfEsvob5/Listeners', 09:40:03 INFO - 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/', 09:40:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:03 INFO - 'USER': 'cltbld', 09:40:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:03 INFO - 'XPC_FLAGS': '0x0', 09:40:03 INFO - 'XPC_SERVICE_NAME': '0', 09:40:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:40:04 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-7nsmOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:40:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:40:04 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-l2OoRq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:40:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:40:04 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-AlZ9Rp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:40:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:40:04 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-6FZkNt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:40:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:40:04 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-LUrHLt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:40:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:40:04 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-z1c5lK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:40:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:40:04 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-abg34h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:40:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:40:04 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-PgjpEQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:40:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:40:04 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-tW70WW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:40:05 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-tqXZFr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:40:05 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-s5a0Xr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:40:05 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-XAktPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:40:05 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-ZqN1WU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:40:05 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-kIX5vl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:40:05 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-e4tXYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:40:05 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-kQPGiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:40:05 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-GUo5DF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:40:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:40:05 INFO - Running setup.py install for manifestparser 09:40:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:40:06 INFO - Running setup.py install for mozcrash 09:40:06 INFO - Running setup.py install for mozdebug 09:40:06 INFO - Running setup.py install for mozdevice 09:40:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:40:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:40:06 INFO - Running setup.py install for mozfile 09:40:06 INFO - Running setup.py install for mozhttpd 09:40:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:40:06 INFO - Running setup.py install for mozinfo 09:40:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:40:06 INFO - Running setup.py install for mozInstall 09:40:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:40:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:40:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:40:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:40:07 INFO - Running setup.py install for mozleak 09:40:07 INFO - Running setup.py install for mozlog 09:40:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:40:07 INFO - Running setup.py install for moznetwork 09:40:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:40:07 INFO - Running setup.py install for mozprocess 09:40:07 INFO - Running setup.py install for mozprofile 09:40:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:40:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:40:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:40:07 INFO - Running setup.py install for mozrunner 09:40:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:40:08 INFO - Running setup.py install for mozscreenshot 09:40:08 INFO - Running setup.py install for moztest 09:40:08 INFO - Running setup.py install for mozversion 09:40:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:40:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:40:08 INFO - Cleaning up... 09:40:08 INFO - Return code: 0 09:40:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:40:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:40:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:40:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:08 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 0x107181a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107191420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad2b68ec80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107020e00>, '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 0x106e89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10701bb20>, '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.fRsyVoTnyW/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.pdWfEsvob5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/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 09:40:08 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 09:40:08 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 09:40:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fRsyVoTnyW/Render', 09:40:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:08 INFO - 'HOME': '/Users/cltbld', 09:40:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:08 INFO - 'LOGNAME': 'cltbld', 09:40:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:08 INFO - 'MOZ_NO_REMOTE': '1', 09:40:08 INFO - 'NO_EM_RESTART': '1', 09:40:08 INFO - 'PAGER': '/bin/cat', 09:40:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:08 INFO - 'PWD': '/builds/slave/test', 09:40:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:08 INFO - 'SHELL': '/bin/bash', 09:40:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pdWfEsvob5/Listeners', 09:40:08 INFO - 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/', 09:40:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:08 INFO - 'USER': 'cltbld', 09:40:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:08 INFO - 'XPC_FLAGS': '0x0', 09:40:08 INFO - 'XPC_SERVICE_NAME': '0', 09:40:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:40:08 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-LnpAbx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:40:08 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)) 09:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:40:08 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-YoTJr0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:40:08 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)) 09:40:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:40:08 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-m_cROB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:40:09 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)) 09:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:40:09 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-48dn1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:40:09 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)) 09:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:40:09 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-j2kD8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:40:09 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)) 09:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:40:09 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-bjxzyl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:40:09 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)) 09:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:40:09 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-BRIXhE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:40:09 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)) 09:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:40:09 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-RhQGEV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:40:09 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)) 09:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:40:09 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-nU1Odu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:40:09 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)) 09:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:40:09 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-0oNn0C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:40:09 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)) 09:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:40:09 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-v1iGll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:40:09 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)) 09:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:40:09 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-EuAFpZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:40:09 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)) 09:40:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:40:10 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-p7tBlI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:40:10 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)) 09:40:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:40:10 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-9KTNo1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:40:10 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)) 09:40:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:40:10 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-F9_RQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:40:10 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)) 09:40:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:40:10 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-hSPXLJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:40:10 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)) 09:40:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:40:10 INFO - Running setup.py (path:/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/pip-6fvS4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:40:10 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)) 09:40:10 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)) 09:40:10 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)) 09:40:10 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)) 09:40:10 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)) 09:40:10 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)) 09:40:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:40:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:12 INFO - Downloading blessings-1.6.tar.gz 09:40:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:40:13 INFO - Installing collected packages: blessings 09:40:13 INFO - Running setup.py install for blessings 09:40:13 INFO - Successfully installed blessings 09:40:13 INFO - Cleaning up... 09:40:13 INFO - Return code: 0 09:40:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:40:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:40:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:40:13 INFO - Reading from file tmpfile_stdout 09:40:13 INFO - Current package versions: 09:40:13 INFO - blessings == 1.6 09:40:13 INFO - blobuploader == 1.2.4 09:40:13 INFO - docopt == 0.6.1 09:40:13 INFO - manifestparser == 1.1 09:40:13 INFO - mozInstall == 1.12 09:40:13 INFO - mozcrash == 0.17 09:40:13 INFO - mozdebug == 0.1 09:40:13 INFO - mozdevice == 0.48 09:40:13 INFO - mozfile == 1.2 09:40:13 INFO - mozhttpd == 0.7 09:40:13 INFO - mozinfo == 0.9 09:40:13 INFO - mozleak == 0.1 09:40:13 INFO - mozlog == 3.1 09:40:13 INFO - moznetwork == 0.27 09:40:13 INFO - mozprocess == 0.22 09:40:13 INFO - mozprofile == 0.28 09:40:13 INFO - mozrunner == 6.11 09:40:13 INFO - mozscreenshot == 0.1 09:40:13 INFO - mozsystemmonitor == 0.0 09:40:13 INFO - moztest == 0.7 09:40:13 INFO - mozversion == 1.4 09:40:13 INFO - psutil == 3.1.1 09:40:13 INFO - requests == 1.2.3 09:40:13 INFO - wsgiref == 0.1.2 09:40:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:40:13 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')]} 09:40:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:13 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')]} 09:40:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:13 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 0x107181a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107191420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad2b68ec80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107020e00>, '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 0x106e89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10701bb20>, '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.fRsyVoTnyW/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.pdWfEsvob5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/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 09:40:13 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 09:40:13 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 09:40:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fRsyVoTnyW/Render', 09:40:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:13 INFO - 'HOME': '/Users/cltbld', 09:40:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:13 INFO - 'LOGNAME': 'cltbld', 09:40:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:13 INFO - 'MOZ_NO_REMOTE': '1', 09:40:13 INFO - 'NO_EM_RESTART': '1', 09:40:13 INFO - 'PAGER': '/bin/cat', 09:40:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:13 INFO - 'PWD': '/builds/slave/test', 09:40:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:13 INFO - 'SHELL': '/bin/bash', 09:40:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pdWfEsvob5/Listeners', 09:40:13 INFO - 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/', 09:40:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:13 INFO - 'USER': 'cltbld', 09:40:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:13 INFO - 'XPC_FLAGS': '0x0', 09:40:13 INFO - 'XPC_SERVICE_NAME': '0', 09:40:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:13 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)) 09:40:13 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)) 09:40:13 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)) 09:40:13 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)) 09:40:13 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)) 09:40:13 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)) 09:40:13 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)) 09:40:13 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)) 09:40:13 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)) 09:40:13 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)) 09:40:13 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)) 09:40:13 INFO - Cleaning up... 09:40:13 INFO - Return code: 0 09:40:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:40:13 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')]} 09:40:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:13 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')]} 09:40:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:13 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 0x107181a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107191420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad2b68ec80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107020e00>, '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 0x106e89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10701bb20>, '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.fRsyVoTnyW/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.pdWfEsvob5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/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 09:40:13 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 09:40:13 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 09:40:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fRsyVoTnyW/Render', 09:40:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:13 INFO - 'HOME': '/Users/cltbld', 09:40:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:13 INFO - 'LOGNAME': 'cltbld', 09:40:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:13 INFO - 'MOZ_NO_REMOTE': '1', 09:40:13 INFO - 'NO_EM_RESTART': '1', 09:40:13 INFO - 'PAGER': '/bin/cat', 09:40:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:13 INFO - 'PWD': '/builds/slave/test', 09:40:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:13 INFO - 'SHELL': '/bin/bash', 09:40:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pdWfEsvob5/Listeners', 09:40:13 INFO - 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/', 09:40:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:13 INFO - 'USER': 'cltbld', 09:40:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:13 INFO - 'XPC_FLAGS': '0x0', 09:40:13 INFO - 'XPC_SERVICE_NAME': '0', 09:40:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:14 INFO - Downloading/unpacking jsonschema 09:40:14 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 09:40:14 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 09:40:14 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 09:40:14 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 09:40:14 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 09:40:14 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 09:40:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:40:16 INFO - Downloading/unpacking functools32 (from jsonschema) 09:40:16 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 09:40:16 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 09:40:16 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 09:40:16 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 09:40:16 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 09:40:16 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 09:40:16 INFO - Downloading functools32-3.2.3-2.tar.gz 09:40:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:40:16 INFO - warning: no files found matching '*.txt' 09:40:16 INFO - no previously-included directories found matching 'build' 09:40:16 INFO - no previously-included directories found matching 'dist' 09:40:16 INFO - no previously-included directories found matching '.git*' 09:40:16 INFO - Installing collected packages: jsonschema, functools32 09:40:17 INFO - Running setup.py install for functools32 09:40:17 INFO - warning: no files found matching '*.txt' 09:40:17 INFO - no previously-included directories found matching 'build' 09:40:17 INFO - no previously-included directories found matching 'dist' 09:40:17 INFO - no previously-included directories found matching '.git*' 09:40:17 INFO - Successfully installed jsonschema functools32 09:40:17 INFO - Cleaning up... 09:40:17 INFO - Return code: 0 09:40:17 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:40:17 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')]} 09:40:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:17 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')]} 09:40:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:17 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 0x107181a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107191420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad2b68ec80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107020e00>, '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 0x106e89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10701bb20>, '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.fRsyVoTnyW/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.pdWfEsvob5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/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 09:40:17 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 09:40:17 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 09:40:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fRsyVoTnyW/Render', 09:40:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:17 INFO - 'HOME': '/Users/cltbld', 09:40:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:17 INFO - 'LOGNAME': 'cltbld', 09:40:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:17 INFO - 'MOZ_NO_REMOTE': '1', 09:40:17 INFO - 'NO_EM_RESTART': '1', 09:40:17 INFO - 'PAGER': '/bin/cat', 09:40:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:17 INFO - 'PWD': '/builds/slave/test', 09:40:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:17 INFO - 'SHELL': '/bin/bash', 09:40:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pdWfEsvob5/Listeners', 09:40:17 INFO - 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/', 09:40:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:17 INFO - 'USER': 'cltbld', 09:40:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:17 INFO - 'XPC_FLAGS': '0x0', 09:40:17 INFO - 'XPC_SERVICE_NAME': '0', 09:40:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:17 INFO - Downloading/unpacking flake8 09:40:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:19 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:40:19 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:40:19 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 09:40:19 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 09:40:19 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 09:40:19 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 09:40:19 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 09:40:19 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 09:40:20 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:40:20 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:40:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:20 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:40:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:40:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:40:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:40:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:40:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:40:21 INFO - no previously-included directories found matching 'docs/_build' 09:40:21 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:40:21 INFO - Running setup.py install for pep8 09:40:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:40:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:40:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:40:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:40:21 INFO - no previously-included directories found matching 'docs/_build' 09:40:21 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:40:21 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:40:21 INFO - Cleaning up... 09:40:21 INFO - Return code: 0 09:40:21 INFO - Running post-action listener: _resource_record_post_action 09:40:21 INFO - Running post-action listener: _start_resource_monitoring 09:40:21 INFO - Starting resource monitoring. 09:40:21 INFO - ##### 09:40:21 INFO - ##### Running install step. 09:40:21 INFO - ##### 09:40:21 INFO - Running pre-action listener: _resource_record_pre_action 09:40:21 INFO - Running main action method: install 09:40:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:40:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:40:21 INFO - Reading from file tmpfile_stdout 09:40:21 INFO - Detecting whether we're running mozinstall >=1.0... 09:40:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:40:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:40:21 INFO - Reading from file tmpfile_stdout 09:40:21 INFO - Output received: 09:40:21 INFO - Usage: mozinstall [options] installer 09:40:21 INFO - Options: 09:40:21 INFO - -h, --help show this help message and exit 09:40:21 INFO - -d DEST, --destination=DEST 09:40:21 INFO - Directory to install application into. [default: 09:40:21 INFO - "/builds/slave/test"] 09:40:21 INFO - --app=APP Application being installed. [default: firefox] 09:40:21 INFO - mkdir: /builds/slave/test/build/application 09:40:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:40:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 09:40:53 INFO - Reading from file tmpfile_stdout 09:40:53 INFO - Output received: 09:40:53 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:40:53 INFO - Running post-action listener: _resource_record_post_action 09:40:53 INFO - ##### 09:40:53 INFO - ##### Running run-tests step. 09:40:53 INFO - ##### 09:40:53 INFO - Running pre-action listener: _resource_record_pre_action 09:40:53 INFO - Running main action method: run_tests 09:40:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:40:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:40:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:40:53 INFO - 2016-05-30 09:40:53.642 screenresolution[1657:8098] starting screenresolution argv=screenresolution get 09:40:53 INFO - 2016-05-30 09:40:53.644 screenresolution[1657:8098] Display 0: 1600x1200x32@60 09:40:53 INFO - 2016-05-30 09:40:53.650 screenresolution[1658:8101] starting screenresolution argv=screenresolution list 09:40:53 INFO - Available Modes on Display 0 09:40:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:40:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:40:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:40:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:40:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:40:53 INFO - Intel Iris: 09:40:53 INFO - Chipset Model: Intel Iris 09:40:53 INFO - Type: GPU 09:40:53 INFO - Bus: Built-In 09:40:53 INFO - VRAM (Dynamic, Max): 1536 MB 09:40:53 INFO - Vendor: Intel (0x8086) 09:40:53 INFO - Device ID: 0x0a2e 09:40:53 INFO - Revision ID: 0x0009 09:40:53 INFO - Displays: 09:40:53 INFO - Display: 09:40:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:40:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:40:53 INFO - Display Serial Number: 1600x1200 60 09:40:53 INFO - Main Display: Yes 09:40:53 INFO - Mirror: Off 09:40:53 INFO - Online: Yes 09:40:53 INFO - Rotation: Supported 09:40:53 INFO - Return code: 0 09:40:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:40:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:40:53 INFO - Python 2.7.3 09:40:53 INFO - Return code: 0 09:40:53 INFO - grabbing minidump binary from tooltool 09:40: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')]} 09:40:53 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 0x107020e00>, '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 0x106e89db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10701bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:40:53 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 09:40:53 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 09:40:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:40:53 INFO - Return code: 0 09:40:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:40:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:40:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:40:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:40:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:40:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:40:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:40:55 INFO - Return code: 0 09:40:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0347', '--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 09:40:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0347 --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 09:40:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fRsyVoTnyW/Render', 09:40:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:55 INFO - 'HOME': '/Users/cltbld', 09:40:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:55 INFO - 'LOGNAME': 'cltbld', 09:40:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:40:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:40:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:55 INFO - 'MOZ_NO_REMOTE': '1', 09:40:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:40:55 INFO - 'NO_EM_RESTART': '1', 09:40:55 INFO - 'PAGER': '/bin/cat', 09:40:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:55 INFO - 'PWD': '/builds/slave/test', 09:40:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:40:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:55 INFO - 'SHELL': '/bin/bash', 09:40:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pdWfEsvob5/Listeners', 09:40:55 INFO - 'TMPDIR': '/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/', 09:40:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:55 INFO - 'USER': 'cltbld', 09:40:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:55 INFO - 'XPC_FLAGS': '0x0', 09:40:55 INFO - 'XPC_SERVICE_NAME': '0', 09:40:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0347', '--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 09:40:55 INFO - mozversion application_buildid: 20160530074242 09:40:55 INFO - mozversion application_changeset: 17ae44b60a7c7f2d32078702ad0e49062560fab8 09:40:55 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:40:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:40:55 INFO - mozversion application_name: Firefox 09:40:55 INFO - mozversion application_remotingname: firefox-dev 09:40:55 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:40:55 INFO - mozversion application_vendor: Mozilla 09:40:55 INFO - mozversion application_version: 48.0a2 09:40:55 INFO - mozversion platform_buildid: 20160530074242 09:40:55 INFO - mozversion platform_changeset: 17ae44b60a7c7f2d32078702ad0e49062560fab8 09:40:55 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:40:55 INFO - mozversion platform_version: 48.0a2 09:40:55 INFO - using testdate: 1464626455 09:40:55 INFO - actual date: 1464626455 09:40:55 INFO - starting webserver on 'localhost:49277' 09:40:55 INFO - SUITE-START | Running 2 tests 09:40:55 INFO - TEST-START | dromaeo_css 09:40:55 INFO - operating with platform_type : mac_ 09:40:55 INFO - Initialising browser for dromaeo_css test... 09:40:55 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/tmpBWwhm3/profile http://localhost:49277/getInfo.html) 09:40:57 INFO - PROCESS | 1676 | __metrics Screen width/height:1600/1200 09:40:57 INFO - PROCESS | 1676 | colorDepth:24 09:40:57 INFO - PROCESS | 1676 | Browser inner width/height: 1024/689 09:40:57 INFO - PROCESS | 1676 | __metrics 09:40:57 INFO - PROCESS | 1676 | 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" 09:40:57 ERROR - PROCESS | 1676 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:40:58 INFO - TEST-INFO | 1676: exit 0 09:40:58 INFO - Browser initialized. 09:40:58 INFO - Running cycle 1/1 for dromaeo_css test... 09:40:58 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/tmpBWwhm3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:41:10 INFO - PROCESS | 1678 | RSS: Main: 211206144 09:41:10 INFO - PROCESS | 1678 | 09:42:01 INFO - PROCESS | 1678 | 1464626521682 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] 09:44:12 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 09:44:13 INFO - PROCESS | 1678 | RSS: Main: 339869696 09:44:13 INFO - PROCESS | 1678 | 09:47:16 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 09:47:17 INFO - PROCESS | 1678 | RSS: Main: 352079872 09:47:17 INFO - PROCESS | 1678 | 09:50:19 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 09:50:20 INFO - PROCESS | 1678 | RSS: Main: 359616512 09:50:20 INFO - PROCESS | 1678 | 09:53:22 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 09:53:22 INFO - PROCESS | 1678 | RSS: Main: 360996864 09:53:22 INFO - PROCESS | 1678 | 09:56:26 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 09:56:26 INFO - PROCESS | 1678 | RSS: Main: 324689920 09:56:26 INFO - PROCESS | 1678 | 09:59:29 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 09:59:29 INFO - PROCESS | 1678 | RSS: Main: 368152576 09:59:29 INFO - PROCESS | 1678 | 09:59:29 INFO - PROCESS | 1678 | __start_tp_report 09:59:29 INFO - PROCESS | 1678 | _x_x_mozilla_page_load 09:59:29 INFO - PROCESS | 1678 | _x_x_mozilla_page_load_details 09:59:29 INFO - PROCESS | 1678 | |i|pagename|runs| 09:59:29 INFO - PROCESS | 1678 | |0;dojo.html;3334.849931753071;3419.5725534308194;3428.9314213715725;3436.742244331676;3475.7740746851455;5643.915341269884;5649.887002259952;5650.74571644276;5652.010898092833;5659.547236221103;5591.217229587861;5630.324361076667;5647.209390685297;5653.632513886594;5662.631928924617;5018.5232402921665;5062.063518249136;5174.965006998596;5219.269302297681;5260.684358938457;5168.121419358718;5180.715060671661;5188.429272779991;5189.688618682872;5195.558377537895;5098.59211263098;5141.82003629873;5173.3533308336455;5181.378234611833;5208.2968799212;5129.897402051975;5157.8452646420665;5164.354455693038;5170.3278573785165;5200.037992971274;5032.396112466491;5068.5691716204165;5084.13569693153;5095.719735414551;5152.9484705152845;5472.315960504937;5482.5888058395385;5494.780208791643;5528.170774383834;5611.775528978836;6159.722812473449;6163;6198.597091189058;6225.408586184305;6229.968850155766;6090.26916769986;6215.12988181655;6257.374262573751;6268.091126786636;6282.96858515709;5672.858178545528;5696.202531645573;5737.053386191315;5773.538116950634;5776.046952252916;6234.096056071935;6235.033569796688;6242.75028998844;6258.186435763321;6266.811995640138;5318.149096144598;5851.063829787298;6002.459778619857;6115.755369785248;6122.479589234876;6131.448169664663;6155.96922015396;6166.845828854225;6178.5366097542865;6181.907271390933;6144.494598823317;6152.2924863640355;6176.042713379434;6178.5366097542865;6185.134081228632;6122.479589234965;6146.600470969463;6171.84570385735;6176.197094377702;6199.783007594712;4785.210440277385;4810.230363141881;4836.322914791932;4847.981923795972;4895.388076490439;4392.4289842320295;4401.2517871961845;4418.491873434532;4435.157320109169;4435.223835828717;5643.068893632587;5656.943430565724;5669.603127781098;5675;5675.6594604323045;5155.845324640267;6057.091436284592;6067.635941843503;6120.571559990758;6124.908126378108;5614;5631.4087020863035;5636.830895073154;5651.208830763697;5668.404817494186;5304.416514183436;5333.359996800409;5342.358916929993;5344.465553444702;5378.327709036371;6107.969460152671;6158.860610787019;6207.751689932533;6214.5960512566535;6269.686515674107;6158.568900176944;6163.7842675506145;6168.290646575701;6168.722407491584;6203.844903877439;6261.373862613702;6271.78048768291;6285.088662144055;6298.1812364392335;6306.9684651576445;5963.7912673056235;6096.8780624388155;6109.663968481598;6128.571000029955;6147.555324498829;5442.8639284016635;6050.757969681164;6125;6156.994883314614;6167.969160154171;6043.576949613661;6072.605280656743;6077.878442431038;6081.300650425258;6091.604045737013;5025.572826309658;5839.445252700986;5936.940630593639;6026.035834266497;6035.668038257938;5860.794872179624;5876.324222714443;5884.293884733859;5887.764489420375;5895.587308888508;5973.671448070398;5975.492083172979;6010.068439391901;6018.187544681412;6077.878442431215;5739.971300143473;5833.329167145833;5950.16697662319;5984.581079324405;5987.670678112746 09:59:29 INFO - PROCESS | 1678 | |1;ext.html;510.17351889938305;514.2466286889705;563.8618538458078;590.9658098327925;622.9627669929567;31066.84466577667;31756;31764;31886;38259.80870095653;42920.78539607298;44179.55820441803;45206.773966130204;45210;45420.54579454204;9637.469939153327;9831.65589204378;9831.803363932717;9831.901680983206;9836.262280329003;8624.482531048126;8814.515201663906;8836.0722124177;9576.281778866578;9708.466034368106;8698.434601750865;8788.64845406183;11600.825987610193;11788.587399441021;11874.465649045771;11515.539378424854;11615.941920290428;11621.070314374872;11627.476763545661;11633.243839150427;7432.62836858155;11505.884941150565;11570.479328430241;11576.24754390962;11576.421178941104;40000.59999399998;40436.79781601073;40534;40585;40593.188136237404;3259.6903294187014;3288.720458761008;3293.8517766700315;3314.767966242364;3679.0986208379118;26088.60867086995;26260.606090908652;26452.47095058107;26784;26816.463670726673;2738.5344491436504;2748.670159580843;2751.317490305248;2792.8184667996516;2865.694200001982;430330;432031;432616;432854;433269;60875.69562152161;61086;61443.38556614466;61569;61755;68620.31379686239;69315.65342173257;69336.95994560086;69390.65304673444;69564.3043569568;17471;17677.646447071245;17680;17703.468895933143;17713;13447.394867230998;13541.45834166642;13558.050936434354;13569.660758481117;13573.932130339284;17291;17481.300747969937;19260.80739192618;20286.69569956452;20321.695174572393;6956.582605043714;7951.920480795234;8146.14465481116;8148.918510814935;8176.936998190316;970.2965350121247;1020.7907378987289;1047.560024789575;1078.82199437092;1136.0286954653818;30238.244043898638;30455.23861903426;30513.42938101133;30563.38873222523;30683.38633227323;1105.4528008635616;1151.1423989128152;1167.9955238494888;1180.4333919718583;1192.8628207756046;15397.307121179574;15410.768838467431;15446.691066178613;15523.379064837505;15610.177657033077;641538;645621;656884;657710;659686;52528;60300.39699603036;60713.392866070775;61114;61637.38362616406;1503.548935319378;1509.8490150984815;1511.894167408271;1526.0462211118052;1538.692261547695;25773.613395798704;26561.60157597599;29727.55408668915;30227.395452090394;31054.68945310518;51550.74224628853;51822;51825.740871295406;51861;51964.740176298874;575573;793784;809942;813111;816721;26801.463970720866;26908.461830762884;27208;27311.90752369883;27591.31021724473;44347.89130271769;45777.313340300585;46200.07599847917;46868.82827929193;47307.290390644856;27702.03042893489;37173;38409.807950960065;38498.0375490615;38641.42037869378;38009.440564647724;38030.85907422782;38041.23917521689;38517.80741096276;39128.2174356517;37510.43734343933;37577;38091.619083809914;38340.61659383371;38466;32582.83708581537;33091;33869.32261354809;34101.488477672356;34289.657103428646;24002.759972400054;24279.393015174057;24438.51122977566;24727.134550290655;25059.49881002333 09:59:29 INFO - PROCESS | 1678 | |2;jquery.html;404.45196758392376;404.9939250911236;405.92287465381594;412.3320221241589;419.08430080274604;17034.5741356466;19726;19970.301039463604;20200.898995505002;20410.795892041075;19693.21227150912;19952.600947981067;20253.59492810143;20650.380488585328;20655.27706530272;5684.118961560965;5783.913241301384;5943.643381397108;5947.464728174464;6002.8799424011495;5645.407232240596;5879.382664820197;5899.351071382166;5953.464188223059;5957.851053723648;5379.408265090837;5786.971065144689;5888.558358123137;5903.085021821603;5909.409059094078;5686.4597863203135;5834.912476312858;5860.267466566679;5866.413358664121;5879.647221166701;5102.974485127551;5188.558972487305;5192.454792246797;5192.870178245536;5216.478352164791;26349;26878;27134.32164195891;27406.451870962468;27408.588871166947;2288.9084436622516;2311.9884400578057;2513.195777351248;2966.9109926702235;2975.092596757988;14810.555683329518;15178.316975736008;15209.6197595061;15576.92211538935;15647.530574082795;1655.6936966661085;1727.5767436978022;1753.6668033073674;1771.008235388174;1798.9640207195914;114708;116233.60715160663;116741.41629291799;118194;118750.40624796908;10946.397948112772;11314.490847911864;11515.884841151648;11540.192186547029;11672.649820505398;14370.065945713495;14431.98976071665;14433.855661443462;14446.205458699873;14479.855201448061;7593.430492713069;8921.197092261727;9093.590788414538;9202.585883635253;9229.446233226028;9239.815203695889;9241.861372079424;9249.445033298023;9315.580798864068;9346.953265233766;5791.710414479344;6863.245043045261;6892.345227203407;6905.240423553405;6921.273266307066;1591.8248992610804;1730.8182640822533;1848.7134494153427;1872.6348362069266;1877.7371168036213;737.557465520694;864.3474176996453;970.9077637624414;1008.3949630221955;1130.0902773267435;18440.631187376177;21197.36407907765;21800.476453356783;21956.341309760734;22931.541369172857;973.4305431322756;995.6515219673056;1011.174829432866;1059.0838924330553;1073.0771536478778;11517.827232591519;11984.940075299568;12012.279263244232;12033.977111945407;12050;639027;646785;652026;663210.3678963245;663285.367146332;69078;71176.28823711697;72644;105981;130149;1019.5463018956641;1066.4987455895716;1079.8995952871746;1081.4376524207369;1083.1767856429171;14561;17574.121293935506;18394.632107358044;18534.629307414045;18673.626527469103;50269.74865125651;51080.48919510757;51369.743151284005;52029;52395.73802130965;69847.95228071482;69893;70093.64953175202;71216.64391678009;74738.62630686811;7987.281144696773;8087.83824323522;8104.392170587112;8187.631556579849;8431.15688431152;7276.818079547842;11091.889081109086;11502.539898404304;12110.910018098413;12364.938175309066;10196.082352588302;10386.169106471616;10600.151987841109;10785.892141078803;10901.563937442414;10529.526171322155;10535.05184533365;10746.979036991474;10750.24748267645;10931.617393391192;10780.275155975007;10818.891811082103;11067;11222.7755444889;11294.548218071513;7644.885326720215;7896.644650990834;7990.200979901963;8030.759077227693;8216.46592971455;10415.062644362044;10625.203109766644;10859.294145880493;10873.945630271797;10903.56385744593 09:59:29 INFO - PROCESS | 1678 | |3;mootools.html;1274.3691872523102;1548.566401407606;1575.5903465099075;1633.003867640739;1752.2990803678526;7761.262680045394;8496.660133594656;8585.742427727162;8588.570571471433;8592.699255526059;7816.5310081395155;8563.614637341334;8583.871241931376;8586;8629.870551941725;2164.350694791564;2217.966730499044;2226.7196362091863;2430.7569243075727;2459.0409740201335;2059.6086743518686;2454.214651311572;2456.754324567538;2457.2259738182506;2460.938476538092;2000.7098970649247;2392.0790495659207;2397.9160729374476;2438.5610590093866;2445.40087678519;2139.0908863733007;2358.846674966139;2423.127674037345;2428.7571242875747;2431.6109422492405;2370.881455927197;2420.382802385398;2426.745191754858;2427.1747605814107;2430.611102223636;8433.241008309282;8514.191151840565;8516.70191543293;8526.190011948855;8539.103394143649;5151.459096794858;5257.86855328616;5259.342582177228;5259.8422047338645;5302.284191634157;3420.743444241692;3480.2343484433386;3607.188382613891;3623.1304486923214;3650.7809531428197;1043.3844032021143;1051.6319288249128;1058.8888166742536;1062.95216715248;1066.589363095214;9882.654107106217;11648.825267620992;11705.648830535012;11719.7070073248;11730;4077.5922407758985;4651.883702907455;4813.109574728686;4844.660873738804;4881.316615673809;9113.270938324918;9983.550740216708;10040.347377420445;10051.748706282255;10086.890442051368;5412.971535408338;5422.240886275925;5425.321834770653;5426.864328391743;5477.20580515831;4306.3325184595815;4486.484054333728;4777.283407488904;4940.2342636891335;4975.925361119586;3967.3850553164307;3984.6812255019527;4037.1118353962083;4070;4101.179764047202;1337.5719769673806;1339.5110784563565;1343.368616750126;1353.64128505946;1355;914.4610357914912;917.5916717060977;933.3233405780891;948.0661548374845;988.5057471264367;5749.8275051748515;5801.245838041028;5803.622764520293;5809.157672137517;5874.9118763218585;425.1263653192719;463.7009129111704;466.0492595105946;468.9413823272091;483.9806407743651;3840.9039774005314;3891.5330160380936;3900.941485877714;3971.7616942983514;4058.837646494166;10224.64213752515;10392.636257730943;10423.530941107669;10435.113015393777;10514.05373516372;10273.897261027294;10285.948570257251;10298.17614590831;10343.224258180666;10428.687139385831;7766.495177813424;8014.71948481812;8046.356291496783;8066.354691624656;8100.635471403801;5507.843352895937;5654.519365853784;5765.509931655856;5828.154917536848;5939.10913362999;8177.591120443835;9175.862362064441;9286;9393.671221507215;9404.341696081208;9017.278617710435;9376.57805398745;9550.66572669981;9612.66355677548;9762.219022478042;6631.469482441489;6791.3548212919695;7173.210946795953;7287.307705767944;7526.285002924714;8064.55644939534;8081.151479094846;8130.796730081559;8133.8373232536205;8140.430169888051;7117.43060555144;7196.460265480215;7211.1707153678;7345.228750981284;7370.96314518424;7068;7091.716331346688;7120.074390329224;7136.821579460555;7219.639018048972;6707.295733947865;6910.274421185703;6921.2386637468835;6927.826804329932;6931.064306318583;4730.290456431563;4751.714897106184;4796.208625576741;4899.118158731462;4934.037862616755;4793.784279707491;4798.808047678054;4826.927596086131;4879.512048795092;5018.5232402921665 09:59:29 INFO - PROCESS | 1678 | |4;prototype.html;322.51944602542216;329.1622819923295;331.3059141099396;336.13613014552595;342.8028883392047;6661.766838160659;6778.661066946657;6782.220044694862;6786.253512113663;6799.728010879565;6656.866862662744;6714.227863795665;6724.327567243273;6725.630090345029;6731.495137864667;4689.85930422087;4816.783244753994;4821.783019764119;4922.138625740481;4988.02733466974;4039.7576145431367;4976.054549635575;4978.054169707744;4987.177115775893;5039.042581909425;4903.632227582928;4942.703437793726;4944.7774850131655;4949.406071271453;4955.6035517158725;4670.486246512881;4776.761161941924;4872.366592343008;4893.1926232171645;4932.778024988884;4193.119444916571;4204.453421055274;4213.115245798386;4218.936715949263;4233.470816147981;5258.579313654898;6377;6402.839929001766;6411.93588064118;6461.547691661585;5868.442497962729;5879.647221166743;5879.794207202727;5895.145203945447;5903.970480147615;6825.965870170618;7162.677679504435;7178.138623365229;7190.7483238086415;7192.640367981632;4025.496812898388;4608.769561521944;4621.514740952185;4632.791524381411;4658.371119898804;7458.142313633893;7532.907728379354;7573.091229052494;7594.658240379196;7611.2388761124;4681.695689780221;4696.2955556666775;4696.929546056812;4708.458527269339;4711.8350857719815;4763.809447622126;4771.618270538349;4774.761261936958;4780.760961951958;4787.210110331756;4560.005098264131;5709.514691251207;5711.514521265739;5726.713664316768;5728.971355143281;7018.649067546602;7147.642617869085;7154.213036565973;7175.2465991070185;7215.5670659759535;1371.6913694418874;1611.4843250159836;1646.9752953705704;1664.0930692772467;1668.8581470574895;977.2572844638127;986.0829428631441;1022.9028242316969;1028.3521381529588;1052.5421441672897;6534.248561415403;6540.411362977355;6570;6570.310117437696;6741.52809303344;6193.482596763732;7534.811629709301;7537.698492060256;7563.168051514329;7564.9243507565325;6735.090762747064;6752.054712340277;6761.3238676132;6775.0514927910135;6779.898301525481;3828.8851334459455;3925.548561915359;3969.0077480629843;3978;3981.7212795104642;7809.297163255221;7828.452008359474;7851.293383595504;7854.8429031419055;7865.842683146305;6275.435210830956;7660.770176894702;7718.807029824187;7723.497972631873;7733.2266773323345;7817.609119544114;7819.832967648112;7823.29590336861;7836.255555722198;7846.136924938252;7145.535540189871;7503.962480187564;7506.024216851774;7515.473916825988;7530.623468826647;6005.339412664691;6210.72051757681;7319.707211711472;7328.450366222448;7388.335049845539;7777.144514103557;7808.297253247235;7822.804429889298;7822.882656760039;7831;4653.022465452237;4724.267738500538;4725.881852953704;4734.1478533862855;4737.431508219036;7117.501774875916;7417.8145546361775;7420.925790742023;7436.851262974602;7444.553326800409;6106.755729770759;7076.690812199759;7144.464165187528;7197.568145911262;7233.565986040854;7152.141742990874;7181.245969173162;7182.640867956686;7193.244709305448;7194.460415468965;7011.4390848733;7016.087908571853;7022.719091236498;7031.296870312928;7051.858962820817;6900.6204658742245;7163.677634506355;7166.820829479305;7188.6046267455795;7192.640367981685;6689.49828762835;6782.626955517494;6794.626295553792;6912.585244885323;6949.339812717783 09:59:29 INFO - PROCESS | 1678 | |5;yui.html;685.366036416315;734.4858598980715;752.9962350188255;754.0950858969238;794.1383598795306;14673.339699713511;14755.336009879555;14984.700305993874;14992.10047397157;15006.699866002673;13679.384427700752;13744.862551374483;13768.311584420764;13769.03616746828;13789.310534473287;747.9813004674872;759.8594260061906;761.4669731188163;913.2648218184358;946.2997381937352;759.0322339017769;761.5468796066359;909.3089252168367;943.1982814607597;944.4380593546829;980.0493521284343;988.6836212412031;990.9157721593673;992.136840948376;996.576455006623;974.6929717139114;977.8435450327913;979.2313034268101;982.2142286171106;982.4596471940475;574.0041028815002;581.102197105473;581.9476247137735;582.7348556406824;729.230661651957;14538.200398978051;14618;14638.048526845827;14641.048331858396;14669.413223471049;10410.947945260225;12425.130240883142;12642.051846111486;12657.30384828834;12950.87049129506;9362;9604.03959604041;10093.293469457141;10179.338343007681;10198.643047493302;3060.5256185291314;3441.5009823575692;3470.4273794823825;3487.773294735834;3506.6493350664987;80685.49514640677;82077;82111;82372.58813705953;82594.58702706508;9384.967653558104;9449.480278584744;9513.429194248367;9617.422954622745;9797.265205109503;21655.566888662455;21655.96816445699;21691.783082168975;22149.889250553642;22501.32496025122;6463.836509428254;7577.242275772379;7602.961985190039;7954.5624990626075;8082.959585202037;4041.6362527372676;4057.472528571267;4137.213929353413;4204.66362690984;4396.450443694538;3051.8016328938993;3579.838907249128;3624.637536246407;3628.1473853644375;3635.781853088823;646.6184950879004;663.0419044480775;665.1984358847577;666.044226534924;688.8036909480774;523.3275241314892;595.2882931596453;611.7247238742584;619.6715740657459;652.8988006858945;7495.725726626378;7891.684332626631;7948.244916732901;8136.064352599408;8366.665333386718;594.913737508059;595.2499850188793;605.7213681706377;609.6220343387129;611.7552978808512;3815.988762977813;3850.845966161322;3854.5181852268465;3970.68234541236;4008.5590585035625;89721;89839;90580.64129038069;91147.5442622796;91389.54305228431;77436.99999999888;77590.22409775943;78146.6092669533;78241.60879195567;79351.60324198342;68827.65586172037;69519.30480695229;69803;70719;70967.2903270971;6651.0023496475915;7250.839865621475;7652.617369131633;7684.731034413769;7864.1742617026675;44015.55984440115;48069.27896081492;48277.5172248273;48587.32769697701;48718.26922596093;62139.37860621336;64288;65735.35661608307;66247.33752662412;66261.66869165623;10362;10381.740456488647;10567.207459440726;10569.577216911544;10571.04860562553;10627.574897004033;10758.193135515025;10771.461426928465;10879.955437739747;10940.726481838017;9537.380070295409;9799.902000979899;9950.154236889946;10137.493125343852;10393.012663796928;8581.769597383673;10593.89406105929;11294.830577541508;11707.414629268673;12093.984455725787;10785.946070269913;11044.834327484934;11297.322160670386;11390.829137563109;11523.8271425927;9267.258619310574;9378.906210937803;9524.666636667684;9617;9677.322587418814;8052.718154864551;9420.528973551158;9436.103570160823;9503.477308747808;9543.141117299476 09:59:29 INFO - PROCESS | 1678 | __end_tp_report 09:59:29 INFO - PROCESS | 1678 | __start_cc_report 09:59:29 INFO - PROCESS | 1678 | _x_x_mozilla_cycle_collect,1392 09:59:29 INFO - PROCESS | 1678 | __end_cc_report 09:59:29 INFO - PROCESS | 1678 | __startTimestamp1464627569903__endTimestamp 09:59:29 INFO - PROCESS | 1678 | 09:59:29 INFO - PROCESS | 1678 | ------- Summary: start ------- 09:59:29 INFO - PROCESS | 1678 | Number of tests: 6 09:59:29 INFO - PROCESS | 1678 | 09:59:29 INFO - PROCESS | 1678 | [#0] dojo.html Cycles:165 Average:5663.86 Median:5851.06 stddev:621.03 (10.6%) stddev-sans-first:595.45 09:59:29 INFO - PROCESS | 1678 | Values: 3334.8 3419.6 3428.9 3436.7 3475.8 5643.9 5649.9 5650.7 5652.0 5659.5 5591.2 5630.3 5647.2 5653.6 5662.6 5018.5 5062.1 5175.0 5219.3 5260.7 5168.1 5180.7 5188.4 5189.7 5195.6 5098.6 5141.8 5173.4 5181.4 5208.3 5129.9 5157.8 5164.4 5170.3 5200.0 5032.4 5068.6 5084.1 5095.7 5152.9 5472.3 5482.6 5494.8 5528.2 5611.8 6159.7 6163.0 6198.6 6225.4 6230.0 6090.3 6215.1 6257.4 6268.1 6283.0 5672.9 5696.2 5737.1 5773.5 5776.0 6234.1 6235.0 6242.8 6258.2 6266.8 5318.1 5851.1 6002.5 6115.8 6122.5 6131.4 6156.0 6166.8 6178.5 6181.9 6144.5 6152.3 6176.0 6178.5 6185.1 6122.5 6146.6 6171.8 6176.2 6199.8 4785.2 4810.2 4836.3 4848.0 4895.4 4392.4 4401.3 4418.5 4435.2 4435.2 5643.1 5656.9 5669.6 5675.0 5675.7 5155.8 6057.1 6067.6 6120.6 6124.9 5614.0 5631.4 5636.8 5651.2 5668.4 5304.4 5333.4 5342.4 5344.5 5378.3 6108.0 6158.9 6207.8 6214.6 6269.7 6158.6 6163.8 6168.3 6168.7 6203.8 6261.4 6271.8 6285.1 6298.2 6307.0 5963.8 6096.9 6109.7 6128.6 6147.6 5442.9 6050.8 6125.0 6157.0 6168.0 6043.6 6072.6 6077.9 6081.3 6091.6 5025.6 5839.4 5936.9 6026.0 6035.7 5860.8 5876.3 5884.3 5887.8 5895.6 5973.7 5975.5 6010.1 6018.2 6077.9 5740.0 5833.3 5950.2 5984.6 5987.7 09:59:29 INFO - PROCESS | 1678 | 09:59:29 INFO - PROCESS | 1678 | [#1] ext.html Cycles:180 Average:74196.36 Median:20426.05 stddev:170094.43 (832.7%) stddev-sans-first:170481.61 09:59:29 INFO - PROCESS | 1678 | Values: 510.2 514.2 563.9 591.0 623.0 31066.8 31756.0 31764.0 31886.0 38259.8 42920.8 44179.6 45206.8 45210.0 45420.5 9637.5 9831.7 9831.8 9831.9 9836.3 8624.5 8814.5 8836.1 9576.3 9708.5 8698.4 8788.6 11600.8 11788.6 11874.5 11515.5 11615.9 11621.1 11627.5 11633.2 7432.6 11505.9 11570.5 11576.2 11576.4 40000.6 40436.8 40534.0 40585.0 40593.2 3259.7 3288.7 3293.9 3314.8 3679.1 26088.6 26260.6 26452.5 26784.0 26816.5 2738.5 2748.7 2751.3 2792.8 2865.7 430330.0 432031.0 432616.0 432854.0 433269.0 60875.7 61086.0 61443.4 61569.0 61755.0 68620.3 69315.7 69337.0 69390.7 69564.3 17471.0 17677.6 17680.0 17703.5 17713.0 13447.4 13541.5 13558.1 13569.7 13573.9 17291.0 17481.3 19260.8 20286.7 20321.7 6956.6 7951.9 8146.1 8148.9 8176.9 970.3 1020.8 1047.6 1078.8 1136.0 30238.2 30455.2 30513.4 30563.4 30683.4 1105.5 1151.1 1168.0 1180.4 1192.9 15397.3 15410.8 15446.7 15523.4 15610.2 641538.0 645621.0 656884.0 657710.0 659686.0 52528.0 60300.4 60713.4 61114.0 61637.4 1503.5 1509.8 1511.9 1526.0 1538.7 25773.6 26561.6 29727.6 30227.4 31054.7 51550.7 51822.0 51825.7 51861.0 51964.7 575573.0 793784.0 809942.0 813111.0 816721.0 26801.5 26908.5 27208.0 27311.9 27591.3 44347.9 45777.3 46200.1 46868.8 47307.3 27702.0 37173.0 38409.8 38498.0 38641.4 38009.4 38030.9 38041.2 38517.8 39128.2 37510.4 37577.0 38091.6 38340.6 38466.0 32582.8 33091.0 33869.3 34101.5 34289.7 24002.8 24279.4 24438.5 24727.1 25059.5 09:59:29 INFO - PROCESS | 1678 | 09:59:29 INFO - PROCESS | 1678 | [#2] jquery.html Cycles:180 Average:35379.52 Median:12610.22 stddev:107720.05 (854.2%) stddev-sans-first:107990.22 09:59:29 INFO - PROCESS | 1678 | Values: 404.5 405.0 405.9 412.3 419.1 17034.6 19726.0 19970.3 20200.9 20410.8 19693.2 19952.6 20253.6 20650.4 20655.3 5684.1 5783.9 5943.6 5947.5 6002.9 5645.4 5879.4 5899.4 5953.5 5957.9 5379.4 5787.0 5888.6 5903.1 5909.4 5686.5 5834.9 5860.3 5866.4 5879.6 5103.0 5188.6 5192.5 5192.9 5216.5 26349.0 26878.0 27134.3 27406.5 27408.6 2288.9 2312.0 2513.2 2966.9 2975.1 14810.6 15178.3 15209.6 15576.9 15647.5 1655.7 1727.6 1753.7 1771.0 1799.0 114708.0 116233.6 116741.4 118194.0 118750.4 10946.4 11314.5 11515.9 11540.2 11672.6 14370.1 14432.0 14433.9 14446.2 14479.9 7593.4 8921.2 9093.6 9202.6 9229.4 9239.8 9241.9 9249.4 9315.6 9347.0 5791.7 6863.2 6892.3 6905.2 6921.3 1591.8 1730.8 1848.7 1872.6 1877.7 737.6 864.3 970.9 1008.4 1130.1 18440.6 21197.4 21800.5 21956.3 22931.5 973.4 995.7 1011.2 1059.1 1073.1 11517.8 11984.9 12012.3 12034.0 12050.0 639027.0 646785.0 652026.0 663210.4 663285.4 69078.0 71176.3 72644.0 105981.0 130149.0 1019.5 1066.5 1079.9 1081.4 1083.2 14561.0 17574.1 18394.6 18534.6 18673.6 50269.7 51080.5 51369.7 52029.0 52395.7 69848.0 69893.0 70093.6 71216.6 74738.6 7987.3 8087.8 8104.4 8187.6 8431.2 7276.8 11091.9 11502.5 12110.9 12364.9 10196.1 10386.2 10600.2 10785.9 10901.6 10529.5 10535.1 10747.0 10750.2 10931.6 10780.3 10818.9 11067.0 11222.8 11294.5 7644.9 7896.6 7990.2 8030.8 8216.5 10415.1 10625.2 10859.3 10873.9 10903.6 09:59:29 INFO - PROCESS | 1678 | 09:59:29 INFO - PROCESS | 1678 | [#3] mootools.html Cycles:180 Average:5560.83 Median:4880.41 stddev:3075.52 (63.0%) stddev-sans-first:3067.27 09:59:29 INFO - PROCESS | 1678 | Values: 1274.4 1548.6 1575.6 1633.0 1752.3 7761.3 8496.7 8585.7 8588.6 8592.7 7816.5 8563.6 8583.9 8586.0 8629.9 2164.4 2218.0 2226.7 2430.8 2459.0 2059.6 2454.2 2456.8 2457.2 2460.9 2000.7 2392.1 2397.9 2438.6 2445.4 2139.1 2358.8 2423.1 2428.8 2431.6 2370.9 2420.4 2426.7 2427.2 2430.6 8433.2 8514.2 8516.7 8526.2 8539.1 5151.5 5257.9 5259.3 5259.8 5302.3 3420.7 3480.2 3607.2 3623.1 3650.8 1043.4 1051.6 1058.9 1063.0 1066.6 9882.7 11648.8 11705.6 11719.7 11730.0 4077.6 4651.9 4813.1 4844.7 4881.3 9113.3 9983.6 10040.3 10051.7 10086.9 5413.0 5422.2 5425.3 5426.9 5477.2 4306.3 4486.5 4777.3 4940.2 4975.9 3967.4 3984.7 4037.1 4070.0 4101.2 1337.6 1339.5 1343.4 1353.6 1355.0 914.5 917.6 933.3 948.1 988.5 5749.8 5801.2 5803.6 5809.2 5874.9 425.1 463.7 466.0 468.9 484.0 3840.9 3891.5 3900.9 3971.8 4058.8 10224.6 10392.6 10423.5 10435.1 10514.1 10273.9 10285.9 10298.2 10343.2 10428.7 7766.5 8014.7 8046.4 8066.4 8100.6 5507.8 5654.5 5765.5 5828.2 5939.1 8177.6 9175.9 9286.0 9393.7 9404.3 9017.3 9376.6 9550.7 9612.7 9762.2 6631.5 6791.4 7173.2 7287.3 7526.3 8064.6 8081.2 8130.8 8133.8 8140.4 7117.4 7196.5 7211.2 7345.2 7371.0 7068.0 7091.7 7120.1 7136.8 7219.6 6707.3 6910.3 6921.2 6927.8 6931.1 4730.3 4751.7 4796.2 4899.1 4934.0 4793.8 4798.8 4826.9 4879.5 5018.5 09:59:29 INFO - PROCESS | 1678 | 09:59:29 INFO - PROCESS | 1678 | [#4] prototype.html Cycles:180 Average:5842.84 Median:6719.28 stddev:1892.06 (28.2%) stddev-sans-first:1851.45 09:59:29 INFO - PROCESS | 1678 | Values: 322.5 329.2 331.3 336.1 342.8 6661.8 6778.7 6782.2 6786.3 6799.7 6656.9 6714.2 6724.3 6725.6 6731.5 4689.9 4816.8 4821.8 4922.1 4988.0 4039.8 4976.1 4978.1 4987.2 5039.0 4903.6 4942.7 4944.8 4949.4 4955.6 4670.5 4776.8 4872.4 4893.2 4932.8 4193.1 4204.5 4213.1 4218.9 4233.5 5258.6 6377.0 6402.8 6411.9 6461.5 5868.4 5879.6 5879.8 5895.1 5904.0 6826.0 7162.7 7178.1 7190.7 7192.6 4025.5 4608.8 4621.5 4632.8 4658.4 7458.1 7532.9 7573.1 7594.7 7611.2 4681.7 4696.3 4696.9 4708.5 4711.8 4763.8 4771.6 4774.8 4780.8 4787.2 4560.0 5709.5 5711.5 5726.7 5729.0 7018.6 7147.6 7154.2 7175.2 7215.6 1371.7 1611.5 1647.0 1664.1 1668.9 977.3 986.1 1022.9 1028.4 1052.5 6534.2 6540.4 6570.0 6570.3 6741.5 6193.5 7534.8 7537.7 7563.2 7564.9 6735.1 6752.1 6761.3 6775.1 6779.9 3828.9 3925.5 3969.0 3978.0 3981.7 7809.3 7828.5 7851.3 7854.8 7865.8 6275.4 7660.8 7718.8 7723.5 7733.2 7817.6 7819.8 7823.3 7836.3 7846.1 7145.5 7504.0 7506.0 7515.5 7530.6 6005.3 6210.7 7319.7 7328.5 7388.3 7777.1 7808.3 7822.8 7822.9 7831.0 4653.0 4724.3 4725.9 4734.1 4737.4 7117.5 7417.8 7420.9 7436.9 7444.6 6106.8 7076.7 7144.5 7197.6 7233.6 7152.1 7181.2 7182.6 7193.2 7194.5 7011.4 7016.1 7022.7 7031.3 7051.9 6900.6 7163.7 7166.8 7188.6 7192.6 6689.5 6782.6 6794.6 6912.6 6949.3 09:59:29 INFO - PROCESS | 1678 | 09:59:29 INFO - PROCESS | 1678 | [#5] yui.html Cycles:180 Average:17963.96 Median:3558.37 stddev:25464.15 (715.6%) stddev-sans-first:25502.54 09:59:29 INFO - PROCESS | 1678 | Values: 685.4 734.5 753.0 754.1 794.1 14673.3 14755.3 14984.7 14992.1 15006.7 13679.4 13744.9 13768.3 13769.0 13789.3 748.0 759.9 761.5 913.3 946.3 759.0 761.5 909.3 943.2 944.4 980.0 988.7 990.9 992.1 996.6 974.7 977.8 979.2 982.2 982.5 574.0 581.1 581.9 582.7 729.2 14538.2 14618.0 14638.0 14641.0 14669.4 10410.9 12425.1 12642.1 12657.3 12950.9 9362.0 9604.0 10093.3 10179.3 10198.6 3060.5 3441.5 3470.4 3487.8 3506.6 80685.5 82077.0 82111.0 82372.6 82594.6 9385.0 9449.5 9513.4 9617.4 9797.3 21655.6 21656.0 21691.8 22149.9 22501.3 6463.8 7577.2 7603.0 7954.6 8083.0 4041.6 4057.5 4137.2 4204.7 4396.5 3051.8 3579.8 3624.6 3628.1 3635.8 646.6 663.0 665.2 666.0 688.8 523.3 595.3 611.7 619.7 652.9 7495.7 7891.7 7948.2 8136.1 8366.7 594.9 595.2 605.7 609.6 611.8 3816.0 3850.8 3854.5 3970.7 4008.6 89721.0 89839.0 90580.6 91147.5 91389.5 77437.0 77590.2 78146.6 78241.6 79351.6 68827.7 69519.3 69803.0 70719.0 70967.3 6651.0 7250.8 7652.6 7684.7 7864.2 44015.6 48069.3 48277.5 48587.3 48718.3 62139.4 64288.0 65735.4 66247.3 66261.7 10362.0 10381.7 10567.2 10569.6 10571.0 10627.6 10758.2 10771.5 10880.0 10940.7 9537.4 9799.9 9950.2 10137.5 10393.0 8581.8 10593.9 11294.8 11707.4 12094.0 10785.9 11044.8 11297.3 11390.8 11523.8 9267.3 9378.9 9524.7 9617.0 9677.3 8052.7 9420.5 9436.1 9503.5 9543.1 09:59:29 INFO - PROCESS | 1678 | -------- Summary: end -------- 09:59:29 INFO - PROCESS | 1678 | 09:59:30 INFO - TEST-INFO | 1678: exit 0 09:59:30 INFO - TEST-OK | dromaeo_css | took 1115281ms 09:59:30 INFO - TEST-START | kraken 09:59:30 INFO - operating with platform_type : mac_ 09:59:30 INFO - Initialising browser for kraken test... 09:59:30 INFO - TEST-INFO | started process 1685 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/tmp_zUG4P/profile http://localhost:49277/getInfo.html) 09:59:32 INFO - PROCESS | 1685 | __metrics Screen width/height:1600/1200 09:59:32 INFO - PROCESS | 1685 | colorDepth:24 09:59:32 INFO - PROCESS | 1685 | Browser inner width/height: 1024/689 09:59:32 INFO - PROCESS | 1685 | __metrics 09:59:32 INFO - PROCESS | 1685 | 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" 09:59:32 ERROR - PROCESS | 1685 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:59:33 INFO - TEST-INFO | 1685: exit 0 09:59:33 INFO - Browser initialized. 09:59:33 INFO - Running cycle 1/1 for kraken test... 09:59:33 INFO - TEST-INFO | started process 1687 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/tmp_zUG4P/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:59:45 INFO - PROCESS | 1687 | RSS: Main: 211214336 09:59:45 INFO - PROCESS | 1687 | 09:59:48 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 09:59:49 INFO - PROCESS | 1687 | RSS: Main: 271405056 09:59:49 INFO - PROCESS | 1687 | 09:59:52 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 09:59:52 INFO - PROCESS | 1687 | RSS: Main: 285609984 09:59:52 INFO - PROCESS | 1687 | 09:59:55 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 09:59:56 INFO - PROCESS | 1687 | RSS: Main: 373501952 09:59:56 INFO - PROCESS | 1687 | 09:59:58 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 09:59:59 INFO - PROCESS | 1687 | RSS: Main: 278564864 09:59:59 INFO - PROCESS | 1687 | 10:00:01 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:00:01 INFO - PROCESS | 1687 | RSS: Main: 280686592 10:00:01 INFO - PROCESS | 1687 | 10:00:04 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 10:00:05 INFO - PROCESS | 1687 | RSS: Main: 318971904 10:00:05 INFO - PROCESS | 1687 | 10:00:07 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 10:00:08 INFO - PROCESS | 1687 | RSS: Main: 318902272 10:00:08 INFO - PROCESS | 1687 | 10:00:11 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 10:00:12 INFO - PROCESS | 1687 | RSS: Main: 315666432 10:00:12 INFO - PROCESS | 1687 | 10:00:13 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:00:14 INFO - PROCESS | 1687 | RSS: Main: 309026816 10:00:14 INFO - PROCESS | 1687 | 10:00:16 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:00:16 INFO - PROCESS | 1687 | RSS: Main: 316452864 10:00:16 INFO - PROCESS | 1687 | 10:00:19 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:00:19 INFO - PROCESS | 1687 | RSS: Main: 318959616 10:00:19 INFO - PROCESS | 1687 | 10:00:22 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:00:23 INFO - PROCESS | 1687 | RSS: Main: 323829760 10:00:23 INFO - PROCESS | 1687 | 10:00:25 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:00:26 INFO - PROCESS | 1687 | RSS: Main: 321753088 10:00:26 INFO - PROCESS | 1687 | 10:00:28 INFO - PROCESS | 1687 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 10:00:28 INFO - PROCESS | 1687 | RSS: Main: 386785280 10:00:28 INFO - PROCESS | 1687 | 10:00:28 INFO - PROCESS | 1687 | __start_tp_report 10:00:28 INFO - PROCESS | 1687 | _x_x_mozilla_page_load 10:00:28 INFO - PROCESS | 1687 | _x_x_mozilla_page_load_details 10:00:28 INFO - PROCESS | 1687 | |i|pagename|runs| 10:00:28 INFO - PROCESS | 1687 | |0;ai-astar;114;114;118;112;112;112;114;113;113;134 10:00:28 INFO - PROCESS | 1687 | |1;audio-beat-detection;156;120;140;169;149;172;139;119;171;116 10:00:28 INFO - PROCESS | 1687 | |2;audio-dft;182;184;187;183;179;184;186;184;187;185 10:00:28 INFO - PROCESS | 1687 | |3;audio-fft;70;74;72;150;56;56;59;62;134;55 10:00:28 INFO - PROCESS | 1687 | |4;audio-oscillator;98;94;145;78;80;87;124;79;80;127 10:00:28 INFO - PROCESS | 1687 | |5;imaging-gaussian-blur;88;85;87;85;88;85;88;85;87;85 10:00:28 INFO - PROCESS | 1687 | |6;imaging-darkroom;93;94;93;94;93;94;94;94;93;93 10:00:28 INFO - PROCESS | 1687 | |7;imaging-desaturate;99;132;132;86;86;83;83;86;87;71 10:00:28 INFO - PROCESS | 1687 | |8;json-parse-financial;61;65;64;64;63;63;65;85;59;58 10:00:28 INFO - PROCESS | 1687 | |9;json-stringify-tinderbox;65;63;72;74;76;73;69;69;68;69 10:00:28 INFO - PROCESS | 1687 | |10;stanford-crypto-aes;81;81;80;81;80;80;79;81;83;85 10:00:28 INFO - PROCESS | 1687 | |11;stanford-crypto-ccm;137;137;143;140;127;126;126;129;153;138 10:00:28 INFO - PROCESS | 1687 | |12;stanford-crypto-pbkdf2;167;160;157;167;166;170;165;161;160;166 10:00:28 INFO - PROCESS | 1687 | |13;stanford-crypto-sha256-iterative;67;67;68;67;68;69;69;93;92;66 10:00:28 INFO - PROCESS | 1687 | __end_tp_report 10:00:28 INFO - PROCESS | 1687 | __start_cc_report 10:00:28 INFO - PROCESS | 1687 | _x_x_mozilla_cycle_collect,2041 10:00:28 INFO - PROCESS | 1687 | __end_cc_report 10:00:28 INFO - PROCESS | 1687 | __startTimestamp1464627628596__endTimestamp 10:00:28 INFO - PROCESS | 1687 | 10:00:28 INFO - PROCESS | 1687 | ------- Summary: start ------- 10:00:28 INFO - PROCESS | 1687 | Number of tests: 14 10:00:28 INFO - PROCESS | 1687 | 10:00:28 INFO - PROCESS | 1687 | [#0] ai-astar Cycles:10 Average:115.60 Median:114.00 stddev:6.70 (5.9%) stddev-sans-first:7.08 10:00:28 INFO - PROCESS | 1687 | Values: 114.0 114.0 118.0 112.0 112.0 112.0 114.0 113.0 113.0 134.0 10:00:28 INFO - PROCESS | 1687 | 10:00:28 INFO - PROCESS | 1687 | [#1] audio-beat-detection Cycles:10 Average:145.10 Median:152.50 stddev:21.91 (14.4%) stddev-sans-first:22.88 10:00:28 INFO - PROCESS | 1687 | Values: 156.0 120.0 140.0 169.0 149.0 172.0 139.0 119.0 171.0 116.0 10:00:28 INFO - PROCESS | 1687 | 10:00:28 INFO - PROCESS | 1687 | [#2] audio-dft Cycles:10 Average:184.10 Median:184.50 stddev:2.42 (1.3%) stddev-sans-first:2.45 10:00:28 INFO - PROCESS | 1687 | Values: 182.0 184.0 187.0 183.0 179.0 184.0 186.0 184.0 187.0 185.0 10:00:28 INFO - PROCESS | 1687 | 10:00:28 INFO - PROCESS | 1687 | [#3] audio-fft Cycles:10 Average:78.80 Median:60.50 stddev:34.23 (56.6%) stddev-sans-first:36.16 10:00:28 INFO - PROCESS | 1687 | Values: 70.0 74.0 72.0 150.0 56.0 56.0 59.0 62.0 134.0 55.0 10:00:28 INFO - PROCESS | 1687 | 10:00:28 INFO - PROCESS | 1687 | [#4] audio-oscillator Cycles:10 Average:99.20 Median:80.00 stddev:24.17 (30.2%) stddev-sans-first:25.63 10:00:28 INFO - PROCESS | 1687 | Values: 98.0 94.0 145.0 78.0 80.0 87.0 124.0 79.0 80.0 127.0 10:00:28 INFO - PROCESS | 1687 | 10:00:28 INFO - PROCESS | 1687 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:87.00 stddev:1.42 (1.6%) stddev-sans-first:1.36 10:00:28 INFO - PROCESS | 1687 | Values: 88.0 85.0 87.0 85.0 88.0 85.0 88.0 85.0 87.0 85.0 10:00:28 INFO - PROCESS | 1687 | 10:00:28 INFO - PROCESS | 1687 | [#6] imaging-darkroom Cycles:10 Average:93.50 Median:94.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 10:00:28 INFO - PROCESS | 1687 | Values: 93.0 94.0 93.0 94.0 93.0 94.0 94.0 94.0 93.0 93.0 10:00:28 INFO - PROCESS | 1687 | 10:00:28 INFO - PROCESS | 1687 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:86.00 stddev:20.88 (24.3%) stddev-sans-first:22.08 10:00:28 INFO - PROCESS | 1687 | Values: 99.0 132.0 132.0 86.0 86.0 83.0 83.0 86.0 87.0 71.0 10:00:28 INFO - PROCESS | 1687 | 10:00:28 INFO - PROCESS | 1687 | [#8] json-parse-financial Cycles:10 Average:64.70 Median:64.00 stddev:7.53 (11.8%) stddev-sans-first:7.87 10:00:28 INFO - PROCESS | 1687 | Values: 61.0 65.0 64.0 64.0 63.0 63.0 65.0 85.0 59.0 58.0 10:00:28 INFO - PROCESS | 1687 | 10:00:28 INFO - PROCESS | 1687 | [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:70.50 stddev:4.02 (5.7%) stddev-sans-first:3.87 10:00:28 INFO - PROCESS | 1687 | Values: 65.0 63.0 72.0 74.0 76.0 73.0 69.0 69.0 68.0 69.0 10:00:28 INFO - PROCESS | 1687 | 10:00:28 INFO - PROCESS | 1687 | [#10] stanford-crypto-aes Cycles:10 Average:81.10 Median:81.00 stddev:1.73 (2.1%) stddev-sans-first:1.83 10:00:28 INFO - PROCESS | 1687 | Values: 81.0 81.0 80.0 81.0 80.0 80.0 79.0 81.0 83.0 85.0 10:00:28 INFO - PROCESS | 1687 | 10:00:28 INFO - PROCESS | 1687 | [#11] stanford-crypto-ccm Cycles:10 Average:135.60 Median:137.50 stddev:8.75 (6.4%) stddev-sans-first:9.26 10:00:28 INFO - PROCESS | 1687 | Values: 137.0 137.0 143.0 140.0 127.0 126.0 126.0 129.0 153.0 138.0 10:00:28 INFO - PROCESS | 1687 | 10:00:28 INFO - PROCESS | 1687 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.90 Median:166.00 stddev:4.12 (2.5%) stddev-sans-first:4.22 10:00:28 INFO - PROCESS | 1687 | Values: 167.0 160.0 157.0 167.0 166.0 170.0 165.0 161.0 160.0 166.0 10:00:28 INFO - PROCESS | 1687 | 10:00:28 INFO - PROCESS | 1687 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.60 Median:68.50 stddev:10.53 (15.4%) stddev-sans-first:10.97 10:00:28 INFO - PROCESS | 1687 | Values: 67.0 67.0 68.0 67.0 68.0 69.0 69.0 93.0 92.0 66.0 10:00:28 INFO - PROCESS | 1687 | -------- Summary: end -------- 10:00:28 INFO - PROCESS | 1687 | 10:00:29 INFO - TEST-INFO | 1687: exit 0 10:00:29 INFO - TEST-OK | kraken | took 58826ms 10:00:29 INFO - SUITE-END | took 1174s 10:00:29 INFO - Completed test suite (00:19:34) 10:00:29 INFO - javascript benchmark 10:00:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3334.849931753071, 3419.5725534308194, 3428.9314213715725, 3436.742244331676, 3475.7740746851455, 5643.915341269884, 5649.887002259952, 5650.74571644276, 5652.010898092833, 5659.547236221103, 5591.217229587861, 5630.324361076667, 5647.209390685297, 5653.632513886594, 5662.631928924617, 5018.5232402921665, 5062.063518249136, 5174.965006998596, 5219.269302297681, 5260.684358938457, 5168.121419358718, 5180.715060671661, 5188.429272779991, 5189.688618682872, 5195.558377537895, 5098.59211263098, 5141.82003629873, 5173.3533308336455, 5181.378234611833, 5208.2968799212, 5129.897402051975, 5157.8452646420665, 5164.354455693038, 5170.3278573785165, 5200.037992971274, 5032.396112466491, 5068.5691716204165, 5084.13569693153, 5095.719735414551, 5152.9484705152845, 5472.315960504937, 5482.5888058395385, 5494.780208791643, 5528.170774383834, 5611.775528978836, 6159.722812473449, 6163.0, 6198.597091189058, 6225.408586184305, 6229.968850155766, 6090.26916769986, 6215.12988181655, 6257.374262573751, 6268.091126786636, 6282.96858515709, 5672.858178545528, 5696.202531645573, 5737.053386191315, 5773.538116950634, 5776.046952252916, 6234.096056071935, 6235.033569796688, 6242.75028998844, 6258.186435763321, 6266.811995640138, 5318.149096144598, 5851.063829787298, 6002.459778619857, 6115.755369785248, 6122.479589234876, 6131.448169664663, 6155.96922015396, 6166.845828854225, 6178.5366097542865, 6181.907271390933, 6144.494598823317, 6152.2924863640355, 6176.042713379434, 6178.5366097542865, 6185.134081228632, 6122.479589234965, 6146.600470969463, 6171.84570385735, 6176.197094377702, 6199.783007594712, 4785.210440277385, 4810.230363141881, 4836.322914791932, 4847.981923795972, 4895.388076490439, 4392.4289842320295, 4401.2517871961845, 4418.491873434532, 4435.157320109169, 4435.223835828717, 5643.068893632587, 5656.943430565724, 5669.603127781098, 5675.0, 5675.6594604323045, 5155.845324640267, 6057.091436284592, 6067.635941843503, 6120.571559990758, 6124.908126378108, 5614.0, 5631.4087020863035, 5636.830895073154, 5651.208830763697, 5668.404817494186, 5304.416514183436, 5333.359996800409, 5342.358916929993, 5344.465553444702, 5378.327709036371, 6107.969460152671, 6158.860610787019, 6207.751689932533, 6214.5960512566535, 6269.686515674107, 6158.568900176944, 6163.7842675506145, 6168.290646575701, 6168.722407491584, 6203.844903877439, 6261.373862613702, 6271.78048768291, 6285.088662144055, 6298.1812364392335, 6306.9684651576445, 5963.7912673056235, 6096.8780624388155, 6109.663968481598, 6128.571000029955, 6147.555324498829, 5442.8639284016635, 6050.757969681164, 6125.0, 6156.994883314614, 6167.969160154171, 6043.576949613661, 6072.605280656743, 6077.878442431038, 6081.300650425258, 6091.604045737013, 5025.572826309658, 5839.445252700986, 5936.940630593639, 6026.035834266497, 6035.668038257938, 5860.794872179624, 5876.324222714443, 5884.293884733859, 5887.764489420375, 5895.587308888508, 5973.671448070398, 5975.492083172979, 6010.068439391901, 6018.187544681412, 6077.878442431215, 5739.971300143473, 5833.329167145833, 5950.16697662319, 5984.581079324405, 5987.670678112746], "value": 5625.6797537912935, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [510.17351889938305, 514.2466286889705, 563.8618538458078, 590.9658098327925, 622.9627669929567, 31066.84466577667, 31756.0, 31764.0, 31886.0, 38259.80870095653, 42920.78539607298, 44179.55820441803, 45206.773966130204, 45210.0, 45420.54579454204, 9637.469939153327, 9831.65589204378, 9831.803363932717, 9831.901680983206, 9836.262280329003, 8624.482531048126, 8814.515201663906, 8836.0722124177, 9576.281778866578, 9708.466034368106, 8698.434601750865, 8788.64845406183, 11600.825987610193, 11788.587399441021, 11874.465649045771, 11515.539378424854, 11615.941920290428, 11621.070314374872, 11627.476763545661, 11633.243839150427, 7432.62836858155, 11505.884941150565, 11570.479328430241, 11576.24754390962, 11576.421178941104, 40000.59999399998, 40436.79781601073, 40534.0, 40585.0, 40593.188136237404, 3259.6903294187014, 3288.720458761008, 3293.8517766700315, 3314.767966242364, 3679.0986208379118, 26088.60867086995, 26260.606090908652, 26452.47095058107, 26784.0, 26816.463670726673, 2738.5344491436504, 2748.670159580843, 2751.317490305248, 2792.8184667996516, 2865.694200001982, 430330.0, 432031.0, 432616.0, 432854.0, 433269.0, 60875.69562152161, 61086.0, 61443.38556614466, 61569.0, 61755.0, 68620.31379686239, 69315.65342173257, 69336.95994560086, 69390.65304673444, 69564.3043569568, 17471.0, 17677.646447071245, 17680.0, 17703.468895933143, 17713.0, 13447.394867230998, 13541.45834166642, 13558.050936434354, 13569.660758481117, 13573.932130339284, 17291.0, 17481.300747969937, 19260.80739192618, 20286.69569956452, 20321.695174572393, 6956.582605043714, 7951.920480795234, 8146.14465481116, 8148.918510814935, 8176.936998190316, 970.2965350121247, 1020.7907378987289, 1047.560024789575, 1078.82199437092, 1136.0286954653818, 30238.244043898638, 30455.23861903426, 30513.42938101133, 30563.38873222523, 30683.38633227323, 1105.4528008635616, 1151.1423989128152, 1167.9955238494888, 1180.4333919718583, 1192.8628207756046, 15397.307121179574, 15410.768838467431, 15446.691066178613, 15523.379064837505, 15610.177657033077, 641538.0, 645621.0, 656884.0, 657710.0, 659686.0, 52528.0, 60300.39699603036, 60713.392866070775, 61114.0, 61637.38362616406, 1503.548935319378, 1509.8490150984815, 1511.894167408271, 1526.0462211118052, 1538.692261547695, 25773.613395798704, 26561.60157597599, 29727.55408668915, 30227.395452090394, 31054.68945310518, 51550.74224628853, 51822.0, 51825.740871295406, 51861.0, 51964.740176298874, 575573.0, 793784.0, 809942.0, 813111.0, 816721.0, 26801.463970720866, 26908.461830762884, 27208.0, 27311.90752369883, 27591.31021724473, 44347.89130271769, 45777.313340300585, 46200.07599847917, 46868.82827929193, 47307.290390644856, 27702.03042893489, 37173.0, 38409.807950960065, 38498.0375490615, 38641.42037869378, 38009.440564647724, 38030.85907422782, 38041.23917521689, 38517.80741096276, 39128.2174356517, 37510.43734343933, 37577.0, 38091.619083809914, 38340.61659383371, 38466.0, 32582.83708581537, 33091.0, 33869.32261354809, 34101.488477672356, 34289.657103428646, 24002.759972400054, 24279.393015174057, 24438.51122977566, 24727.134550290655, 25059.49881002333], "value": 20363.403183392536, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [404.45196758392376, 404.9939250911236, 405.92287465381594, 412.3320221241589, 419.08430080274604, 17034.5741356466, 19726.0, 19970.301039463604, 20200.898995505002, 20410.795892041075, 19693.21227150912, 19952.600947981067, 20253.59492810143, 20650.380488585328, 20655.27706530272, 5684.118961560965, 5783.913241301384, 5943.643381397108, 5947.464728174464, 6002.8799424011495, 5645.407232240596, 5879.382664820197, 5899.351071382166, 5953.464188223059, 5957.851053723648, 5379.408265090837, 5786.971065144689, 5888.558358123137, 5903.085021821603, 5909.409059094078, 5686.4597863203135, 5834.912476312858, 5860.267466566679, 5866.413358664121, 5879.647221166701, 5102.974485127551, 5188.558972487305, 5192.454792246797, 5192.870178245536, 5216.478352164791, 26349.0, 26878.0, 27134.32164195891, 27406.451870962468, 27408.588871166947, 2288.9084436622516, 2311.9884400578057, 2513.195777351248, 2966.9109926702235, 2975.092596757988, 14810.555683329518, 15178.316975736008, 15209.6197595061, 15576.92211538935, 15647.530574082795, 1655.6936966661085, 1727.5767436978022, 1753.6668033073674, 1771.008235388174, 1798.9640207195914, 114708.0, 116233.60715160663, 116741.41629291799, 118194.0, 118750.40624796908, 10946.397948112772, 11314.490847911864, 11515.884841151648, 11540.192186547029, 11672.649820505398, 14370.065945713495, 14431.98976071665, 14433.855661443462, 14446.205458699873, 14479.855201448061, 7593.430492713069, 8921.197092261727, 9093.590788414538, 9202.585883635253, 9229.446233226028, 9239.815203695889, 9241.861372079424, 9249.445033298023, 9315.580798864068, 9346.953265233766, 5791.710414479344, 6863.245043045261, 6892.345227203407, 6905.240423553405, 6921.273266307066, 1591.8248992610804, 1730.8182640822533, 1848.7134494153427, 1872.6348362069266, 1877.7371168036213, 737.557465520694, 864.3474176996453, 970.9077637624414, 1008.3949630221955, 1130.0902773267435, 18440.631187376177, 21197.36407907765, 21800.476453356783, 21956.341309760734, 22931.541369172857, 973.4305431322756, 995.6515219673056, 1011.174829432866, 1059.0838924330553, 1073.0771536478778, 11517.827232591519, 11984.940075299568, 12012.279263244232, 12033.977111945407, 12050.0, 639027.0, 646785.0, 652026.0, 663210.3678963245, 663285.367146332, 69078.0, 71176.28823711697, 72644.0, 105981.0, 130149.0, 1019.5463018956641, 1066.4987455895716, 1079.8995952871746, 1081.4376524207369, 1083.1767856429171, 14561.0, 17574.121293935506, 18394.632107358044, 18534.629307414045, 18673.626527469103, 50269.74865125651, 51080.48919510757, 51369.743151284005, 52029.0, 52395.73802130965, 69847.95228071482, 69893.0, 70093.64953175202, 71216.64391678009, 74738.62630686811, 7987.281144696773, 8087.83824323522, 8104.392170587112, 8187.631556579849, 8431.15688431152, 7276.818079547842, 11091.889081109086, 11502.539898404304, 12110.910018098413, 12364.938175309066, 10196.082352588302, 10386.169106471616, 10600.151987841109, 10785.892141078803, 10901.563937442414, 10529.526171322155, 10535.05184533365, 10746.979036991474, 10750.24748267645, 10931.617393391192, 10780.275155975007, 10818.891811082103, 11067.0, 11222.7755444889, 11294.548218071513, 7644.885326720215, 7896.644650990834, 7990.200979901963, 8030.759077227693, 8216.46592971455, 10415.062644362044, 10625.203109766644, 10859.294145880493, 10873.945630271797, 10903.56385744593], "value": 9686.385323317309, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1274.3691872523102, 1548.566401407606, 1575.5903465099075, 1633.003867640739, 1752.2990803678526, 7761.262680045394, 8496.660133594656, 8585.742427727162, 8588.570571471433, 8592.699255526059, 7816.5310081395155, 8563.614637341334, 8583.871241931376, 8586.0, 8629.870551941725, 2164.350694791564, 2217.966730499044, 2226.7196362091863, 2430.7569243075727, 2459.0409740201335, 2059.6086743518686, 2454.214651311572, 2456.754324567538, 2457.2259738182506, 2460.938476538092, 2000.7098970649247, 2392.0790495659207, 2397.9160729374476, 2438.5610590093866, 2445.40087678519, 2139.0908863733007, 2358.846674966139, 2423.127674037345, 2428.7571242875747, 2431.6109422492405, 2370.881455927197, 2420.382802385398, 2426.745191754858, 2427.1747605814107, 2430.611102223636, 8433.241008309282, 8514.191151840565, 8516.70191543293, 8526.190011948855, 8539.103394143649, 5151.459096794858, 5257.86855328616, 5259.342582177228, 5259.8422047338645, 5302.284191634157, 3420.743444241692, 3480.2343484433386, 3607.188382613891, 3623.1304486923214, 3650.7809531428197, 1043.3844032021143, 1051.6319288249128, 1058.8888166742536, 1062.95216715248, 1066.589363095214, 9882.654107106217, 11648.825267620992, 11705.648830535012, 11719.7070073248, 11730.0, 4077.5922407758985, 4651.883702907455, 4813.109574728686, 4844.660873738804, 4881.316615673809, 9113.270938324918, 9983.550740216708, 10040.347377420445, 10051.748706282255, 10086.890442051368, 5412.971535408338, 5422.240886275925, 5425.321834770653, 5426.864328391743, 5477.20580515831, 4306.3325184595815, 4486.484054333728, 4777.283407488904, 4940.2342636891335, 4975.925361119586, 3967.3850553164307, 3984.6812255019527, 4037.1118353962083, 4070.0, 4101.179764047202, 1337.5719769673806, 1339.5110784563565, 1343.368616750126, 1353.64128505946, 1355.0, 914.4610357914912, 917.5916717060977, 933.3233405780891, 948.0661548374845, 988.5057471264367, 5749.8275051748515, 5801.245838041028, 5803.622764520293, 5809.157672137517, 5874.9118763218585, 425.1263653192719, 463.7009129111704, 466.0492595105946, 468.9413823272091, 483.9806407743651, 3840.9039774005314, 3891.5330160380936, 3900.941485877714, 3971.7616942983514, 4058.837646494166, 10224.64213752515, 10392.636257730943, 10423.530941107669, 10435.113015393777, 10514.05373516372, 10273.897261027294, 10285.948570257251, 10298.17614590831, 10343.224258180666, 10428.687139385831, 7766.495177813424, 8014.71948481812, 8046.356291496783, 8066.354691624656, 8100.635471403801, 5507.843352895937, 5654.519365853784, 5765.509931655856, 5828.154917536848, 5939.10913362999, 8177.591120443835, 9175.862362064441, 9286.0, 9393.671221507215, 9404.341696081208, 9017.278617710435, 9376.57805398745, 9550.66572669981, 9612.66355677548, 9762.219022478042, 6631.469482441489, 6791.3548212919695, 7173.210946795953, 7287.307705767944, 7526.285002924714, 8064.55644939534, 8081.151479094846, 8130.796730081559, 8133.8373232536205, 8140.430169888051, 7117.43060555144, 7196.460265480215, 7211.1707153678, 7345.228750981284, 7370.96314518424, 7068.0, 7091.716331346688, 7120.074390329224, 7136.821579460555, 7219.639018048972, 6707.295733947865, 6910.274421185703, 6921.2386637468835, 6927.826804329932, 6931.064306318583, 4730.290456431563, 4751.714897106184, 4796.208625576741, 4899.118158731462, 4934.037862616755, 4793.784279707491, 4798.808047678054, 4826.927596086131, 4879.512048795092, 5018.5232402921665], "value": 4435.883488219373, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.51944602542216, 329.1622819923295, 331.3059141099396, 336.13613014552595, 342.8028883392047, 6661.766838160659, 6778.661066946657, 6782.220044694862, 6786.253512113663, 6799.728010879565, 6656.866862662744, 6714.227863795665, 6724.327567243273, 6725.630090345029, 6731.495137864667, 4689.85930422087, 4816.783244753994, 4821.783019764119, 4922.138625740481, 4988.02733466974, 4039.7576145431367, 4976.054549635575, 4978.054169707744, 4987.177115775893, 5039.042581909425, 4903.632227582928, 4942.703437793726, 4944.7774850131655, 4949.406071271453, 4955.6035517158725, 4670.486246512881, 4776.761161941924, 4872.366592343008, 4893.1926232171645, 4932.778024988884, 4193.119444916571, 4204.453421055274, 4213.115245798386, 4218.936715949263, 4233.470816147981, 5258.579313654898, 6377.0, 6402.839929001766, 6411.93588064118, 6461.547691661585, 5868.442497962729, 5879.647221166743, 5879.794207202727, 5895.145203945447, 5903.970480147615, 6825.965870170618, 7162.677679504435, 7178.138623365229, 7190.7483238086415, 7192.640367981632, 4025.496812898388, 4608.769561521944, 4621.514740952185, 4632.791524381411, 4658.371119898804, 7458.142313633893, 7532.907728379354, 7573.091229052494, 7594.658240379196, 7611.2388761124, 4681.695689780221, 4696.2955556666775, 4696.929546056812, 4708.458527269339, 4711.8350857719815, 4763.809447622126, 4771.618270538349, 4774.761261936958, 4780.760961951958, 4787.210110331756, 4560.005098264131, 5709.514691251207, 5711.514521265739, 5726.713664316768, 5728.971355143281, 7018.649067546602, 7147.642617869085, 7154.213036565973, 7175.2465991070185, 7215.5670659759535, 1371.6913694418874, 1611.4843250159836, 1646.9752953705704, 1664.0930692772467, 1668.8581470574895, 977.2572844638127, 986.0829428631441, 1022.9028242316969, 1028.3521381529588, 1052.5421441672897, 6534.248561415403, 6540.411362977355, 6570.0, 6570.310117437696, 6741.52809303344, 6193.482596763732, 7534.811629709301, 7537.698492060256, 7563.168051514329, 7564.9243507565325, 6735.090762747064, 6752.054712340277, 6761.3238676132, 6775.0514927910135, 6779.898301525481, 3828.8851334459455, 3925.548561915359, 3969.0077480629843, 3978.0, 3981.7212795104642, 7809.297163255221, 7828.452008359474, 7851.293383595504, 7854.8429031419055, 7865.842683146305, 6275.435210830956, 7660.770176894702, 7718.807029824187, 7723.497972631873, 7733.2266773323345, 7817.609119544114, 7819.832967648112, 7823.29590336861, 7836.255555722198, 7846.136924938252, 7145.535540189871, 7503.962480187564, 7506.024216851774, 7515.473916825988, 7530.623468826647, 6005.339412664691, 6210.72051757681, 7319.707211711472, 7328.450366222448, 7388.335049845539, 7777.144514103557, 7808.297253247235, 7822.804429889298, 7822.882656760039, 7831.0, 4653.022465452237, 4724.267738500538, 4725.881852953704, 4734.1478533862855, 4737.431508219036, 7117.501774875916, 7417.8145546361775, 7420.925790742023, 7436.851262974602, 7444.553326800409, 6106.755729770759, 7076.690812199759, 7144.464165187528, 7197.568145911262, 7233.565986040854, 7152.141742990874, 7181.245969173162, 7182.640867956686, 7193.244709305448, 7194.460415468965, 7011.4390848733, 7016.087908571853, 7022.719091236498, 7031.296870312928, 7051.858962820817, 6900.6204658742245, 7163.677634506355, 7166.820829479305, 7188.6046267455795, 7192.640367981685, 6689.49828762835, 6782.626955517494, 6794.626295553792, 6912.585244885323, 6949.339812717783], "value": 5202.552420273299, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [685.366036416315, 734.4858598980715, 752.9962350188255, 754.0950858969238, 794.1383598795306, 14673.339699713511, 14755.336009879555, 14984.700305993874, 14992.10047397157, 15006.699866002673, 13679.384427700752, 13744.862551374483, 13768.311584420764, 13769.03616746828, 13789.310534473287, 747.9813004674872, 759.8594260061906, 761.4669731188163, 913.2648218184358, 946.2997381937352, 759.0322339017769, 761.5468796066359, 909.3089252168367, 943.1982814607597, 944.4380593546829, 980.0493521284343, 988.6836212412031, 990.9157721593673, 992.136840948376, 996.576455006623, 974.6929717139114, 977.8435450327913, 979.2313034268101, 982.2142286171106, 982.4596471940475, 574.0041028815002, 581.102197105473, 581.9476247137735, 582.7348556406824, 729.230661651957, 14538.200398978051, 14618.0, 14638.048526845827, 14641.048331858396, 14669.413223471049, 10410.947945260225, 12425.130240883142, 12642.051846111486, 12657.30384828834, 12950.87049129506, 9362.0, 9604.03959604041, 10093.293469457141, 10179.338343007681, 10198.643047493302, 3060.5256185291314, 3441.5009823575692, 3470.4273794823825, 3487.773294735834, 3506.6493350664987, 80685.49514640677, 82077.0, 82111.0, 82372.58813705953, 82594.58702706508, 9384.967653558104, 9449.480278584744, 9513.429194248367, 9617.422954622745, 9797.265205109503, 21655.566888662455, 21655.96816445699, 21691.783082168975, 22149.889250553642, 22501.32496025122, 6463.836509428254, 7577.242275772379, 7602.961985190039, 7954.5624990626075, 8082.959585202037, 4041.6362527372676, 4057.472528571267, 4137.213929353413, 4204.66362690984, 4396.450443694538, 3051.8016328938993, 3579.838907249128, 3624.637536246407, 3628.1473853644375, 3635.781853088823, 646.6184950879004, 663.0419044480775, 665.1984358847577, 666.044226534924, 688.8036909480774, 523.3275241314892, 595.2882931596453, 611.7247238742584, 619.6715740657459, 652.8988006858945, 7495.725726626378, 7891.684332626631, 7948.244916732901, 8136.064352599408, 8366.665333386718, 594.913737508059, 595.2499850188793, 605.7213681706377, 609.6220343387129, 611.7552978808512, 3815.988762977813, 3850.845966161322, 3854.5181852268465, 3970.68234541236, 4008.5590585035625, 89721.0, 89839.0, 90580.64129038069, 91147.5442622796, 91389.54305228431, 77436.99999999888, 77590.22409775943, 78146.6092669533, 78241.60879195567, 79351.60324198342, 68827.65586172037, 69519.30480695229, 69803.0, 70719.0, 70967.2903270971, 6651.0023496475915, 7250.839865621475, 7652.617369131633, 7684.731034413769, 7864.1742617026675, 44015.55984440115, 48069.27896081492, 48277.5172248273, 48587.32769697701, 48718.26922596093, 62139.37860621336, 64288.0, 65735.35661608307, 66247.33752662412, 66261.66869165623, 10362.0, 10381.740456488647, 10567.207459440726, 10569.577216911544, 10571.04860562553, 10627.574897004033, 10758.193135515025, 10771.461426928465, 10879.955437739747, 10940.726481838017, 9537.380070295409, 9799.902000979899, 9950.154236889946, 10137.493125343852, 10393.012663796928, 8581.769597383673, 10593.89406105929, 11294.830577541508, 11707.414629268673, 12093.984455725787, 10785.946070269913, 11044.834327484934, 11297.322160670386, 11390.829137563109, 11523.8271425927, 9267.258619310574, 9378.906210937803, 9524.666636667684, 9617.0, 9677.322587418814, 8052.718154864551, 9420.528973551158, 9436.103570160823, 9503.477308747808, 9543.141117299476], "value": 6758.10752810627, "unit": "score"}], "name": "dromaeo_css", "value": 7465.200162432434, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 118.0, 112.0, 112.0, 112.0, 114.0, 113.0, 113.0, 134.0], "value": 115.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 120.0, 140.0, 169.0, 149.0, 172.0, 139.0, 119.0, 171.0, 116.0], "value": 145.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 187.0, 183.0, 179.0, 184.0, 186.0, 184.0, 187.0, 185.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 74.0, 72.0, 150.0, 56.0, 56.0, 59.0, 62.0, 134.0, 55.0], "value": 78.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 94.0, 145.0, 78.0, 80.0, 87.0, 124.0, 79.0, 80.0, 127.0], "value": 99.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 87.0, 85.0, 88.0, 85.0, 88.0, 85.0, 87.0, 85.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 93.0, 94.0, 93.0, 94.0, 94.0, 94.0, 93.0, 93.0], "value": 93.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 132.0, 132.0, 86.0, 86.0, 83.0, 83.0, 86.0, 87.0, 71.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 65.0, 64.0, 64.0, 63.0, 63.0, 65.0, 85.0, 59.0, 58.0], "value": 64.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 72.0, 74.0, 76.0, 73.0, 69.0, 69.0, 68.0, 69.0], "value": 69.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 80.0, 81.0, 80.0, 80.0, 79.0, 81.0, 83.0, 85.0], "value": 81.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 137.0, 143.0, 140.0, 127.0, 126.0, 126.0, 129.0, 153.0, 138.0], "value": 135.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 160.0, 157.0, 167.0, 166.0, 170.0, 165.0, 161.0, 160.0, 166.0], "value": 163.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 67.0, 68.0, 69.0, 69.0, 93.0, 92.0, 66.0], "value": 72.6, "unit": "score"}], "name": "kraken", "value": 1484.7999999999997, "alertThreshold": 2.0}]} 10:00:29 INFO - Return code: 0 10:00:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 10:00:29 ERROR - # TBPL SUCCESS # 10:00:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:00:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:00:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:00:29 INFO - 2016-05-30 10:00:29.892 screenresolution[1691:10339] starting screenresolution argv=screenresolution get 10:00:29 INFO - 2016-05-30 10:00:29.895 screenresolution[1691:10339] Display 0: 1600x1200x32@60 10:00:29 INFO - 2016-05-30 10:00:29.901 screenresolution[1692:10342] starting screenresolution argv=screenresolution list 10:00:29 INFO - Available Modes on Display 0 10:00:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:00:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:00:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:00:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:00:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:00:30 INFO - Intel Iris: 10:00:30 INFO - Chipset Model: Intel Iris 10:00:30 INFO - Type: GPU 10:00:30 INFO - Bus: Built-In 10:00:30 INFO - VRAM (Dynamic, Max): 1536 MB 10:00:30 INFO - Vendor: Intel (0x8086) 10:00:30 INFO - Device ID: 0x0a2e 10:00:30 INFO - Revision ID: 0x0009 10:00:30 INFO - Displays: 10:00:30 INFO - Display: 10:00:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:00:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:00:30 INFO - Display Serial Number: 1600x1200 60 10:00:30 INFO - Main Display: Yes 10:00:30 INFO - Mirror: Off 10:00:30 INFO - Online: Yes 10:00:30 INFO - Rotation: Supported 10:00:30 INFO - Return code: 0 10:00:30 INFO - Running post-action listener: _resource_record_post_action 10:00:30 INFO - Running post-run listener: _resource_record_post_run 10:00:31 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 36918784; Write bytes: 653373952; Read time: 244; Write time: 1874 10:00:31 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 360092160; Write bytes: 357641728; Read time: 27850; Write time: 773 10:00:31 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 29740544; Write bytes: 294884352; Read time: 181; Write time: 1099 10:00:31 INFO - Running post-run listener: _upload_blobber_files 10:00:31 INFO - Blob upload gear active. 10:00:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:00:31 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 10:00:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:00:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:00:31 INFO - (blobuploader) - INFO - Open directory for files ... 10:00:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:00:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:00:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:00:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:00:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:00:32 INFO - (blobuploader) - INFO - Done attempting. 10:00:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:00:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:00:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:00:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:00:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:00:33 INFO - (blobuploader) - INFO - Done attempting. 10:00:33 INFO - (blobuploader) - INFO - Iteration through files over. 10:00:33 INFO - Return code: 0 10:00:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:00:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:00:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c08fe1ef68cfabfa6d83e43bb68f6f25f8e889a19443475c0cc9d367daa5f264961a7b78bd3afea0623057b5f67296c57091b32297e8772dfa0561fc5ed18660", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/14ed12b27dc3651a5b6ef1033750f5ae395e55d111d3b90ce55bee5247800f55bee4f39d7ac5f4728f4d1fa798b55f576dd89aa7701aeb54957e6b6fe3faa245"} 10:00:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:00:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:00:33 INFO - Contents: 10:00:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c08fe1ef68cfabfa6d83e43bb68f6f25f8e889a19443475c0cc9d367daa5f264961a7b78bd3afea0623057b5f67296c57091b32297e8772dfa0561fc5ed18660", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/14ed12b27dc3651a5b6ef1033750f5ae395e55d111d3b90ce55bee5247800f55bee4f39d7ac5f4728f4d1fa798b55f576dd89aa7701aeb54957e6b6fe3faa245"} 10:00:33 INFO - Running post-run listener: copy_logs_to_upload_dir 10:00:33 INFO - Copying logs to upload dir... 10:00:33 INFO - mkdir: /builds/slave/test/build/upload/logs 10:00:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.020482 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-05-30 10:00:33.765810) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 3 secs) (at 2016-05-30 10:00:33.771143) ========= 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.fRsyVoTnyW/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.pdWfEsvob5/Listeners TMPDIR=/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c08fe1ef68cfabfa6d83e43bb68f6f25f8e889a19443475c0cc9d367daa5f264961a7b78bd3afea0623057b5f67296c57091b32297e8772dfa0561fc5ed18660", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/14ed12b27dc3651a5b6ef1033750f5ae395e55d111d3b90ce55bee5247800f55bee4f39d7ac5f4728f4d1fa798b55f576dd89aa7701aeb54957e6b6fe3faa245"} build_url:https://queue.taskcluster.net/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011276 build_url: 'https://queue.taskcluster.net/v1/task/GYZVv9DhTj-_QbWNUdf2jA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c08fe1ef68cfabfa6d83e43bb68f6f25f8e889a19443475c0cc9d367daa5f264961a7b78bd3afea0623057b5f67296c57091b32297e8772dfa0561fc5ed18660", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/14ed12b27dc3651a5b6ef1033750f5ae395e55d111d3b90ce55bee5247800f55bee4f39d7ac5f4728f4d1fa798b55f576dd89aa7701aeb54957e6b6fe3faa245"}' ========= master_lag: 3.28 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 3 secs) (at 2016-05-30 10:00:37.061139) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 10:00:37.061569) ========= 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.fRsyVoTnyW/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.pdWfEsvob5/Listeners TMPDIR=/var/folders/7v/_qk3dpcd4577lpvnh1ww7k2w00000w/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.005234 ========= master_lag: 0.32 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 10:00:37.390884) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 10:00:37.391300) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 10:00:37.639001) ========= ========= Total master_lag: 3.82 =========