builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0146 starttime: 1463392001.97 results: success (0) buildid: 20160516004009 builduid: fd5caa0c704f4344a63b32d981388128 revision: f00f9e754ce71fc5efa3e4334afea5cf6ad6c215 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 02:46:41.968922) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 02:46:41.969306) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 02:46:41.969580) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 02:46:41.999647) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 02:46:41.999907) ========= 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.KnW6RfeADG/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.LUVOeJMpmB/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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-16 02:46:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.44M=0.001s 2016-05-16 02:46:42 (9.44 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.502300 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 02:46:42.519591) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 02:46:42.519867) ========= 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.KnW6RfeADG/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.LUVOeJMpmB/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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.026603 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 02:46:42.567591) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 02:46:42.567928) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f00f9e754ce71fc5efa3e4334afea5cf6ad6c215 --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 f00f9e754ce71fc5efa3e4334afea5cf6ad6c215 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KnW6RfeADG/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.LUVOeJMpmB/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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-16 02:46:42,638 truncating revision to first 12 chars 2016-05-16 02:46:42,638 Setting DEBUG logging. 2016-05-16 02:46:42,638 attempt 1/10 2016-05-16 02:46:42,639 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/f00f9e754ce7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-16 02:46:43,165 unpacking tar archive at: mozilla-aurora-f00f9e754ce7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.780960 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 02:46:43.365436) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 02:46:43.365742) ========= script_repo_revision: f00f9e754ce71fc5efa3e4334afea5cf6ad6c215 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 02:46:43.366147) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 02:46:43.366412) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 02:46:43.380922) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 3 secs) (at 2016-05-16 02:46:43.381637) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KnW6RfeADG/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.LUVOeJMpmB/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 02:46:43 INFO - MultiFileLogger online at 20160516 02:46:43 in /builds/slave/test 02:46:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:46:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:46:43 INFO - {'append_to_log': False, 02:46:43 INFO - 'base_work_dir': '/builds/slave/test', 02:46:43 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:46:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:46:43 INFO - 'branch': 'Mozilla-Aurora', 02:46:43 INFO - 'buildbot_json_path': 'buildprops.json', 02:46:43 INFO - 'config_files': ('talos/mac_config.py',), 02:46:43 INFO - 'default_actions': ('clobber', 02:46:43 INFO - 'read-buildbot-config', 02:46:43 INFO - 'download-and-extract', 02:46:43 INFO - 'populate-webroot', 02:46:43 INFO - 'create-virtualenv', 02:46:43 INFO - 'install', 02:46:43 INFO - 'run-tests'), 02:46:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:46:43 INFO - 'download_minidump_stackwalk': True, 02:46:43 INFO - 'download_symbols': 'ondemand', 02:46:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:46:43 INFO - 'tooltool.py': '/tools/tooltool.py', 02:46:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:46:43 INFO - '/tools/misc-python/virtualenv.py')}, 02:46:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:46:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:46:43 INFO - 'installer_path': 'installer.exe', 02:46:43 INFO - 'log_level': 'info', 02:46:43 INFO - 'log_name': 'talos', 02:46:43 INFO - 'log_to_console': True, 02:46:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:46:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:46:43 INFO - 'opt_config_files': (), 02:46:43 INFO - 'pip_index': False, 02:46:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:46:43 INFO - 'cmd': ('bash', 02:46:43 INFO - '-c', 02:46:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:46:43 INFO - 'enabled': True, 02:46:43 INFO - 'halt_on_failure': False, 02:46:43 INFO - 'name': 'check_screen_resolution'},), 02:46:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:46:43 INFO - 'cmd': ('bash', 02:46:43 INFO - '-c', 02:46:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:46:43 INFO - 'enabled': True, 02:46:43 INFO - 'halt_on_failure': False, 02:46:43 INFO - 'name': 'check_screen_resolution'},), 02:46:43 INFO - 'run_cmd_checks_enabled': True, 02:46:43 INFO - 'sps_profile': False, 02:46:43 INFO - 'sps_profile_interval': 0, 02:46:43 INFO - 'suite': 'dromaeojs-e10s', 02:46:43 INFO - 'system_bits': '32', 02:46:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:46:43 INFO - 'title': 't-yosemite-r7-0146', 02:46:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:46:43 INFO - 'use_talos_json': True, 02:46:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:46:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:46:43 INFO - 'work_dir': 'build'} 02:46:43 INFO - ##### 02:46:43 INFO - ##### Running clobber step. 02:46:43 INFO - ##### 02:46:43 INFO - Running pre-action listener: _resource_record_pre_action 02:46:43 INFO - Running main action method: clobber 02:46:43 INFO - rmtree: /builds/slave/test/build 02:46:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:46:45 INFO - Running post-action listener: _resource_record_post_action 02:46:45 INFO - ##### 02:46:45 INFO - ##### Running read-buildbot-config step. 02:46:45 INFO - ##### 02:46:45 INFO - Running pre-action listener: _resource_record_pre_action 02:46:45 INFO - Running main action method: read_buildbot_config 02:46:45 INFO - Using buildbot properties: 02:46:45 INFO - { 02:46:45 INFO - "project": "", 02:46:45 INFO - "product": "firefox", 02:46:45 INFO - "script_repo_revision": "production", 02:46:45 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:46:45 INFO - "repository": "", 02:46:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:46:45 INFO - "buildid": "20160516004009", 02:46:45 INFO - "stage_platform": "macosx64", 02:46:45 INFO - "basedir": "/builds/slave/test", 02:46:45 INFO - "nightly_build": "True", 02:46:45 INFO - "buildnumber": 6, 02:46:45 INFO - "slavename": "t-yosemite-r7-0146", 02:46:45 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 02:46:45 INFO - "platform": "yosemite_r7", 02:46:45 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:46:45 INFO - "branch": "mozilla-aurora", 02:46:45 INFO - "repo_path": "releases/mozilla-aurora", 02:46:45 INFO - "revision": "f00f9e754ce71fc5efa3e4334afea5cf6ad6c215", 02:46:45 INFO - "pgo_build": "False", 02:46:45 INFO - "builduid": "fd5caa0c704f4344a63b32d981388128", 02:46:45 INFO - "slavebuilddir": "test" 02:46:45 INFO - } 02:46:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 02:46:45 INFO - Running post-action listener: _resource_record_post_action 02:46:45 INFO - ##### 02:46:45 INFO - ##### Running download-and-extract step. 02:46:45 INFO - ##### 02:46:45 INFO - Running pre-action listener: _resource_record_pre_action 02:46:45 INFO - Running main action method: download_and_extract 02:46:45 INFO - mkdir: /builds/slave/test/build/tests 02:46:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:46:45 INFO - https://queue.taskcluster.net/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:46:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:46:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:46:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:46:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 02:47:15 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json: timed out 02:47:15 INFO - retry: attempt #1 caught exception: timed out 02:47:15 INFO - retry: Failed, sleeping 30 seconds before retrying 02:47:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/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 #2 02:47:46 INFO - Downloaded 1199 bytes. 02:47:46 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:47:46 INFO - Using the following test package requirements: 02:47:46 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:47:46 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:47:46 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 02:47:46 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:47:46 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 02:47:46 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:47:46 INFO - u'jsshell-mac.zip'], 02:47:46 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:47:46 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 02:47:46 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:47:46 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:47:46 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 02:47:46 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:47:46 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 02:47:46 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:47:46 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 02:47:46 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:47:46 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 02:47:46 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:47:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:46 INFO - https://queue.taskcluster.net/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:47:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:47:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:47:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:47:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:47:48 INFO - Downloaded 18102284 bytes. 02:47:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:47:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:47:51 INFO - Return code: 0 02:47:51 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:47: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')]} 02:47:51 INFO - https://queue.taskcluster.net/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:47:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:47:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:47:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:47:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:47:52 INFO - Downloaded 18102284 bytes. 02:47:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:47:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:47:56 INFO - Return code: 0 02:47: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')]} 02:47:56 INFO - https://queue.taskcluster.net/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:47:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:47:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:47:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:47:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:47:57 INFO - Downloaded 10840145 bytes. 02:47:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:47:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 02:47:57 INFO - Return code: 0 02:47:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:57 INFO - https://queue.taskcluster.net/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 02:47:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:47:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:47:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 02:47:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 02:48:00 INFO - Downloaded 114148849 bytes. 02:48:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:48:00 INFO - mkdir: /builds/slave/test/properties 02:48:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:48:00 INFO - Writing to file /builds/slave/test/properties/build_url 02:48:00 INFO - Contents: 02:48:00 INFO - build_url:https://queue.taskcluster.net/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:48:01 INFO - Running post-action listener: _resource_record_post_action 02:48:01 INFO - Running post-action listener: set_extra_try_arguments 02:48:01 INFO - ##### 02:48:01 INFO - ##### Running populate-webroot step. 02:48:01 INFO - ##### 02:48:01 INFO - Running pre-action listener: _resource_record_pre_action 02:48:01 INFO - Running main action method: populate_webroot 02:48:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:48:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:48:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:48:01 INFO - '--tppagecycles', 02:48:01 INFO - '7'], 02:48:01 INFO - 'tests': ['tsvgm']}}, 02:48:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:48:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:48:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:48:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:48:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:48:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:48:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:48:01 INFO - 'talos_options': ['--disable-e10s'], 02:48:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:48:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:48:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:48:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:48:01 INFO - 'talos_options': ['--disable-e10s'], 02:48:01 INFO - 'tests': ['damp', 'tps']}, 02:48:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:48:01 INFO - 'tests': ['damp', 'tps']}, 02:48:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:48:01 INFO - 'tests': ['dromaeo_dom']}, 02:48:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:48:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:48:01 INFO - 'tests': ['a11yr', 02:48:01 INFO - 'ts_paint', 02:48:01 INFO - 'tpaint', 02:48:01 INFO - 'sessionrestore', 02:48:01 INFO - 'sessionrestore_no_auto_restore', 02:48:01 INFO - 'tabpaint']}, 02:48:01 INFO - 'other-e10s': {'tests': ['a11yr', 02:48:01 INFO - 'ts_paint', 02:48:01 INFO - 'tpaint', 02:48:01 INFO - 'sessionrestore', 02:48:01 INFO - 'sessionrestore_no_auto_restore', 02:48:01 INFO - 'tabpaint']}, 02:48:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:48:01 INFO - 'ts_paint', 02:48:01 INFO - 'tpaint', 02:48:01 INFO - 'sessionrestore', 02:48:01 INFO - 'sessionrestore_no_auto_restore', 02:48:01 INFO - 'tabpaint']}, 02:48:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:48:01 INFO - 'ts_paint', 02:48:01 INFO - 'tpaint', 02:48:01 INFO - 'sessionrestore', 02:48:01 INFO - 'sessionrestore_no_auto_restore', 02:48:01 INFO - 'tabpaint']}, 02:48:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:48:01 INFO - 'tests': ['a11yr', 02:48:01 INFO - 'ts_paint', 02:48:01 INFO - 'tpaint', 02:48:01 INFO - 'sessionrestore', 02:48:01 INFO - 'sessionrestore_no_auto_restore', 02:48:01 INFO - 'tabpaint']}, 02:48:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:48:01 INFO - 'tests': ['a11yr', 02:48:01 INFO - 'ts_paint', 02:48:01 INFO - 'tpaint', 02:48:01 INFO - 'sessionrestore', 02:48:01 INFO - 'sessionrestore_no_auto_restore', 02:48:01 INFO - 'tabpaint']}, 02:48:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:48:01 INFO - 'tests': ['tsvgx', 02:48:01 INFO - 'tsvgr_opacity', 02:48:01 INFO - 'tart', 02:48:01 INFO - 'tscrollx', 02:48:01 INFO - 'cart']}, 02:48:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:48:01 INFO - 'tsvgr_opacity', 02:48:01 INFO - 'tart', 02:48:01 INFO - 'tscrollx', 02:48:01 INFO - 'cart']}, 02:48:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:48:01 INFO - 'talos_options': ['--disable-e10s'], 02:48:01 INFO - 'tests': ['tp5o']}, 02:48:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:48:01 INFO - 'tests': ['tp5o']}, 02:48:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:48:01 INFO - 'talos_options': ['--disable-e10s', 02:48:01 INFO - '--xperf_path', 02:48:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:48:01 INFO - 'tests': ['tp5n']}, 02:48:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:48:01 INFO - 'talos_options': ['--xperf_path', 02:48:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:48:01 INFO - 'tests': ['tp5n']}}, 02:48:01 INFO - 'talos.zip': {'path': '', 02:48:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:48:01 INFO - Running post-action listener: _resource_record_post_action 02:48:01 INFO - ##### 02:48:01 INFO - ##### Running create-virtualenv step. 02:48:01 INFO - ##### 02:48:01 INFO - Running pre-action listener: _resource_record_pre_action 02:48:01 INFO - Running main action method: create_virtualenv 02:48:01 INFO - Creating virtualenv /builds/slave/test/build/venv 02:48:01 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:48:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:48:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:48:01 INFO - Using real prefix '/tools/python27' 02:48:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:48:01 INFO - Installing distribute.............................................................................................................................................................................................done. 02:48:04 INFO - Installing pip.................done. 02:48:04 INFO - Return code: 0 02:48:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:48:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:48:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:48:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:04 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 0x10f937a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f947420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff11049c3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d6e00>, '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 0x10f63fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d1b20>, '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.KnW6RfeADG/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.LUVOeJMpmB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:48:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:48:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:48:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KnW6RfeADG/Render', 02:48:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:04 INFO - 'HOME': '/Users/cltbld', 02:48:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:04 INFO - 'LOGNAME': 'cltbld', 02:48:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:04 INFO - 'MOZ_NO_REMOTE': '1', 02:48:04 INFO - 'NO_EM_RESTART': '1', 02:48:04 INFO - 'PAGER': '/bin/cat', 02:48:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:04 INFO - 'PWD': '/builds/slave/test', 02:48:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:04 INFO - 'SHELL': '/bin/bash', 02:48:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LUVOeJMpmB/Listeners', 02:48:04 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 02:48:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:04 INFO - 'USER': 'cltbld', 02:48:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:04 INFO - 'XPC_FLAGS': '0x0', 02:48:04 INFO - 'XPC_SERVICE_NAME': '0', 02:48:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:05 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:48:05 INFO - Cleaning up... 02:48:05 INFO - Return code: 0 02:48:05 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:48:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:48:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:05 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 0x10f937a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f947420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff11049c3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d6e00>, '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 0x10f63fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d1b20>, '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.KnW6RfeADG/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.LUVOeJMpmB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:48:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 02:48:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 02:48:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KnW6RfeADG/Render', 02:48:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:05 INFO - 'HOME': '/Users/cltbld', 02:48:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:05 INFO - 'LOGNAME': 'cltbld', 02:48:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:05 INFO - 'MOZ_NO_REMOTE': '1', 02:48:05 INFO - 'NO_EM_RESTART': '1', 02:48:05 INFO - 'PAGER': '/bin/cat', 02:48:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:05 INFO - 'PWD': '/builds/slave/test', 02:48:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:05 INFO - 'SHELL': '/bin/bash', 02:48:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LUVOeJMpmB/Listeners', 02:48:05 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 02:48:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:05 INFO - 'USER': 'cltbld', 02:48:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:05 INFO - 'XPC_FLAGS': '0x0', 02:48:05 INFO - 'XPC_SERVICE_NAME': '0', 02:48:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:05 INFO - Downloading/unpacking psutil>=0.7.1 02:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:48:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:48:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:48:08 INFO - Installing collected packages: psutil 02:48:08 INFO - Running setup.py install for psutil 02:48:08 INFO - building 'psutil._psutil_osx' extension 02:48:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:48:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:48:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:48:09 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:48:09 INFO - building 'psutil._psutil_posix' extension 02:48:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:48:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:48:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:48:09 INFO - ^ 02:48:09 INFO - 1 warning generated. 02:48:09 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:48:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:48:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:48:09 INFO - Successfully installed psutil 02:48:09 INFO - Cleaning up... 02:48:09 INFO - Return code: 0 02:48:09 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:48:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:48:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f937a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f947420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff11049c3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d6e00>, '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 0x10f63fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d1b20>, '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.KnW6RfeADG/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.LUVOeJMpmB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:48:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 02:48:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 02:48:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KnW6RfeADG/Render', 02:48:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:09 INFO - 'HOME': '/Users/cltbld', 02:48:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:09 INFO - 'LOGNAME': 'cltbld', 02:48:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:09 INFO - 'MOZ_NO_REMOTE': '1', 02:48:09 INFO - 'NO_EM_RESTART': '1', 02:48:09 INFO - 'PAGER': '/bin/cat', 02:48:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:09 INFO - 'PWD': '/builds/slave/test', 02:48:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:09 INFO - 'SHELL': '/bin/bash', 02:48:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LUVOeJMpmB/Listeners', 02:48:09 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 02:48:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:09 INFO - 'USER': 'cltbld', 02:48:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:09 INFO - 'XPC_FLAGS': '0x0', 02:48:09 INFO - 'XPC_SERVICE_NAME': '0', 02:48:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:09 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:12 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:48:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:48:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:48:12 INFO - Installing collected packages: mozsystemmonitor 02:48:12 INFO - Running setup.py install for mozsystemmonitor 02:48:12 INFO - Successfully installed mozsystemmonitor 02:48:12 INFO - Cleaning up... 02:48:12 INFO - Return code: 0 02:48:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:48:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:48:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:12 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 0x10f937a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f947420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff11049c3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d6e00>, '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 0x10f63fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d1b20>, '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.KnW6RfeADG/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.LUVOeJMpmB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:48:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:48:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:48:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KnW6RfeADG/Render', 02:48:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:12 INFO - 'HOME': '/Users/cltbld', 02:48:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:12 INFO - 'LOGNAME': 'cltbld', 02:48:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:12 INFO - 'MOZ_NO_REMOTE': '1', 02:48:12 INFO - 'NO_EM_RESTART': '1', 02:48:12 INFO - 'PAGER': '/bin/cat', 02:48:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:12 INFO - 'PWD': '/builds/slave/test', 02:48:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:12 INFO - 'SHELL': '/bin/bash', 02:48:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LUVOeJMpmB/Listeners', 02:48:12 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 02:48:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:12 INFO - 'USER': 'cltbld', 02:48:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:12 INFO - 'XPC_FLAGS': '0x0', 02:48:12 INFO - 'XPC_SERVICE_NAME': '0', 02:48:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:12 INFO - Downloading/unpacking blobuploader==1.2.4 02:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:15 INFO - Downloading blobuploader-1.2.4.tar.gz 02:48:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:48:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:48:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:48:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:48: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 02:48: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 02:48: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 02:48: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 02:48: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 02:48: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 02:48:16 INFO - Downloading docopt-0.6.1.tar.gz 02:48:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:48:16 INFO - Installing collected packages: blobuploader, requests, docopt 02:48:16 INFO - Running setup.py install for blobuploader 02:48:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:48:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:48:16 INFO - Running setup.py install for requests 02:48:17 INFO - Running setup.py install for docopt 02:48:17 INFO - Successfully installed blobuploader requests docopt 02:48:17 INFO - Cleaning up... 02:48:17 INFO - Return code: 0 02:48:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:48: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')]} 02:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48: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')]} 02:48:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:17 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 0x10f937a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f947420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff11049c3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d6e00>, '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 0x10f63fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d1b20>, '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.KnW6RfeADG/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.LUVOeJMpmB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:48:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:48:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:48:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KnW6RfeADG/Render', 02:48:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:17 INFO - 'HOME': '/Users/cltbld', 02:48:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:17 INFO - 'LOGNAME': 'cltbld', 02:48:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:17 INFO - 'MOZ_NO_REMOTE': '1', 02:48:17 INFO - 'NO_EM_RESTART': '1', 02:48:17 INFO - 'PAGER': '/bin/cat', 02:48:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:17 INFO - 'PWD': '/builds/slave/test', 02:48:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:17 INFO - 'SHELL': '/bin/bash', 02:48:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LUVOeJMpmB/Listeners', 02:48:17 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 02:48:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:17 INFO - 'USER': 'cltbld', 02:48:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:17 INFO - 'XPC_FLAGS': '0x0', 02:48:17 INFO - 'XPC_SERVICE_NAME': '0', 02:48:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:48:17 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-lmf0Bv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:48:17 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-yU5WgT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:48:17 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-58aSjW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:48:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:48:17 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-qR8e8f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:48:18 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-0xM2v0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:48:18 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-bQojM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:48:18 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-GszI93-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:48:18 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-tCsXwM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:48:18 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-Ux58O0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:48:18 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-I_YXVv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:48:18 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-AZ_LqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:48:18 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-XSmqaC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:48:18 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-_j0I_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:48:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:48:18 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-s8VE2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:48:19 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-A4ib84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:48:19 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-fMHq0k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:48:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:48:19 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-Ggz9uW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:48:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:48:19 INFO - Running setup.py install for manifestparser 02:48:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:48:19 INFO - Running setup.py install for mozcrash 02:48:19 INFO - Running setup.py install for mozdebug 02:48:19 INFO - Running setup.py install for mozdevice 02:48:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:48:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:48:19 INFO - Running setup.py install for mozfile 02:48:20 INFO - Running setup.py install for mozhttpd 02:48:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:48:20 INFO - Running setup.py install for mozinfo 02:48:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:48:20 INFO - Running setup.py install for mozInstall 02:48:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:48:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:48:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:48:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:48:20 INFO - Running setup.py install for mozleak 02:48:20 INFO - Running setup.py install for mozlog 02:48:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:48:20 INFO - Running setup.py install for moznetwork 02:48:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:48:20 INFO - Running setup.py install for mozprocess 02:48:21 INFO - Running setup.py install for mozprofile 02:48:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:48:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:48:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:48:21 INFO - Running setup.py install for mozrunner 02:48:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:48:21 INFO - Running setup.py install for mozscreenshot 02:48:21 INFO - Running setup.py install for moztest 02:48:21 INFO - Running setup.py install for mozversion 02:48:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:48:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:48:21 INFO - Cleaning up... 02:48:21 INFO - Return code: 0 02:48:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:48:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:48:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:21 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 0x10f937a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f947420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff11049c3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d6e00>, '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 0x10f63fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d1b20>, '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.KnW6RfeADG/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.LUVOeJMpmB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:48:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:48:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:48:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KnW6RfeADG/Render', 02:48:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:21 INFO - 'HOME': '/Users/cltbld', 02:48:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:21 INFO - 'LOGNAME': 'cltbld', 02:48:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:21 INFO - 'MOZ_NO_REMOTE': '1', 02:48:21 INFO - 'NO_EM_RESTART': '1', 02:48:21 INFO - 'PAGER': '/bin/cat', 02:48:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:21 INFO - 'PWD': '/builds/slave/test', 02:48:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:21 INFO - 'SHELL': '/bin/bash', 02:48:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LUVOeJMpmB/Listeners', 02:48:21 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 02:48:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:21 INFO - 'USER': 'cltbld', 02:48:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:21 INFO - 'XPC_FLAGS': '0x0', 02:48:21 INFO - 'XPC_SERVICE_NAME': '0', 02:48:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:48:22 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-a5jEes-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:48:22 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:48:22 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-TiJWgm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:48:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:48:22 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-Z51sPV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:48:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:48:22 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-Amcksr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:48:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:48:22 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-mnzOgV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:48:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:48:22 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-iLYXh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:48:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:48:22 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-iY86YK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:48:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:48:22 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-e1hb2Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:48:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:48:23 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-fAQnXa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:48:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:48:23 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-lN5X7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:48:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:48:23 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-lF21rP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:48:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:48:23 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-HwITTB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:48:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:48:23 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-bYAwOh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:48:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:48:23 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-Jpz7sa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:48:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:48:23 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-8fjxrf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:48:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:48:23 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-gzGAQW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:48:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:48:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:48:23 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-T6GqY5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:48:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:48:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:48:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:48:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:48:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:48:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:48:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:26 INFO - Downloading blessings-1.6.tar.gz 02:48:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:48:26 INFO - Installing collected packages: blessings 02:48:26 INFO - Running setup.py install for blessings 02:48:26 INFO - Successfully installed blessings 02:48:26 INFO - Cleaning up... 02:48:26 INFO - Return code: 0 02:48:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:48:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:48:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:48:27 INFO - Reading from file tmpfile_stdout 02:48:27 INFO - Current package versions: 02:48:27 INFO - blessings == 1.6 02:48:27 INFO - blobuploader == 1.2.4 02:48:27 INFO - docopt == 0.6.1 02:48:27 INFO - manifestparser == 1.1 02:48:27 INFO - mozInstall == 1.12 02:48:27 INFO - mozcrash == 0.17 02:48:27 INFO - mozdebug == 0.1 02:48:27 INFO - mozdevice == 0.48 02:48:27 INFO - mozfile == 1.2 02:48:27 INFO - mozhttpd == 0.7 02:48:27 INFO - mozinfo == 0.9 02:48:27 INFO - mozleak == 0.1 02:48:27 INFO - mozlog == 3.1 02:48:27 INFO - moznetwork == 0.27 02:48:27 INFO - mozprocess == 0.22 02:48:27 INFO - mozprofile == 0.28 02:48:27 INFO - mozrunner == 6.11 02:48:27 INFO - mozscreenshot == 0.1 02:48:27 INFO - mozsystemmonitor == 0.0 02:48:27 INFO - moztest == 0.7 02:48:27 INFO - mozversion == 1.4 02:48:27 INFO - psutil == 3.1.1 02:48:27 INFO - requests == 1.2.3 02:48:27 INFO - wsgiref == 0.1.2 02:48:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:48:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:48:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:48:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:27 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 0x10f937a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f947420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff11049c3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d6e00>, '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 0x10f63fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d1b20>, '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.KnW6RfeADG/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.LUVOeJMpmB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:48:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:48:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:48:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KnW6RfeADG/Render', 02:48:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:27 INFO - 'HOME': '/Users/cltbld', 02:48:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:27 INFO - 'LOGNAME': 'cltbld', 02:48:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:27 INFO - 'MOZ_NO_REMOTE': '1', 02:48:27 INFO - 'NO_EM_RESTART': '1', 02:48:27 INFO - 'PAGER': '/bin/cat', 02:48:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:27 INFO - 'PWD': '/builds/slave/test', 02:48:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:27 INFO - 'SHELL': '/bin/bash', 02:48:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LUVOeJMpmB/Listeners', 02:48:27 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 02:48:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:27 INFO - 'USER': 'cltbld', 02:48:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:27 INFO - 'XPC_FLAGS': '0x0', 02:48:27 INFO - 'XPC_SERVICE_NAME': '0', 02:48:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:48:27 INFO - Cleaning up... 02:48:27 INFO - Return code: 0 02:48:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:48:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:48:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:48:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f937a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f947420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff11049c3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d6e00>, '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 0x10f63fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d1b20>, '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.KnW6RfeADG/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.LUVOeJMpmB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:48:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:48:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:48:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KnW6RfeADG/Render', 02:48:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:27 INFO - 'HOME': '/Users/cltbld', 02:48:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:27 INFO - 'LOGNAME': 'cltbld', 02:48:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:27 INFO - 'MOZ_NO_REMOTE': '1', 02:48:27 INFO - 'NO_EM_RESTART': '1', 02:48:27 INFO - 'PAGER': '/bin/cat', 02:48:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:27 INFO - 'PWD': '/builds/slave/test', 02:48:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:27 INFO - 'SHELL': '/bin/bash', 02:48:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LUVOeJMpmB/Listeners', 02:48:27 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 02:48:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:27 INFO - 'USER': 'cltbld', 02:48:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:27 INFO - 'XPC_FLAGS': '0x0', 02:48:27 INFO - 'XPC_SERVICE_NAME': '0', 02:48:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:27 INFO - Downloading/unpacking jsonschema 02:48:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:48:30 INFO - Downloading/unpacking functools32 (from jsonschema) 02:48:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:30 INFO - Downloading functools32-3.2.3-2.tar.gz 02:48:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:48:30 INFO - warning: no files found matching '*.txt' 02:48:30 INFO - no previously-included directories found matching 'build' 02:48:30 INFO - no previously-included directories found matching 'dist' 02:48:30 INFO - no previously-included directories found matching '.git*' 02:48:30 INFO - Installing collected packages: jsonschema, functools32 02:48:30 INFO - Running setup.py install for functools32 02:48:30 INFO - warning: no files found matching '*.txt' 02:48:30 INFO - no previously-included directories found matching 'build' 02:48:30 INFO - no previously-included directories found matching 'dist' 02:48:30 INFO - no previously-included directories found matching '.git*' 02:48:30 INFO - Successfully installed jsonschema functools32 02:48:30 INFO - Cleaning up... 02:48:30 INFO - Return code: 0 02:48:30 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:48:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:48:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:48:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:30 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 0x10f937a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f947420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff11049c3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d6e00>, '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 0x10f63fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d1b20>, '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.KnW6RfeADG/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.LUVOeJMpmB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:48:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 02:48:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 02:48:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KnW6RfeADG/Render', 02:48:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:30 INFO - 'HOME': '/Users/cltbld', 02:48:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:30 INFO - 'LOGNAME': 'cltbld', 02:48:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:30 INFO - 'MOZ_NO_REMOTE': '1', 02:48:30 INFO - 'NO_EM_RESTART': '1', 02:48:30 INFO - 'PAGER': '/bin/cat', 02:48:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:30 INFO - 'PWD': '/builds/slave/test', 02:48:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:30 INFO - 'SHELL': '/bin/bash', 02:48:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LUVOeJMpmB/Listeners', 02:48:30 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 02:48:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:30 INFO - 'USER': 'cltbld', 02:48:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:30 INFO - 'XPC_FLAGS': '0x0', 02:48:30 INFO - 'XPC_SERVICE_NAME': '0', 02:48:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:31 INFO - Downloading/unpacking flake8 02:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:33 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:48:33 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:48:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:34 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:48:34 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:34 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:48:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:48:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:48:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:48:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:48:34 INFO - no previously-included directories found matching 'docs/_build' 02:48:34 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:48:35 INFO - Running setup.py install for pep8 02:48:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:48:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:48:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:48:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:48:35 INFO - no previously-included directories found matching 'docs/_build' 02:48:35 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:48:35 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:48:35 INFO - Cleaning up... 02:48:35 INFO - Return code: 0 02:48:35 INFO - Running post-action listener: _resource_record_post_action 02:48:35 INFO - Running post-action listener: _start_resource_monitoring 02:48:35 INFO - Starting resource monitoring. 02:48:35 INFO - ##### 02:48:35 INFO - ##### Running install step. 02:48:35 INFO - ##### 02:48:35 INFO - Running pre-action listener: _resource_record_pre_action 02:48:35 INFO - Running main action method: install 02:48:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:48:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:48:35 INFO - Reading from file tmpfile_stdout 02:48:35 INFO - Detecting whether we're running mozinstall >=1.0... 02:48:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:48:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:48:35 INFO - Reading from file tmpfile_stdout 02:48:35 INFO - Output received: 02:48:35 INFO - Usage: mozinstall [options] installer 02:48:35 INFO - Options: 02:48:35 INFO - -h, --help show this help message and exit 02:48:35 INFO - -d DEST, --destination=DEST 02:48:35 INFO - Directory to install application into. [default: 02:48:35 INFO - "/builds/slave/test"] 02:48:35 INFO - --app=APP Application being installed. [default: firefox] 02:48:35 INFO - mkdir: /builds/slave/test/build/application 02:48:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:48:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:49:07 INFO - Reading from file tmpfile_stdout 02:49:07 INFO - Output received: 02:49:07 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:49:07 INFO - Running post-action listener: _resource_record_post_action 02:49:07 INFO - ##### 02:49:07 INFO - ##### Running run-tests step. 02:49:07 INFO - ##### 02:49:07 INFO - Running pre-action listener: _resource_record_pre_action 02:49:07 INFO - Running main action method: run_tests 02:49:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:49:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:49:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:49:07 INFO - 2016-05-16 02:49:07.977 screenresolution[1649:8991] starting screenresolution argv=screenresolution get 02:49:07 INFO - 2016-05-16 02:49:07.980 screenresolution[1649:8991] Display 0: 1600x1200x32@60 02:49:07 INFO - 2016-05-16 02:49:07.986 screenresolution[1650:8993] starting screenresolution argv=screenresolution list 02:49:07 INFO - Available Modes on Display 0 02:49:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:49:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:49:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:49:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:49:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:49:08 INFO - Intel Iris: 02:49:08 INFO - Chipset Model: Intel Iris 02:49:08 INFO - Type: GPU 02:49:08 INFO - Bus: Built-In 02:49:08 INFO - VRAM (Dynamic, Max): 1536 MB 02:49:08 INFO - Vendor: Intel (0x8086) 02:49:08 INFO - Device ID: 0x0a2e 02:49:08 INFO - Revision ID: 0x0009 02:49:08 INFO - Displays: 02:49:08 INFO - Display: 02:49:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:49:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:49:08 INFO - Display Serial Number: 1600x1200 60 02:49:08 INFO - Main Display: Yes 02:49:08 INFO - Mirror: Off 02:49:08 INFO - Online: Yes 02:49:08 INFO - Rotation: Supported 02:49:08 INFO - Return code: 0 02:49:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:49:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:49:08 INFO - Python 2.7.3 02:49:08 INFO - Return code: 0 02:49:08 INFO - grabbing minidump binary from tooltool 02:49:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:49:08 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 0x10f7d6e00>, '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 0x10f63fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7d1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:49:08 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:49:08 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:49:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:49:08 INFO - Return code: 0 02:49:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:49:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:49:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:49:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:49:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:49:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:49:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:49:09 INFO - Return code: 0 02:49:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0146', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 02:49:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0146 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 02:49:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KnW6RfeADG/Render', 02:49:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:49:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:49:09 INFO - 'HOME': '/Users/cltbld', 02:49:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:49:09 INFO - 'LOGNAME': 'cltbld', 02:49:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:49:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:49:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:09 INFO - 'MOZ_NO_REMOTE': '1', 02:49:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:49:09 INFO - 'NO_EM_RESTART': '1', 02:49:09 INFO - 'PAGER': '/bin/cat', 02:49:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:49:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:09 INFO - 'PWD': '/builds/slave/test', 02:49:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:49:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:49:09 INFO - 'SHELL': '/bin/bash', 02:49:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LUVOeJMpmB/Listeners', 02:49:09 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 02:49:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:49:09 INFO - 'USER': 'cltbld', 02:49:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:49:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:49:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:09 INFO - 'XPC_FLAGS': '0x0', 02:49:09 INFO - 'XPC_SERVICE_NAME': '0', 02:49:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:49:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0146', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 02:49:09 INFO - mozversion application_buildid: 20160516004009 02:49:09 INFO - mozversion application_changeset: f00f9e754ce71fc5efa3e4334afea5cf6ad6c215 02:49:09 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:49:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:49:09 INFO - mozversion application_name: Firefox 02:49:09 INFO - mozversion application_remotingname: firefox-dev 02:49:09 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:49:09 INFO - mozversion application_vendor: Mozilla 02:49:09 INFO - mozversion application_version: 48.0a2 02:49:09 INFO - mozversion platform_buildid: 20160516004009 02:49:09 INFO - mozversion platform_changeset: f00f9e754ce71fc5efa3e4334afea5cf6ad6c215 02:49:09 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:49:09 INFO - mozversion platform_version: 48.0a2 02:49:09 INFO - using testdate: 1463392149 02:49:09 INFO - actual date: 1463392149 02:49:09 INFO - starting webserver on 'localhost:49276' 02:49:09 INFO - SUITE-START | Running 2 tests 02:49:09 INFO - TEST-START | dromaeo_css 02:49:09 INFO - operating with platform_type : mac_ 02:49:09 INFO - Initialising browser for dromaeo_css test... 02:49:09 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/tmpcM3_ZT/profile http://localhost:49276/getInfo.html) 02:49:12 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 02:49:12 INFO - PROCESS | 1668 | colorDepth:24 02:49:12 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 02:49:12 INFO - PROCESS | 1668 | __metrics 02:49:12 ERROR - PROCESS | 1668 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 02:49:13 INFO - TEST-INFO | 1668: exit 0 02:49:13 INFO - Browser initialized. 02:49:13 INFO - Running cycle 1/1 for dromaeo_css test... 02:49:13 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/tmpcM3_ZT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:49:25 INFO - PROCESS | 1673 | RSS: Main: 221421568 02:49:25 INFO - PROCESS | 1673 | 02:50:15 INFO - PROCESS | 1673 | 1463392215143 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 02:52:26 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 02:52:28 INFO - PROCESS | 1673 | RSS: Main: 231710720 02:52:28 INFO - PROCESS | 1673 | 02:55:30 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 02:55:30 INFO - PROCESS | 1673 | RSS: Main: 243294208 02:55:30 INFO - PROCESS | 1673 | 02:58:32 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 02:58:33 INFO - PROCESS | 1673 | RSS: Main: 245280768 02:58:33 INFO - PROCESS | 1673 | 03:01:35 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 03:01:35 INFO - PROCESS | 1673 | RSS: Main: 247742464 03:01:35 INFO - PROCESS | 1673 | 03:04:38 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 03:04:38 INFO - PROCESS | 1673 | RSS: Main: 250732544 03:04:38 INFO - PROCESS | 1673 | 03:07:41 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 03:07:41 INFO - PROCESS | 1673 | RSS: Main: 250974208 03:07:41 INFO - PROCESS | 1673 | 03:07:41 INFO - PROCESS | 1673 | __start_tp_report 03:07:41 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 03:07:41 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 03:07:41 INFO - PROCESS | 1673 | |i|pagename|runs| 03:07:41 INFO - PROCESS | 1673 | |0;dojo.html;2784.67976182739;3506.1409954561136;3517.3317069756745;3522.6125126236093;3576.8748093816707;5786.537077033832;5800.941990580093;5806.4483874032;5828.0675091985295;5852.590318677694;5793.652380857152;5794.565407594426;5817.098349755784;5824.126381042845;5862.32583252926;5213.400458947222;5285.814996475124;5287.973560132194;5306.628536002481;5315.521603055724;5142.280080788674;5273.235380869772;5289.074411977907;5293;5336.519713225809;4351.173277077361;5218.8695282617855;5250.868728281805;5258.815941442051;5262.0265250928505;5183.922241166367;5202.791888324463;5252.264682944391;5273.288106105665;5340.279062326575;4338.284183109783;5152.458991805881;5235.162374020177;5314.256004159421;5318.282031925718;5675.156509709058;5741.138829175616;5741.827745167651;5776.451237132465;5826.941730582725;6073.757049718006;6191.102290167901;6195.853767053064;6223.937760622381;6291.370862913672;5231.790728370861;6284.434400903895;6292.119103325538;6294.3705629437145;6307.148534947769;5843.590948633598;5888.263967004124;5889.381614930457;5904.291485021782;5916.5858389913155;6197.411245931629;6278.497720182466;6289.465395441439;6299.244090709144;6302.558820882586;6272.686365681698;6273.435390814758;6287.46556542699;6297.622142671363;6304.842378940563;6260.186175797117;6271.43557079865;6278.937210627927;6280.05799130125;6280.654563998933;6187.566870319124;6266.24805023391;6277.905831412532;6282.811515654537;6286;6260.593061450991;6280.905786413207;6291.245050593958;6298.9921612541775;6311.368863113744;4768.736284884509;4927;5001.223342371108;5008.29883816266;5063.87340316495;3999.8800035998966;4011.578784227614;4325.632321252666;4526.932096018562;4540.02389486262;5995.400459953969;6036.547258955596;6044.184035155197;6077.301110372276;6079.969600151971;5613.540479475366;5956.106584012433;6201.162843016135;6222.377762223741;6269.7492100316385;5833.212516310328;5950.4049595041015;5969.522438204933;6037.335893051761;6076.5442591805795;5466.508014278734;5529.949309631157;5532.972335138298;5535.446455354513;5561.972190139023;6335.239771227482;6412.903806442814;6494.9675251624385;6507.804765857032;6508.479321654351;6210.037444196157;6259.874802503833;6275.34108918557;6281.811545653638;6295.433410993032;6496.642684652389;6512.120863683342;6517.478601711947;6520.18497687789;6542.051402546699;6145.231846019247;6165;6261.279952805486;6272.874542509033;6274.058891166362;5420.83737487876;6091.969540152271;6235.251769787655;6264.874702506016;6265.8120256392385;6174.8147555573405;6228.532173116534;6234.438900498977;6246.312905580292;6252.312245652884;6078.057901025348;6131.049687298476;6147.66187859672;6233;6240.126382306572;4686.250199967989;5530.640508367104;6000.729967151576;6110.663913484623;6118.898598252357;6235.594686345373;6240.469560087444;6249.718762655601;6249.781257656142;6259.34276900937;5931.228940237742;6030.5778595497895;6031.24609423822;6054.66699331541;6111.480524155497 03:07:41 INFO - PROCESS | 1673 | |1;ext.html;544.3930018029897;562.4768964862676;568.127923637217;589.101620029455;614.9508039356851;31435.68564314359;40275.798621006856;40285.59714402859;40315.395269070985;40394.7980260099;41670.58329416705;42606.57393426065;42934.92662683427;43289.35065974013;43593.692189234294;9495.857562136573;9616.182624476927;9642.855357169648;9736.853947190797;9749.512524373788;9673.903260967405;9676.806463870718;9767.853482197772;9810.215182785396;9842.60629574816;9758.414495130279;9779.853302200472;9820.901790982105;9836.557354919047;9847.556859941284;9740.610375585004;9798;9808.264380171515;9819.3126481146;9880.802383952352;9553.856692149624;9601.711948641552;9692.176165025981;9693.41839489633;9711.223102151811;40281.395779063045;40527.59472405268;40737;40776;40819;2794.804363694542;3185.9681403185905;3266.7059964603063;3301.257217126127;3321.4187517184637;26788.46423071547;27116;27135;27272;27731.58402623942;2711.8237314574685;2751.1883994221753;2770.141256210581;2771.7921155913386;2802.929926751827;343358;344375;344425;345486;347721;66063;66339.66830165818;66667.33330666817;66701.33298666951;66816;73377;74872.25127748762;75062.62468687621;75174.62412687902;75200.24799752042;17036.403725869535;17068.317267309416;17624.647507050042;17713.380031698827;17849.10754462272;13249;13323.733525329439;13368.532101376406;13391.531296404579;13413.0610857241;20341.491462713253;20593.38219853407;21046.47383815417;21111.68332475014;21185.046672899756;7024.824379390556;7955.920440795518;8224.958875205586;8283.461574997606;8323.375746818892;769.54981335918;833.3166803221421;982.2142286171035;1077.472038701031;1144.2905398652722;23341.41646458852;30539.08382748521;30602.84698576493;31111.68888311088;31677.683223167933;1038.8026275007724;1083.8049151152711;1087.2280680716622;1168.9123315751353;1190.4880901212389;13321.200727956353;15858.207089645703;15870.603234918988;15874.841251587568;16217.189140542925;416490;508567;545103;547059;547306;61753;62750.0587491188;63971.04043439352;64008.67995659992;64081;1473.6536913825457;1474;1475;1494.1782019889017;1506.314626844783;30231.546526801674;30476;30624.800574345743;30748;30759.923402680797;56122.71938640444;56447.71776141093;56571.434285658266;56615.58461038507;56718.43281567297;484841;487692;490314;490655;493476;27992.580111298754;28059.71940280571;28215.871365145165;28473.145805625863;28486.857565712868;47667.523324766305;48055.75972120117;49243;49678.503214967386;49916;36774.44838327374;36880;37212;37490.43764343482;37564.81217594004;30208.5468717965;36130.63869361273;36596.81701591475;36865.63134368622;36950.26099478049;32653.18367040843;35973.92078237657;36583.81708091443;37005.629943700216;37058.629413705516;30984.690153099084;31746.365072697954;31880;31908.680913190572;32303.51544726878;24644.137455188982;24694;24841.006359745406;24850.627240591766;25009.374765631004 03:07:41 INFO - PROCESS | 1673 | |2;jquery.html;389.34201199972046;393.5218709268242;393.8306528192877;401.8754186202278;405.88838069530874;14888.702225955501;15797.973131746428;20476.795232047676;21195;21203.151873925064;16609.91695041526;16806.15969201541;20098.799012009913;20218.595628087467;21105.894470527663;5961.940380596193;6148.754049838023;6149.631022138664;6153.538484613649;6163.784267550636;5845;5958.8808223835495;6123.908141377883;6139.846503837395;6147.170132032185;6100.603460775058;6144.44699977002;6153.7230824613;6161.815145545641;6185.381461853801;5804.651720896739;5948.73230704615;6133.570650054498;6153.876922461525;6193.009118541035;5845.327787304472;5935.228420305376;5962.791302304442;5979.1629171915965;6003.399660034005;13490.595282141552;13504;13830.03189776706;13840.238786866717;13854.584362469142;2189.8467107302495;2192.4190089626263;2219.7780221977673;2396.412878844677;2756.3935934094466;9858.211343092535;10346.22403319754;10372.481375931173;13165;14523.273836308248;1594.4260066376096;1605.9759103613455;1771.9822801771945;1800.6668766278149;1901.7812951510616;112932;113765.43117284443;114576;116979.99999999916;122266.38866805698;10094.646687365906;11364.715882102848;11984.700382490508;11999.220050696676;12183.756324873453;15202.923985380003;15307.846921530865;15503.922480387526;15524;15581.922090389475;8877.067907869581;9031.36780425377;9040.09599040104;9131.26949844011;9157.542122893828;7340.926590734131;8931.374803763803;8978.551072446351;9066.410683305565;9178.954105229432;3873.457715919717;4764.3086704220195;4796.35249241348;4845.830395936126;4866.8783280417565;1654.925528351227;1968.9015549222768;2022.4337185588058;2025.534127150734;2030.218365929128;942.1002942190192;978.0561757903515;978.5596481583316;985.7486340983004;1004.9648262310784;19335.709964350546;20040;20484.79515204829;20600.896995514926;20849.478763031046;984.640606178754;991.1525645573075;998.6205242008135;1004.1163775877327;1039.8821267137705;10787.78424431507;10908.781824363627;10911.945440272748;10930.180236482296;10996.560137594566;622655;623374;624908;624917;635529;99653;118197.22704159445;119575;120327;123767.38116309361;986.1124987511154;994.7413672445216;1001.1590264178126;1008.1682611845257;1008.2992320337441;17874.28502859871;18149.183286751864;18261.269549217883;18315.17581708853;18966.715499267797;52531.21203181879;52742;53459.198112027574;53601;55164;52147.47852521578;57065;72581;78477;80348;8158.740937506203;8177.877331840146;8218.424710270223;8405.915940840514;8442.70450534253;10637.627683031056;11337.206395552232;12605.369731513205;12623.242605443702;12632.810507842207;10601.310914790514;10948.288361256493;11076.501557430096;11102.389368584805;11140.665780026613;9350.298727595322;10686.946565267124;10749.785004300027;10872;11177.385243811343;11048.729396119548;11113.611023614134;11301.71745706364;11342.829857552308;11424.48589813477;6935.410490108397;8048.5170889746805;8189.058258300133;8304.87542686848;8501.19238672325;10234.48827558634;11268.887311126784;11420.600278990527;11422;11622.94188529052 03:07:41 INFO - PROCESS | 1673 | |3;mootools.html;1382.854800245974;1584.112896777804;1769.654917291128;1877.6526342626614;1948.5420926082377;8037.27664510194;8639.265662418702;8744.737657870266;8982.595783189749;9016.053314401992;8758.518281494515;8760.211580957712;8871.423357481775;8883.60023798929;8885.333599980011;2044.2027609232375;2426.1144682191048;2455.312512496504;2464.2360868130845;2467.1858286765414;2417.6736140620965;2427.987860060697;2480.776730094291;2484.776370126688;2488.191337815208;2437.3541011631933;2438.37821355555;2462.507498500298;2476.950460990788;2502.324372419446;2190.5290362572046;2214.2139540463063;2402.7745849616736;2478.08310924958;2498.5127900059497;1920.2894928876406;2100.8865301390288;2457.9016839326405;2474.0227610094094;2484.0312278211513;8712.215900568915;8895.110488951053;9046.457212567202;9056.275497960214;9078.455292682394;4957.6033917286895;5209.82778874754;5431.321084864392;5494.2857428534035;5541.806036788734;3547.8758243461357;3637.2725454909123;3641.4537819327047;3679.4848721179055;3686.391745362012;1053.9894601054045;1071.2982996137541;1071.9624813131502;1075.317173594765;1076.0638244727058;12166.391680415943;12283.877161228364;12308.876911230953;12329.260244385365;12337.321447320393;4825.396825396825;4843.878903027382;4922.729249891218;4930.950690493121;4931.90136197274;10231.181505479544;10275.02387273208;10277.74305642365;10280.485975701185;10297.07326340633;5439.864003399867;5441.183822426668;5518.613697041249;5522.447755224445;5525.364583072917;4429.844955426544;4894.216925291937;4993.375828021497;5005;5051.646384753032;3439.7420193485586;4066.9186616268103;4129.056562622365;4155.649413940481;4164.042270277853;1179.887910648487;1254.4166962362544;1270.5489551209373;1348.6493511686836;1348.676317683749;893.65147592439;915.1717695485596;933.1881263300971;936.3164889630608;938.2775263047415;5938.940610593925;5981.043033114681;5997.640141591519;6160.568760186834;6241.469475094577;460.75349687917475;480.28437627934125;1001.7796084861319;1084.7776205877776;1102.4267380961855;3548.716102711777;3638.9810852961214;3872.186840763471;3983.462232598562;4000.779762172507;10415.270931034906;10691.625793097204;10794.676159715222;10798.892011079946;10845.240833141605;10513.57945682164;10517.474126293655;10524.421156836448;10663.093637040938;10706.946465267623;7948.602569871599;8325.916740832636;8345.082040975487;8393.706220282262;8451.45065570736;5875.559333049953;5884.529237661062;5950.642961422329;6015.909761353496;6104.908426373697;9456.479893605918;9458.243340532603;9578.760530986781;9665.613375464902;9682.854757178507;9520.047995200424;9703.757406064904;9721.4653194075;9734.36726612768;9808.65669701557;6647.900281495685;6788.66056697173;7321.304476074764;7379.372753316028;7585.16563178061;7858.174891636296;8253.257206851413;8284.46151000183;8319.625616847135;8325.25072743456;7226.530275532074;7356.852862942604;7361.631918404166;7444.776656700307;7489.775306740807;7148.17795953472;7174.282571742784;7198.676059577437;7266.164391095092;7313.780586582411;6827;6840.657967101525;6867.313268673092;6894.586324820527;6897.206821215625;4872.634552408655;4872.926906096341;4884.462709102067;4969.35398398206;4991.351124353956;4272.700910936329;4599.126166028444;4926.4088309403105;4985.401751789808;5073.568746656576 03:07:41 INFO - PROCESS | 1673 | |4;prototype.html;323.0566745104295;328.8881780194734;332.88848235840993;335.0735217124649;337.8732975134325;5242.636914402259;6656.833579160523;6663.933360666392;6729.125213722209;6765.154355705537;6675.165604299463;6682.431993280565;6686.197656281241;6699.665016749167;6715.966420167904;4004.439378487014;4531.52418996004;4859.343988561552;4860.781264843091;4884.804607815683;4820.879478013043;4859.951400485986;4878.292647566101;4889.144399730051;4903.0929278083495;4845.975770121162;4847.927281090787;4849.709017458946;4863.756812159396;4876.244182151772;4879.146149423872;4882.926756098661;4884.340614017097;4888.217885138396;4934.753262336869;4189.224993376235;4192.517860446057;4195.937060944088;4202.85290014851;4215.59951804578;6078.544109191828;6289.7169627366875;6339.397757213057;6396.232452105731;6400.135981642466;4607.35497030416;5648.237487939158;5666.1784041314195;5695.487406133427;5710.343310519302;7183.060573645086;7189.676464559107;7189.748358807469;7223.89164162538;7225.45809064317;3843.442700808395;4545.568171023748;4613.953860461386;4626.583607475343;4647.930281045787;7502.924970750277;7568.810779730551;7578.507397019176;7584;7617.771466856003;3800.7149463790324;3957.22834047358;4675.696079754805;4850.684705494202;4859.319695242669;4888.437829649636;4950.158473059589;4958.57852082577;4967.105920934267;4969.105560999054;4680.578747912704;5795.797147099831;5837.445442682938;5848.941510584839;5876.177335173079;7238.384737297389;7240.601766902765;7252.637368131573;7255.818604534823;7264.709411623476;1720.4494561740373;1747.0827815396765;1748.5278974676767;1755.1926113987454;1756.5608597850537;878.5695009445326;1113.3653817324048;1126.8140756775203;1128.1651577832345;1140.925839820409;6727.831804204836;6753.3246675332075;6772.830679233059;6802.285759995229;6804.897926531106;5996.910046349309;7250.528715633573;7497.850042999218;7521.135069466972;7527.661255243418;6678.899816502756;6820.454363650896;6845.14435695538;6848.589084654936;6889.276625954303;3929.941050884239;3939.03493644059;3970.7419017763755;3971;3992.6007399259843;6355.745770169141;7917.524948503108;7922.405819563556;7926.365890728728;7942;7569.621518924142;7812.335951444077;7820.530768153929;7837.333826624801;7843.529388236724;7882.132965373919;7882.487638303606;7914.010748656418;7921.524708517507;7957.1644977276555;5987.790427335022;7545.283198096172;7549.396048316232;7566.697332106654;7570.735024274344;7427.368673662799;7430.071241094864;7435.107787065586;7444.776656700307;7456.067991501062;6231.563790534619;7421.8144546386775;7882.645280962256;7895.368370530229;7939.960300198462;4859.1496488113735;4898.559129678346;4903.26451032348;4908.631852610997;4921.163402221559;6019.668918209541;7456.440766942609;7468.775936721907;7502;7514.586697731677;7214.350708436266;7215.665101956155;7232.45756568249;7236.73357162505;7240.855182896418;6902.585844849325;7190.316919892602;7199.2440793715905;7264.636768161677;7275.236100209613;5777.422257774189;6956.7130080935185;7065.364117229474;7067.363937245673;7074.186468556182;7193.748218812317;7225.963870180615;7226.241244669234;7229.421646268181;7233.855322893407;7019.543729657556;7064.540804847874;7065.823354415975;7077.504574679929;7101.041359416619 03:07:41 INFO - PROCESS | 1673 | |5;yui.html;658.4666420199636;675.0549231076492;676.3811112831759;694.600604652325;743.5018239184636;14295.356708948097;14424.423023079078;14462.987590868643;14664.266786660664;14665.04677195983;13335.466581336736;13341.399637016333;13372;13386.933065334684;15098.396064157421;921.5208091792264;937.6296362936636;940.5062342270329;940.7930255343813;942.3309450290302;914.6981496106268;929.6513807322254;930.418488444722;932.3753085432743;935.7567032571512;939.1031564855581;940.952952352383;948.2366694810709;953.1897886796203;976.843705007199;933.9906600933972;934.7803266232464;954.312894715807;954.4082668745353;975.1759763000254;701.382783150824;705.6824429006916;720.121451828768;721.1562471907828;726.4696771356939;14668.339924703414;14810.037347572374;14829.480968166063;14842.628934276729;14873.47942821996;12306.569270075594;12339.568115116017;12405.317707526083;12541.122121451503;12604.684882878002;9922.553485093189;10105.595776168944;10218.693439196835;10231.283810133295;10249.590016399336;3192.680731926812;3326.750493712957;3469.60099588548;3535.805530695811;3686.7234957378037;79536.40927181482;80054.19810306611;82483;82670.9999999994;82775;7605.733799316998;9244.3991140577;9470.052994700474;9476.194523465445;9531.428114313163;20806.479838004172;20920.37238882836;20954;20983.37049888506;21005.68491472629;7101.396381307647;7104.68028938699;7191.7842464726145;7231;7320.1581818090535;3994.46074779901;4023.6982226333143;4094.6314831665295;4116.259073366823;4124.711270211116;3571.8749843755345;3605.152789094561;3633.7274704397278;3634.4548317752547;3640.2173532690067;632.3739497897049;636.6721138613618;637.6046850952439;642.1651852591706;646.8576913079115;577.2784019975031;580.8199458167982;588.5379976718341;594.649156997365;595.3570144244206;7785.221477852169;8061.838763224702;8127.837443251102;8210.261076503024;8541.103184165697;498.66091058080127;575.6114622629708;584.8917950179231;587.4918195760637;615.0835255469408;3713.665770080706;3863.430818505662;3894.766314021168;3895.922081558353;3937.310970580137;85308.72036919452;86430;91018.63472047924;92632;92798.0720192803;74196;74299;74493;75498.62250688822;76675.61662191653;66219.34451638747;66223.33776662171;67159.31362745533;67366;67991;7673;7735.303822655988;7765.41759368061;7849.097353804385;7862;47069.29396058993;48900.266496001874;49463.25805112854;49477.01045979132;50647;60533.39466605454;61500;64460.35539644543;64831;65579;10572.682819515427;10578.8413173804;10628.043476087189;10837.295575787548;11127;9148.222401095714;11805.055595552163;11910.166288359731;12092.697682558006;12382;11174.49714762854;11333.26333788301;11446;11489.482973266056;11531.827022594836;11377.975982161644;11456.541738330374;11491.310521368745;11657.591984280509;12057.879421205676;9828.4102953823;10279.588816447258;10730.62442814529;11006.394648294421;11175.720606984565;9362.859557106785;9389.671361502315;9410.058994100535;9534.52327383642;9553.856692149484;9326.860097098413;9444.244460443011;9594.376365536218;9599.42403455795;9645.9035409645 03:07:41 INFO - PROCESS | 1673 | __end_tp_report 03:07:41 INFO - PROCESS | 1673 | __start_cc_report 03:07:41 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,414 03:07:41 INFO - PROCESS | 1673 | __end_cc_report 03:07:41 INFO - PROCESS | 1673 | __startTimestamp1463393261386__endTimestamp 03:07:41 INFO - PROCESS | 1673 | 03:07:41 INFO - PROCESS | 1673 | ------- Summary: start ------- 03:07:41 INFO - PROCESS | 1673 | Number of tests: 6 03:07:41 INFO - PROCESS | 1673 | 03:07:41 INFO - PROCESS | 1673 | [#0] dojo.html Cycles:165 Average:5786.77 Median:6054.67 stddev:687.39 (11.4%) stddev-sans-first:647.90 03:07:41 INFO - PROCESS | 1673 | Values: 2784.7 3506.1 3517.3 3522.6 3576.9 5786.5 5800.9 5806.4 5828.1 5852.6 5793.7 5794.6 5817.1 5824.1 5862.3 5213.4 5285.8 5288.0 5306.6 5315.5 5142.3 5273.2 5289.1 5293.0 5336.5 4351.2 5218.9 5250.9 5258.8 5262.0 5183.9 5202.8 5252.3 5273.3 5340.3 4338.3 5152.5 5235.2 5314.3 5318.3 5675.2 5741.1 5741.8 5776.5 5826.9 6073.8 6191.1 6195.9 6223.9 6291.4 5231.8 6284.4 6292.1 6294.4 6307.1 5843.6 5888.3 5889.4 5904.3 5916.6 6197.4 6278.5 6289.5 6299.2 6302.6 6272.7 6273.4 6287.5 6297.6 6304.8 6260.2 6271.4 6278.9 6280.1 6280.7 6187.6 6266.2 6277.9 6282.8 6286.0 6260.6 6280.9 6291.2 6299.0 6311.4 4768.7 4927.0 5001.2 5008.3 5063.9 3999.9 4011.6 4325.6 4526.9 4540.0 5995.4 6036.5 6044.2 6077.3 6080.0 5613.5 5956.1 6201.2 6222.4 6269.7 5833.2 5950.4 5969.5 6037.3 6076.5 5466.5 5529.9 5533.0 5535.4 5562.0 6335.2 6412.9 6495.0 6507.8 6508.5 6210.0 6259.9 6275.3 6281.8 6295.4 6496.6 6512.1 6517.5 6520.2 6542.1 6145.2 6165.0 6261.3 6272.9 6274.1 5420.8 6092.0 6235.3 6264.9 6265.8 6174.8 6228.5 6234.4 6246.3 6252.3 6078.1 6131.0 6147.7 6233.0 6240.1 4686.3 5530.6 6000.7 6110.7 6118.9 6235.6 6240.5 6249.7 6249.8 6259.3 5931.2 6030.6 6031.2 6054.7 6111.5 03:07:41 INFO - PROCESS | 1673 | 03:07:41 INFO - PROCESS | 1673 | [#1] ext.html Cycles:180 Average:60932.57 Median:37351.22 stddev:121185.12 (324.4%) stddev-sans-first:121440.26 03:07:41 INFO - PROCESS | 1673 | Values: 544.4 562.5 568.1 589.1 615.0 31435.7 40275.8 40285.6 40315.4 40394.8 41670.6 42606.6 42934.9 43289.4 43593.7 9495.9 9616.2 9642.9 9736.9 9749.5 9673.9 9676.8 9767.9 9810.2 9842.6 9758.4 9779.9 9820.9 9836.6 9847.6 9740.6 9798.0 9808.3 9819.3 9880.8 9553.9 9601.7 9692.2 9693.4 9711.2 40281.4 40527.6 40737.0 40776.0 40819.0 2794.8 3186.0 3266.7 3301.3 3321.4 26788.5 27116.0 27135.0 27272.0 27731.6 2711.8 2751.2 2770.1 2771.8 2802.9 343358.0 344375.0 344425.0 345486.0 347721.0 66063.0 66339.7 66667.3 66701.3 66816.0 73377.0 74872.3 75062.6 75174.6 75200.2 17036.4 17068.3 17624.6 17713.4 17849.1 13249.0 13323.7 13368.5 13391.5 13413.1 20341.5 20593.4 21046.5 21111.7 21185.0 7024.8 7955.9 8225.0 8283.5 8323.4 769.5 833.3 982.2 1077.5 1144.3 23341.4 30539.1 30602.8 31111.7 31677.7 1038.8 1083.8 1087.2 1168.9 1190.5 13321.2 15858.2 15870.6 15874.8 16217.2 416490.0 508567.0 545103.0 547059.0 547306.0 61753.0 62750.1 63971.0 64008.7 64081.0 1473.7 1474.0 1475.0 1494.2 1506.3 30231.5 30476.0 30624.8 30748.0 30759.9 56122.7 56447.7 56571.4 56615.6 56718.4 484841.0 487692.0 490314.0 490655.0 493476.0 27992.6 28059.7 28215.9 28473.1 28486.9 47667.5 48055.8 49243.0 49678.5 49916.0 36774.4 36880.0 37212.0 37490.4 37564.8 30208.5 36130.6 36596.8 36865.6 36950.3 32653.2 35973.9 36583.8 37005.6 37058.6 30984.7 31746.4 31880.0 31908.7 32303.5 24644.1 24694.0 24841.0 24850.6 25009.4 03:07:41 INFO - PROCESS | 1673 | 03:07:41 INFO - PROCESS | 1673 | [#2] jquery.html Cycles:180 Average:34835.61 Median:21150.45 stddev:103932.22 (491.4%) stddev-sans-first:104191.59 03:07:41 INFO - PROCESS | 1673 | Values: 389.3 393.5 393.8 401.9 405.9 14888.7 15798.0 20476.8 21195.0 21203.2 16609.9 16806.2 20098.8 20218.6 21105.9 5961.9 6148.8 6149.6 6153.5 6163.8 5845.0 5958.9 6123.9 6139.8 6147.2 6100.6 6144.4 6153.7 6161.8 6185.4 5804.7 5948.7 6133.6 6153.9 6193.0 5845.3 5935.2 5962.8 5979.2 6003.4 13490.6 13504.0 13830.0 13840.2 13854.6 2189.8 2192.4 2219.8 2396.4 2756.4 9858.2 10346.2 10372.5 13165.0 14523.3 1594.4 1606.0 1772.0 1800.7 1901.8 112932.0 113765.4 114576.0 116980.0 122266.4 10094.6 11364.7 11984.7 11999.2 12183.8 15202.9 15307.8 15503.9 15524.0 15581.9 8877.1 9031.4 9040.1 9131.3 9157.5 7340.9 8931.4 8978.6 9066.4 9179.0 3873.5 4764.3 4796.4 4845.8 4866.9 1654.9 1968.9 2022.4 2025.5 2030.2 942.1 978.1 978.6 985.7 1005.0 19335.7 20040.0 20484.8 20600.9 20849.5 984.6 991.2 998.6 1004.1 1039.9 10787.8 10908.8 10911.9 10930.2 10996.6 622655.0 623374.0 624908.0 624917.0 635529.0 99653.0 118197.2 119575.0 120327.0 123767.4 986.1 994.7 1001.2 1008.2 1008.3 17874.3 18149.2 18261.3 18315.2 18966.7 52531.2 52742.0 53459.2 53601.0 55164.0 52147.5 57065.0 72581.0 78477.0 80348.0 8158.7 8177.9 8218.4 8405.9 8442.7 10637.6 11337.2 12605.4 12623.2 12632.8 10601.3 10948.3 11076.5 11102.4 11140.7 9350.3 10686.9 10749.8 10872.0 11177.4 11048.7 11113.6 11301.7 11342.8 11424.5 6935.4 8048.5 8189.1 8304.9 8501.2 10234.5 11268.9 11420.6 11422.0 11622.9 03:07:41 INFO - PROCESS | 1673 | 03:07:41 INFO - PROCESS | 1673 | [#3] mootools.html Cycles:180 Average:5727.50 Median:4928.68 stddev:3207.35 (65.1%) stddev-sans-first:3199.72 03:07:41 INFO - PROCESS | 1673 | Values: 1382.9 1584.1 1769.7 1877.7 1948.5 8037.3 8639.3 8744.7 8982.6 9016.1 8758.5 8760.2 8871.4 8883.6 8885.3 2044.2 2426.1 2455.3 2464.2 2467.2 2417.7 2428.0 2480.8 2484.8 2488.2 2437.4 2438.4 2462.5 2477.0 2502.3 2190.5 2214.2 2402.8 2478.1 2498.5 1920.3 2100.9 2457.9 2474.0 2484.0 8712.2 8895.1 9046.5 9056.3 9078.5 4957.6 5209.8 5431.3 5494.3 5541.8 3547.9 3637.3 3641.5 3679.5 3686.4 1054.0 1071.3 1072.0 1075.3 1076.1 12166.4 12283.9 12308.9 12329.3 12337.3 4825.4 4843.9 4922.7 4931.0 4931.9 10231.2 10275.0 10277.7 10280.5 10297.1 5439.9 5441.2 5518.6 5522.4 5525.4 4429.8 4894.2 4993.4 5005.0 5051.6 3439.7 4066.9 4129.1 4155.6 4164.0 1179.9 1254.4 1270.5 1348.6 1348.7 893.7 915.2 933.2 936.3 938.3 5938.9 5981.0 5997.6 6160.6 6241.5 460.8 480.3 1001.8 1084.8 1102.4 3548.7 3639.0 3872.2 3983.5 4000.8 10415.3 10691.6 10794.7 10798.9 10845.2 10513.6 10517.5 10524.4 10663.1 10706.9 7948.6 8325.9 8345.1 8393.7 8451.5 5875.6 5884.5 5950.6 6015.9 6104.9 9456.5 9458.2 9578.8 9665.6 9682.9 9520.0 9703.8 9721.5 9734.4 9808.7 6647.9 6788.7 7321.3 7379.4 7585.2 7858.2 8253.3 8284.5 8319.6 8325.3 7226.5 7356.9 7361.6 7444.8 7489.8 7148.2 7174.3 7198.7 7266.2 7313.8 6827.0 6840.7 6867.3 6894.6 6897.2 4872.6 4872.9 4884.5 4969.4 4991.4 4272.7 4599.1 4926.4 4985.4 5073.6 03:07:41 INFO - PROCESS | 1673 | 03:07:41 INFO - PROCESS | 1673 | [#4] prototype.html Cycles:180 Average:5851.70 Median:6684.31 stddev:1900.67 (28.4%) stddev-sans-first:1860.15 03:07:41 INFO - PROCESS | 1673 | Values: 323.1 328.9 332.9 335.1 337.9 5242.6 6656.8 6663.9 6729.1 6765.2 6675.2 6682.4 6686.2 6699.7 6716.0 4004.4 4531.5 4859.3 4860.8 4884.8 4820.9 4860.0 4878.3 4889.1 4903.1 4846.0 4847.9 4849.7 4863.8 4876.2 4879.1 4882.9 4884.3 4888.2 4934.8 4189.2 4192.5 4195.9 4202.9 4215.6 6078.5 6289.7 6339.4 6396.2 6400.1 4607.4 5648.2 5666.2 5695.5 5710.3 7183.1 7189.7 7189.7 7223.9 7225.5 3843.4 4545.6 4614.0 4626.6 4647.9 7502.9 7568.8 7578.5 7584.0 7617.8 3800.7 3957.2 4675.7 4850.7 4859.3 4888.4 4950.2 4958.6 4967.1 4969.1 4680.6 5795.8 5837.4 5848.9 5876.2 7238.4 7240.6 7252.6 7255.8 7264.7 1720.4 1747.1 1748.5 1755.2 1756.6 878.6 1113.4 1126.8 1128.2 1140.9 6727.8 6753.3 6772.8 6802.3 6804.9 5996.9 7250.5 7497.9 7521.1 7527.7 6678.9 6820.5 6845.1 6848.6 6889.3 3929.9 3939.0 3970.7 3971.0 3992.6 6355.7 7917.5 7922.4 7926.4 7942.0 7569.6 7812.3 7820.5 7837.3 7843.5 7882.1 7882.5 7914.0 7921.5 7957.2 5987.8 7545.3 7549.4 7566.7 7570.7 7427.4 7430.1 7435.1 7444.8 7456.1 6231.6 7421.8 7882.6 7895.4 7940.0 4859.1 4898.6 4903.3 4908.6 4921.2 6019.7 7456.4 7468.8 7502.0 7514.6 7214.4 7215.7 7232.5 7236.7 7240.9 6902.6 7190.3 7199.2 7264.6 7275.2 5777.4 6956.7 7065.4 7067.4 7074.2 7193.7 7226.0 7226.2 7229.4 7233.9 7019.5 7064.5 7065.8 7077.5 7101.0 03:07:41 INFO - PROCESS | 1673 | 03:07:41 INFO - PROCESS | 1673 | [#5] yui.html Cycles:180 Average:17809.07 Median:637.14 stddev:24940.38 (3914.4%) stddev-sans-first:24977.09 03:07:41 INFO - PROCESS | 1673 | Values: 658.5 675.1 676.4 694.6 743.5 14295.4 14424.4 14463.0 14664.3 14665.0 13335.5 13341.4 13372.0 13386.9 15098.4 921.5 937.6 940.5 940.8 942.3 914.7 929.7 930.4 932.4 935.8 939.1 941.0 948.2 953.2 976.8 934.0 934.8 954.3 954.4 975.2 701.4 705.7 720.1 721.2 726.5 14668.3 14810.0 14829.5 14842.6 14873.5 12306.6 12339.6 12405.3 12541.1 12604.7 9922.6 10105.6 10218.7 10231.3 10249.6 3192.7 3326.8 3469.6 3535.8 3686.7 79536.4 80054.2 82483.0 82671.0 82775.0 7605.7 9244.4 9470.1 9476.2 9531.4 20806.5 20920.4 20954.0 20983.4 21005.7 7101.4 7104.7 7191.8 7231.0 7320.2 3994.5 4023.7 4094.6 4116.3 4124.7 3571.9 3605.2 3633.7 3634.5 3640.2 632.4 636.7 637.6 642.2 646.9 577.3 580.8 588.5 594.6 595.4 7785.2 8061.8 8127.8 8210.3 8541.1 498.7 575.6 584.9 587.5 615.1 3713.7 3863.4 3894.8 3895.9 3937.3 85308.7 86430.0 91018.6 92632.0 92798.1 74196.0 74299.0 74493.0 75498.6 76675.6 66219.3 66223.3 67159.3 67366.0 67991.0 7673.0 7735.3 7765.4 7849.1 7862.0 47069.3 48900.3 49463.3 49477.0 50647.0 60533.4 61500.0 64460.4 64831.0 65579.0 10572.7 10578.8 10628.0 10837.3 11127.0 9148.2 11805.1 11910.2 12092.7 12382.0 11174.5 11333.3 11446.0 11489.5 11531.8 11378.0 11456.5 11491.3 11657.6 12057.9 9828.4 10279.6 10730.6 11006.4 11175.7 9362.9 9389.7 9410.1 9534.5 9553.9 9326.9 9444.2 9594.4 9599.4 9645.9 03:07:41 INFO - PROCESS | 1673 | -------- Summary: end -------- 03:07:41 INFO - PROCESS | 1673 | 03:07:41 INFO - TEST-INFO | 1673: exit 0 03:07:41 INFO - TEST-OK | dromaeo_css | took 1112296ms 03:07:41 INFO - TEST-START | kraken 03:07:41 INFO - operating with platform_type : mac_ 03:07:41 INFO - Initialising browser for kraken test... 03:07:41 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/tmpwH6sFm/profile http://localhost:49276/getInfo.html) 03:07:44 INFO - PROCESS | 1681 | __metrics Screen width/height:1600/1200 03:07:44 INFO - PROCESS | 1681 | colorDepth:24 03:07:44 INFO - PROCESS | 1681 | Browser inner width/height: 1024/689 03:07:44 INFO - PROCESS | 1681 | __metrics 03:07:44 ERROR - PROCESS | 1681 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 03:07:45 INFO - TEST-INFO | 1681: exit 0 03:07:45 INFO - Browser initialized. 03:07:45 INFO - Running cycle 1/1 for kraken test... 03:07:45 INFO - TEST-INFO | started process 1685 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/tmpwH6sFm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:07:57 INFO - PROCESS | 1685 | RSS: Main: 220610560 03:07:57 INFO - PROCESS | 1685 | 03:08:00 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 03:08:01 INFO - PROCESS | 1685 | RSS: Main: 232677376 03:08:01 INFO - PROCESS | 1685 | 03:08:03 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 03:08:04 INFO - PROCESS | 1685 | RSS: Main: 241692672 03:08:04 INFO - PROCESS | 1685 | 03:08:07 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 03:08:08 INFO - PROCESS | 1685 | RSS: Main: 245129216 03:08:08 INFO - PROCESS | 1685 | 03:08:10 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 03:08:11 INFO - PROCESS | 1685 | RSS: Main: 246288384 03:08:11 INFO - PROCESS | 1685 | 03:08:13 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:08:13 INFO - PROCESS | 1685 | RSS: Main: 246521856 03:08:13 INFO - PROCESS | 1685 | 03:08:16 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 03:08:17 INFO - PROCESS | 1685 | RSS: Main: 247873536 03:08:17 INFO - PROCESS | 1685 | 03:08:20 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 03:08:21 INFO - PROCESS | 1685 | RSS: Main: 248061952 03:08:21 INFO - PROCESS | 1685 | 03:08:24 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 03:08:24 INFO - PROCESS | 1685 | RSS: Main: 248311808 03:08:24 INFO - PROCESS | 1685 | 03:08:26 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:08:27 INFO - PROCESS | 1685 | RSS: Main: 247341056 03:08:27 INFO - PROCESS | 1685 | 03:08:29 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:08:29 INFO - PROCESS | 1685 | RSS: Main: 247459840 03:08:29 INFO - PROCESS | 1685 | 03:08:32 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:08:32 INFO - PROCESS | 1685 | RSS: Main: 247447552 03:08:32 INFO - PROCESS | 1685 | 03:08:35 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:08:36 INFO - PROCESS | 1685 | RSS: Main: 247533568 03:08:36 INFO - PROCESS | 1685 | 03:08:39 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:08:39 INFO - PROCESS | 1685 | RSS: Main: 247492608 03:08:39 INFO - PROCESS | 1685 | 03:08:41 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 03:08:41 INFO - PROCESS | 1685 | RSS: Main: 249200640 03:08:41 INFO - PROCESS | 1685 | 03:08:41 INFO - PROCESS | 1685 | __start_tp_report 03:08:41 INFO - PROCESS | 1685 | _x_x_mozilla_page_load 03:08:41 INFO - PROCESS | 1685 | _x_x_mozilla_page_load_details 03:08:41 INFO - PROCESS | 1685 | |i|pagename|runs| 03:08:41 INFO - PROCESS | 1685 | |0;ai-astar;114;117;108;118;131;171;156;162;117;116 03:08:41 INFO - PROCESS | 1685 | |1;audio-beat-detection;191;157;142;161;120;139;124;164;152;149 03:08:41 INFO - PROCESS | 1685 | |2;audio-dft;195;199;195;196;194;251;202;208;219;218 03:08:41 INFO - PROCESS | 1685 | |3;audio-fft;63;76;73;150;65;80;80;84;127;59 03:08:41 INFO - PROCESS | 1685 | |4;audio-oscillator;78;82;126;93;114;65;143;119;117;106 03:08:41 INFO - PROCESS | 1685 | |5;imaging-gaussian-blur;90;88;95;129;90;87;91;115;136;104 03:08:41 INFO - PROCESS | 1685 | |6;imaging-darkroom;95;100;142;148;107;141;151;96;94;106 03:08:41 INFO - PROCESS | 1685 | |7;imaging-desaturate;144;130;125;101;150;111;76;78;104;101 03:08:41 INFO - PROCESS | 1685 | |8;json-parse-financial;61;64;83;100;103;80;57;86;74;84 03:08:41 INFO - PROCESS | 1685 | |9;json-stringify-tinderbox;74;77;70;78;70;74;72;64;67;69 03:08:41 INFO - PROCESS | 1685 | |10;stanford-crypto-aes;96;93;107;77;86;103;104;74;101;104 03:08:41 INFO - PROCESS | 1685 | |11;stanford-crypto-ccm;149;147;129;129;133;142;152;151;182;144 03:08:41 INFO - PROCESS | 1685 | |12;stanford-crypto-pbkdf2;190;244;175;170;169;166;169;169;216;186 03:08:41 INFO - PROCESS | 1685 | |13;stanford-crypto-sha256-iterative;66;89;73;57;97;99;101;92;66;73 03:08:41 INFO - PROCESS | 1685 | __end_tp_report 03:08:41 INFO - PROCESS | 1685 | __start_cc_report 03:08:41 INFO - PROCESS | 1685 | _x_x_mozilla_cycle_collect,1085 03:08:41 INFO - PROCESS | 1685 | __end_cc_report 03:08:41 INFO - PROCESS | 1685 | __startTimestamp1463393321936__endTimestamp 03:08:41 INFO - PROCESS | 1685 | 03:08:41 INFO - PROCESS | 1685 | ------- Summary: start ------- 03:08:41 INFO - PROCESS | 1685 | Number of tests: 14 03:08:41 INFO - PROCESS | 1685 | 03:08:41 INFO - PROCESS | 1685 | [#0] ai-astar Cycles:10 Average:131.00 Median:124.50 stddev:23.07 (18.5%) stddev-sans-first:23.63 03:08:41 INFO - PROCESS | 1685 | Values: 114.0 117.0 108.0 118.0 131.0 171.0 156.0 162.0 117.0 116.0 03:08:41 INFO - PROCESS | 1685 | 03:08:41 INFO - PROCESS | 1685 | [#1] audio-beat-detection Cycles:10 Average:149.90 Median:154.50 stddev:20.58 (13.3%) stddev-sans-first:15.56 03:08:41 INFO - PROCESS | 1685 | Values: 191.0 157.0 142.0 161.0 120.0 139.0 124.0 164.0 152.0 149.0 03:08:41 INFO - PROCESS | 1685 | 03:08:41 INFO - PROCESS | 1685 | [#2] audio-dft Cycles:10 Average:207.70 Median:205.00 stddev:17.84 (8.7%) stddev-sans-first:18.32 03:08:41 INFO - PROCESS | 1685 | Values: 195.0 199.0 195.0 196.0 194.0 251.0 202.0 208.0 219.0 218.0 03:08:41 INFO - PROCESS | 1685 | 03:08:41 INFO - PROCESS | 1685 | [#3] audio-fft Cycles:10 Average:85.70 Median:74.50 stddev:29.48 (39.6%) stddev-sans-first:30.10 03:08:41 INFO - PROCESS | 1685 | Values: 63.0 76.0 73.0 150.0 65.0 80.0 80.0 84.0 127.0 59.0 03:08:41 INFO - PROCESS | 1685 | 03:08:41 INFO - PROCESS | 1685 | [#4] audio-oscillator Cycles:10 Average:104.30 Median:104.00 stddev:24.28 (23.3%) stddev-sans-first:23.81 03:08:41 INFO - PROCESS | 1685 | Values: 78.0 82.0 126.0 93.0 114.0 65.0 143.0 119.0 117.0 106.0 03:08:41 INFO - PROCESS | 1685 | 03:08:41 INFO - PROCESS | 1685 | [#5] imaging-gaussian-blur Cycles:10 Average:102.50 Median:89.00 stddev:18.06 (20.3%) stddev-sans-first:18.58 03:08:41 INFO - PROCESS | 1685 | Values: 90.0 88.0 95.0 129.0 90.0 87.0 91.0 115.0 136.0 104.0 03:08:41 INFO - PROCESS | 1685 | 03:08:41 INFO - PROCESS | 1685 | [#6] imaging-darkroom Cycles:10 Average:118.00 Median:149.50 stddev:24.20 (16.2%) stddev-sans-first:24.20 03:08:41 INFO - PROCESS | 1685 | Values: 95.0 100.0 142.0 148.0 107.0 141.0 151.0 96.0 94.0 106.0 03:08:41 INFO - PROCESS | 1685 | 03:08:41 INFO - PROCESS | 1685 | [#7] imaging-desaturate Cycles:10 Average:112.00 Median:137.00 stddev:25.21 (18.4%) stddev-sans-first:23.93 03:08:41 INFO - PROCESS | 1685 | Values: 144.0 130.0 125.0 101.0 150.0 111.0 76.0 78.0 104.0 101.0 03:08:41 INFO - PROCESS | 1685 | 03:08:41 INFO - PROCESS | 1685 | [#8] json-parse-financial Cycles:10 Average:79.20 Median:77.00 stddev:15.51 (20.1%) stddev-sans-first:14.99 03:08:41 INFO - PROCESS | 1685 | Values: 61.0 64.0 83.0 100.0 103.0 80.0 57.0 86.0 74.0 84.0 03:08:41 INFO - PROCESS | 1685 | 03:08:41 INFO - PROCESS | 1685 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:73.00 stddev:4.38 (6.0%) stddev-sans-first:4.55 03:08:41 INFO - PROCESS | 1685 | Values: 74.0 77.0 70.0 78.0 70.0 74.0 72.0 64.0 67.0 69.0 03:08:41 INFO - PROCESS | 1685 | 03:08:41 INFO - PROCESS | 1685 | [#10] stanford-crypto-aes Cycles:10 Average:94.50 Median:75.50 stddev:11.81 (15.6%) stddev-sans-first:12.51 03:08:41 INFO - PROCESS | 1685 | Values: 96.0 93.0 107.0 77.0 86.0 103.0 104.0 74.0 101.0 104.0 03:08:41 INFO - PROCESS | 1685 | 03:08:41 INFO - PROCESS | 1685 | [#11] stanford-crypto-ccm Cycles:10 Average:145.80 Median:148.00 stddev:15.40 (10.4%) stddev-sans-first:16.29 03:08:41 INFO - PROCESS | 1685 | Values: 149.0 147.0 129.0 129.0 133.0 142.0 152.0 151.0 182.0 144.0 03:08:41 INFO - PROCESS | 1685 | 03:08:41 INFO - PROCESS | 1685 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.40 Median:180.50 stddev:25.65 (14.2%) stddev-sans-first:27.15 03:08:41 INFO - PROCESS | 1685 | Values: 190.0 244.0 175.0 170.0 169.0 166.0 169.0 169.0 216.0 186.0 03:08:41 INFO - PROCESS | 1685 | 03:08:41 INFO - PROCESS | 1685 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:81.30 Median:81.00 stddev:16.05 (19.8%) stddev-sans-first:16.04 03:08:41 INFO - PROCESS | 1685 | Values: 66.0 89.0 73.0 57.0 97.0 99.0 101.0 92.0 66.0 73.0 03:08:41 INFO - PROCESS | 1685 | -------- Summary: end -------- 03:08:41 INFO - PROCESS | 1685 | 03:08:42 INFO - TEST-INFO | 1685: exit 0 03:08:42 INFO - TEST-OK | kraken | took 60799ms 03:08:42 INFO - SUITE-END | took 1173s 03:08:43 INFO - Completed test suite (00:19:33) 03:08:43 INFO - javascript benchmark 03:08:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2784.67976182739, 3506.1409954561136, 3517.3317069756745, 3522.6125126236093, 3576.8748093816707, 5786.537077033832, 5800.941990580093, 5806.4483874032, 5828.0675091985295, 5852.590318677694, 5793.652380857152, 5794.565407594426, 5817.098349755784, 5824.126381042845, 5862.32583252926, 5213.400458947222, 5285.814996475124, 5287.973560132194, 5306.628536002481, 5315.521603055724, 5142.280080788674, 5273.235380869772, 5289.074411977907, 5293.0, 5336.519713225809, 4351.173277077361, 5218.8695282617855, 5250.868728281805, 5258.815941442051, 5262.0265250928505, 5183.922241166367, 5202.791888324463, 5252.264682944391, 5273.288106105665, 5340.279062326575, 4338.284183109783, 5152.458991805881, 5235.162374020177, 5314.256004159421, 5318.282031925718, 5675.156509709058, 5741.138829175616, 5741.827745167651, 5776.451237132465, 5826.941730582725, 6073.757049718006, 6191.102290167901, 6195.853767053064, 6223.937760622381, 6291.370862913672, 5231.790728370861, 6284.434400903895, 6292.119103325538, 6294.3705629437145, 6307.148534947769, 5843.590948633598, 5888.263967004124, 5889.381614930457, 5904.291485021782, 5916.5858389913155, 6197.411245931629, 6278.497720182466, 6289.465395441439, 6299.244090709144, 6302.558820882586, 6272.686365681698, 6273.435390814758, 6287.46556542699, 6297.622142671363, 6304.842378940563, 6260.186175797117, 6271.43557079865, 6278.937210627927, 6280.05799130125, 6280.654563998933, 6187.566870319124, 6266.24805023391, 6277.905831412532, 6282.811515654537, 6286.0, 6260.593061450991, 6280.905786413207, 6291.245050593958, 6298.9921612541775, 6311.368863113744, 4768.736284884509, 4927.0, 5001.223342371108, 5008.29883816266, 5063.87340316495, 3999.8800035998966, 4011.578784227614, 4325.632321252666, 4526.932096018562, 4540.02389486262, 5995.400459953969, 6036.547258955596, 6044.184035155197, 6077.301110372276, 6079.969600151971, 5613.540479475366, 5956.106584012433, 6201.162843016135, 6222.377762223741, 6269.7492100316385, 5833.212516310328, 5950.4049595041015, 5969.522438204933, 6037.335893051761, 6076.5442591805795, 5466.508014278734, 5529.949309631157, 5532.972335138298, 5535.446455354513, 5561.972190139023, 6335.239771227482, 6412.903806442814, 6494.9675251624385, 6507.804765857032, 6508.479321654351, 6210.037444196157, 6259.874802503833, 6275.34108918557, 6281.811545653638, 6295.433410993032, 6496.642684652389, 6512.120863683342, 6517.478601711947, 6520.18497687789, 6542.051402546699, 6145.231846019247, 6165.0, 6261.279952805486, 6272.874542509033, 6274.058891166362, 5420.83737487876, 6091.969540152271, 6235.251769787655, 6264.874702506016, 6265.8120256392385, 6174.8147555573405, 6228.532173116534, 6234.438900498977, 6246.312905580292, 6252.312245652884, 6078.057901025348, 6131.049687298476, 6147.66187859672, 6233.0, 6240.126382306572, 4686.250199967989, 5530.640508367104, 6000.729967151576, 6110.663913484623, 6118.898598252357, 6235.594686345373, 6240.469560087444, 6249.718762655601, 6249.781257656142, 6259.34276900937, 5931.228940237742, 6030.5778595497895, 6031.24609423822, 6054.66699331541, 6111.480524155497], "value": 5741.774065523254, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [544.3930018029897, 562.4768964862676, 568.127923637217, 589.101620029455, 614.9508039356851, 31435.68564314359, 40275.798621006856, 40285.59714402859, 40315.395269070985, 40394.7980260099, 41670.58329416705, 42606.57393426065, 42934.92662683427, 43289.35065974013, 43593.692189234294, 9495.857562136573, 9616.182624476927, 9642.855357169648, 9736.853947190797, 9749.512524373788, 9673.903260967405, 9676.806463870718, 9767.853482197772, 9810.215182785396, 9842.60629574816, 9758.414495130279, 9779.853302200472, 9820.901790982105, 9836.557354919047, 9847.556859941284, 9740.610375585004, 9798.0, 9808.264380171515, 9819.3126481146, 9880.802383952352, 9553.856692149624, 9601.711948641552, 9692.176165025981, 9693.41839489633, 9711.223102151811, 40281.395779063045, 40527.59472405268, 40737.0, 40776.0, 40819.0, 2794.804363694542, 3185.9681403185905, 3266.7059964603063, 3301.257217126127, 3321.4187517184637, 26788.46423071547, 27116.0, 27135.0, 27272.0, 27731.58402623942, 2711.8237314574685, 2751.1883994221753, 2770.141256210581, 2771.7921155913386, 2802.929926751827, 343358.0, 344375.0, 344425.0, 345486.0, 347721.0, 66063.0, 66339.66830165818, 66667.33330666817, 66701.33298666951, 66816.0, 73377.0, 74872.25127748762, 75062.62468687621, 75174.62412687902, 75200.24799752042, 17036.403725869535, 17068.317267309416, 17624.647507050042, 17713.380031698827, 17849.10754462272, 13249.0, 13323.733525329439, 13368.532101376406, 13391.531296404579, 13413.0610857241, 20341.491462713253, 20593.38219853407, 21046.47383815417, 21111.68332475014, 21185.046672899756, 7024.824379390556, 7955.920440795518, 8224.958875205586, 8283.461574997606, 8323.375746818892, 769.54981335918, 833.3166803221421, 982.2142286171035, 1077.472038701031, 1144.2905398652722, 23341.41646458852, 30539.08382748521, 30602.84698576493, 31111.68888311088, 31677.683223167933, 1038.8026275007724, 1083.8049151152711, 1087.2280680716622, 1168.9123315751353, 1190.4880901212389, 13321.200727956353, 15858.207089645703, 15870.603234918988, 15874.841251587568, 16217.189140542925, 416490.0, 508567.0, 545103.0, 547059.0, 547306.0, 61753.0, 62750.0587491188, 63971.04043439352, 64008.67995659992, 64081.0, 1473.6536913825457, 1474.0, 1475.0, 1494.1782019889017, 1506.314626844783, 30231.546526801674, 30476.0, 30624.800574345743, 30748.0, 30759.923402680797, 56122.71938640444, 56447.71776141093, 56571.434285658266, 56615.58461038507, 56718.43281567297, 484841.0, 487692.0, 490314.0, 490655.0, 493476.0, 27992.580111298754, 28059.71940280571, 28215.871365145165, 28473.145805625863, 28486.857565712868, 47667.523324766305, 48055.75972120117, 49243.0, 49678.503214967386, 49916.0, 36774.44838327374, 36880.0, 37212.0, 37490.43764343482, 37564.81217594004, 30208.5468717965, 36130.63869361273, 36596.81701591475, 36865.63134368622, 36950.26099478049, 32653.18367040843, 35973.92078237657, 36583.81708091443, 37005.629943700216, 37058.629413705516, 30984.690153099084, 31746.365072697954, 31880.0, 31908.680913190572, 32303.51544726878, 24644.137455188982, 24694.0, 24841.006359745406, 24850.627240591766, 25009.374765631004], "value": 19872.591856481573, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.34201199972046, 393.5218709268242, 393.8306528192877, 401.8754186202278, 405.88838069530874, 14888.702225955501, 15797.973131746428, 20476.795232047676, 21195.0, 21203.151873925064, 16609.91695041526, 16806.15969201541, 20098.799012009913, 20218.595628087467, 21105.894470527663, 5961.940380596193, 6148.754049838023, 6149.631022138664, 6153.538484613649, 6163.784267550636, 5845.0, 5958.8808223835495, 6123.908141377883, 6139.846503837395, 6147.170132032185, 6100.603460775058, 6144.44699977002, 6153.7230824613, 6161.815145545641, 6185.381461853801, 5804.651720896739, 5948.73230704615, 6133.570650054498, 6153.876922461525, 6193.009118541035, 5845.327787304472, 5935.228420305376, 5962.791302304442, 5979.1629171915965, 6003.399660034005, 13490.595282141552, 13504.0, 13830.03189776706, 13840.238786866717, 13854.584362469142, 2189.8467107302495, 2192.4190089626263, 2219.7780221977673, 2396.412878844677, 2756.3935934094466, 9858.211343092535, 10346.22403319754, 10372.481375931173, 13165.0, 14523.273836308248, 1594.4260066376096, 1605.9759103613455, 1771.9822801771945, 1800.6668766278149, 1901.7812951510616, 112932.0, 113765.43117284443, 114576.0, 116979.99999999916, 122266.38866805698, 10094.646687365906, 11364.715882102848, 11984.700382490508, 11999.220050696676, 12183.756324873453, 15202.923985380003, 15307.846921530865, 15503.922480387526, 15524.0, 15581.922090389475, 8877.067907869581, 9031.36780425377, 9040.09599040104, 9131.26949844011, 9157.542122893828, 7340.926590734131, 8931.374803763803, 8978.551072446351, 9066.410683305565, 9178.954105229432, 3873.457715919717, 4764.3086704220195, 4796.35249241348, 4845.830395936126, 4866.8783280417565, 1654.925528351227, 1968.9015549222768, 2022.4337185588058, 2025.534127150734, 2030.218365929128, 942.1002942190192, 978.0561757903515, 978.5596481583316, 985.7486340983004, 1004.9648262310784, 19335.709964350546, 20040.0, 20484.79515204829, 20600.896995514926, 20849.478763031046, 984.640606178754, 991.1525645573075, 998.6205242008135, 1004.1163775877327, 1039.8821267137705, 10787.78424431507, 10908.781824363627, 10911.945440272748, 10930.180236482296, 10996.560137594566, 622655.0, 623374.0, 624908.0, 624917.0, 635529.0, 99653.0, 118197.22704159445, 119575.0, 120327.0, 123767.38116309361, 986.1124987511154, 994.7413672445216, 1001.1590264178126, 1008.1682611845257, 1008.2992320337441, 17874.28502859871, 18149.183286751864, 18261.269549217883, 18315.17581708853, 18966.715499267797, 52531.21203181879, 52742.0, 53459.198112027574, 53601.0, 55164.0, 52147.47852521578, 57065.0, 72581.0, 78477.0, 80348.0, 8158.740937506203, 8177.877331840146, 8218.424710270223, 8405.915940840514, 8442.70450534253, 10637.627683031056, 11337.206395552232, 12605.369731513205, 12623.242605443702, 12632.810507842207, 10601.310914790514, 10948.288361256493, 11076.501557430096, 11102.389368584805, 11140.665780026613, 9350.298727595322, 10686.946565267124, 10749.785004300027, 10872.0, 11177.385243811343, 11048.729396119548, 11113.611023614134, 11301.71745706364, 11342.829857552308, 11424.48589813477, 6935.410490108397, 8048.5170889746805, 8189.058258300133, 8304.87542686848, 8501.19238672325, 10234.48827558634, 11268.887311126784, 11420.600278990527, 11422.0, 11622.94188529052], "value": 9457.324811685427, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1382.854800245974, 1584.112896777804, 1769.654917291128, 1877.6526342626614, 1948.5420926082377, 8037.27664510194, 8639.265662418702, 8744.737657870266, 8982.595783189749, 9016.053314401992, 8758.518281494515, 8760.211580957712, 8871.423357481775, 8883.60023798929, 8885.333599980011, 2044.2027609232375, 2426.1144682191048, 2455.312512496504, 2464.2360868130845, 2467.1858286765414, 2417.6736140620965, 2427.987860060697, 2480.776730094291, 2484.776370126688, 2488.191337815208, 2437.3541011631933, 2438.37821355555, 2462.507498500298, 2476.950460990788, 2502.324372419446, 2190.5290362572046, 2214.2139540463063, 2402.7745849616736, 2478.08310924958, 2498.5127900059497, 1920.2894928876406, 2100.8865301390288, 2457.9016839326405, 2474.0227610094094, 2484.0312278211513, 8712.215900568915, 8895.110488951053, 9046.457212567202, 9056.275497960214, 9078.455292682394, 4957.6033917286895, 5209.82778874754, 5431.321084864392, 5494.2857428534035, 5541.806036788734, 3547.8758243461357, 3637.2725454909123, 3641.4537819327047, 3679.4848721179055, 3686.391745362012, 1053.9894601054045, 1071.2982996137541, 1071.9624813131502, 1075.317173594765, 1076.0638244727058, 12166.391680415943, 12283.877161228364, 12308.876911230953, 12329.260244385365, 12337.321447320393, 4825.396825396825, 4843.878903027382, 4922.729249891218, 4930.950690493121, 4931.90136197274, 10231.181505479544, 10275.02387273208, 10277.74305642365, 10280.485975701185, 10297.07326340633, 5439.864003399867, 5441.183822426668, 5518.613697041249, 5522.447755224445, 5525.364583072917, 4429.844955426544, 4894.216925291937, 4993.375828021497, 5005.0, 5051.646384753032, 3439.7420193485586, 4066.9186616268103, 4129.056562622365, 4155.649413940481, 4164.042270277853, 1179.887910648487, 1254.4166962362544, 1270.5489551209373, 1348.6493511686836, 1348.676317683749, 893.65147592439, 915.1717695485596, 933.1881263300971, 936.3164889630608, 938.2775263047415, 5938.940610593925, 5981.043033114681, 5997.640141591519, 6160.568760186834, 6241.469475094577, 460.75349687917475, 480.28437627934125, 1001.7796084861319, 1084.7776205877776, 1102.4267380961855, 3548.716102711777, 3638.9810852961214, 3872.186840763471, 3983.462232598562, 4000.779762172507, 10415.270931034906, 10691.625793097204, 10794.676159715222, 10798.892011079946, 10845.240833141605, 10513.57945682164, 10517.474126293655, 10524.421156836448, 10663.093637040938, 10706.946465267623, 7948.602569871599, 8325.916740832636, 8345.082040975487, 8393.706220282262, 8451.45065570736, 5875.559333049953, 5884.529237661062, 5950.642961422329, 6015.909761353496, 6104.908426373697, 9456.479893605918, 9458.243340532603, 9578.760530986781, 9665.613375464902, 9682.854757178507, 9520.047995200424, 9703.757406064904, 9721.4653194075, 9734.36726612768, 9808.65669701557, 6647.900281495685, 6788.66056697173, 7321.304476074764, 7379.372753316028, 7585.16563178061, 7858.174891636296, 8253.257206851413, 8284.46151000183, 8319.625616847135, 8325.25072743456, 7226.530275532074, 7356.852862942604, 7361.631918404166, 7444.776656700307, 7489.775306740807, 7148.17795953472, 7174.282571742784, 7198.676059577437, 7266.164391095092, 7313.780586582411, 6827.0, 6840.657967101525, 6867.313268673092, 6894.586324820527, 6897.206821215625, 4872.634552408655, 4872.926906096341, 4884.462709102067, 4969.35398398206, 4991.351124353956, 4272.700910936329, 4599.126166028444, 4926.4088309403105, 4985.401751789808, 5073.568746656576], "value": 4602.572051737703, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.0566745104295, 328.8881780194734, 332.88848235840993, 335.0735217124649, 337.8732975134325, 5242.636914402259, 6656.833579160523, 6663.933360666392, 6729.125213722209, 6765.154355705537, 6675.165604299463, 6682.431993280565, 6686.197656281241, 6699.665016749167, 6715.966420167904, 4004.439378487014, 4531.52418996004, 4859.343988561552, 4860.781264843091, 4884.804607815683, 4820.879478013043, 4859.951400485986, 4878.292647566101, 4889.144399730051, 4903.0929278083495, 4845.975770121162, 4847.927281090787, 4849.709017458946, 4863.756812159396, 4876.244182151772, 4879.146149423872, 4882.926756098661, 4884.340614017097, 4888.217885138396, 4934.753262336869, 4189.224993376235, 4192.517860446057, 4195.937060944088, 4202.85290014851, 4215.59951804578, 6078.544109191828, 6289.7169627366875, 6339.397757213057, 6396.232452105731, 6400.135981642466, 4607.35497030416, 5648.237487939158, 5666.1784041314195, 5695.487406133427, 5710.343310519302, 7183.060573645086, 7189.676464559107, 7189.748358807469, 7223.89164162538, 7225.45809064317, 3843.442700808395, 4545.568171023748, 4613.953860461386, 4626.583607475343, 4647.930281045787, 7502.924970750277, 7568.810779730551, 7578.507397019176, 7584.0, 7617.771466856003, 3800.7149463790324, 3957.22834047358, 4675.696079754805, 4850.684705494202, 4859.319695242669, 4888.437829649636, 4950.158473059589, 4958.57852082577, 4967.105920934267, 4969.105560999054, 4680.578747912704, 5795.797147099831, 5837.445442682938, 5848.941510584839, 5876.177335173079, 7238.384737297389, 7240.601766902765, 7252.637368131573, 7255.818604534823, 7264.709411623476, 1720.4494561740373, 1747.0827815396765, 1748.5278974676767, 1755.1926113987454, 1756.5608597850537, 878.5695009445326, 1113.3653817324048, 1126.8140756775203, 1128.1651577832345, 1140.925839820409, 6727.831804204836, 6753.3246675332075, 6772.830679233059, 6802.285759995229, 6804.897926531106, 5996.910046349309, 7250.528715633573, 7497.850042999218, 7521.135069466972, 7527.661255243418, 6678.899816502756, 6820.454363650896, 6845.14435695538, 6848.589084654936, 6889.276625954303, 3929.941050884239, 3939.03493644059, 3970.7419017763755, 3971.0, 3992.6007399259843, 6355.745770169141, 7917.524948503108, 7922.405819563556, 7926.365890728728, 7942.0, 7569.621518924142, 7812.335951444077, 7820.530768153929, 7837.333826624801, 7843.529388236724, 7882.132965373919, 7882.487638303606, 7914.010748656418, 7921.524708517507, 7957.1644977276555, 5987.790427335022, 7545.283198096172, 7549.396048316232, 7566.697332106654, 7570.735024274344, 7427.368673662799, 7430.071241094864, 7435.107787065586, 7444.776656700307, 7456.067991501062, 6231.563790534619, 7421.8144546386775, 7882.645280962256, 7895.368370530229, 7939.960300198462, 4859.1496488113735, 4898.559129678346, 4903.26451032348, 4908.631852610997, 4921.163402221559, 6019.668918209541, 7456.440766942609, 7468.775936721907, 7502.0, 7514.586697731677, 7214.350708436266, 7215.665101956155, 7232.45756568249, 7236.73357162505, 7240.855182896418, 6902.585844849325, 7190.316919892602, 7199.2440793715905, 7264.636768161677, 7275.236100209613, 5777.422257774189, 6956.7130080935185, 7065.364117229474, 7067.363937245673, 7074.186468556182, 7193.748218812317, 7225.963870180615, 7226.241244669234, 7229.421646268181, 7233.855322893407, 7019.543729657556, 7064.540804847874, 7065.823354415975, 7077.504574679929, 7101.041359416619], "value": 5223.463069679643, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [658.4666420199636, 675.0549231076492, 676.3811112831759, 694.600604652325, 743.5018239184636, 14295.356708948097, 14424.423023079078, 14462.987590868643, 14664.266786660664, 14665.04677195983, 13335.466581336736, 13341.399637016333, 13372.0, 13386.933065334684, 15098.396064157421, 921.5208091792264, 937.6296362936636, 940.5062342270329, 940.7930255343813, 942.3309450290302, 914.6981496106268, 929.6513807322254, 930.418488444722, 932.3753085432743, 935.7567032571512, 939.1031564855581, 940.952952352383, 948.2366694810709, 953.1897886796203, 976.843705007199, 933.9906600933972, 934.7803266232464, 954.312894715807, 954.4082668745353, 975.1759763000254, 701.382783150824, 705.6824429006916, 720.121451828768, 721.1562471907828, 726.4696771356939, 14668.339924703414, 14810.037347572374, 14829.480968166063, 14842.628934276729, 14873.47942821996, 12306.569270075594, 12339.568115116017, 12405.317707526083, 12541.122121451503, 12604.684882878002, 9922.553485093189, 10105.595776168944, 10218.693439196835, 10231.283810133295, 10249.590016399336, 3192.680731926812, 3326.750493712957, 3469.60099588548, 3535.805530695811, 3686.7234957378037, 79536.40927181482, 80054.19810306611, 82483.0, 82670.9999999994, 82775.0, 7605.733799316998, 9244.3991140577, 9470.052994700474, 9476.194523465445, 9531.428114313163, 20806.479838004172, 20920.37238882836, 20954.0, 20983.37049888506, 21005.68491472629, 7101.396381307647, 7104.68028938699, 7191.7842464726145, 7231.0, 7320.1581818090535, 3994.46074779901, 4023.6982226333143, 4094.6314831665295, 4116.259073366823, 4124.711270211116, 3571.8749843755345, 3605.152789094561, 3633.7274704397278, 3634.4548317752547, 3640.2173532690067, 632.3739497897049, 636.6721138613618, 637.6046850952439, 642.1651852591706, 646.8576913079115, 577.2784019975031, 580.8199458167982, 588.5379976718341, 594.649156997365, 595.3570144244206, 7785.221477852169, 8061.838763224702, 8127.837443251102, 8210.261076503024, 8541.103184165697, 498.66091058080127, 575.6114622629708, 584.8917950179231, 587.4918195760637, 615.0835255469408, 3713.665770080706, 3863.430818505662, 3894.766314021168, 3895.922081558353, 3937.310970580137, 85308.72036919452, 86430.0, 91018.63472047924, 92632.0, 92798.0720192803, 74196.0, 74299.0, 74493.0, 75498.62250688822, 76675.61662191653, 66219.34451638747, 66223.33776662171, 67159.31362745533, 67366.0, 67991.0, 7673.0, 7735.303822655988, 7765.41759368061, 7849.097353804385, 7862.0, 47069.29396058993, 48900.266496001874, 49463.25805112854, 49477.01045979132, 50647.0, 60533.39466605454, 61500.0, 64460.35539644543, 64831.0, 65579.0, 10572.682819515427, 10578.8413173804, 10628.043476087189, 10837.295575787548, 11127.0, 9148.222401095714, 11805.055595552163, 11910.166288359731, 12092.697682558006, 12382.0, 11174.49714762854, 11333.26333788301, 11446.0, 11489.482973266056, 11531.827022594836, 11377.975982161644, 11456.541738330374, 11491.310521368745, 11657.591984280509, 12057.879421205676, 9828.4102953823, 10279.588816447258, 10730.62442814529, 11006.394648294421, 11175.720606984565, 9362.859557106785, 9389.671361502315, 9410.058994100535, 9534.52327383642, 9553.856692149484, 9326.860097098413, 9444.244460443011, 9594.376365536218, 9599.42403455795, 9645.9035409645], "value": 6801.225760347965, "unit": "score"}], "name": "dromaeo_css", "value": 7489.313502435486, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 117.0, 108.0, 118.0, 131.0, 171.0, 156.0, 162.0, 117.0, 116.0], "value": 131.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 157.0, 142.0, 161.0, 120.0, 139.0, 124.0, 164.0, 152.0, 149.0], "value": 149.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 199.0, 195.0, 196.0, 194.0, 251.0, 202.0, 208.0, 219.0, 218.0], "value": 207.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 76.0, 73.0, 150.0, 65.0, 80.0, 80.0, 84.0, 127.0, 59.0], "value": 85.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 82.0, 126.0, 93.0, 114.0, 65.0, 143.0, 119.0, 117.0, 106.0], "value": 104.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 95.0, 129.0, 90.0, 87.0, 91.0, 115.0, 136.0, 104.0], "value": 102.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 100.0, 142.0, 148.0, 107.0, 141.0, 151.0, 96.0, 94.0, 106.0], "value": 118.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 130.0, 125.0, 101.0, 150.0, 111.0, 76.0, 78.0, 104.0, 101.0], "value": 112.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 83.0, 100.0, 103.0, 80.0, 57.0, 86.0, 74.0, 84.0], "value": 79.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 70.0, 78.0, 70.0, 74.0, 72.0, 64.0, 67.0, 69.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 93.0, 107.0, 77.0, 86.0, 103.0, 104.0, 74.0, 101.0, 104.0], "value": 94.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 147.0, 129.0, 129.0, 133.0, 142.0, 152.0, 151.0, 182.0, 144.0], "value": 145.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 244.0, 175.0, 170.0, 169.0, 166.0, 169.0, 169.0, 216.0, 186.0], "value": 185.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 89.0, 73.0, 57.0, 97.0, 99.0, 101.0, 92.0, 66.0, 73.0], "value": 81.3, "unit": "score"}], "name": "kraken", "value": 1668.8, "alertThreshold": 2.0}]} 03:08:43 INFO - Return code: 0 03:08:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:08:43 ERROR - # TBPL SUCCESS # 03:08:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:08:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:08:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:08:43 INFO - 2016-05-16 03:08:43.230 screenresolution[1690:11903] starting screenresolution argv=screenresolution get 03:08:43 INFO - 2016-05-16 03:08:43.232 screenresolution[1690:11903] Display 0: 1600x1200x32@60 03:08:43 INFO - 2016-05-16 03:08:43.238 screenresolution[1691:11906] starting screenresolution argv=screenresolution list 03:08:43 INFO - Available Modes on Display 0 03:08:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:08:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:08:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:08:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:08:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:08:43 INFO - Intel Iris: 03:08:43 INFO - Chipset Model: Intel Iris 03:08:43 INFO - Type: GPU 03:08:43 INFO - Bus: Built-In 03:08:43 INFO - VRAM (Dynamic, Max): 1536 MB 03:08:43 INFO - Vendor: Intel (0x8086) 03:08:43 INFO - Device ID: 0x0a2e 03:08:43 INFO - Revision ID: 0x0009 03:08:43 INFO - Displays: 03:08:43 INFO - Display: 03:08:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:08:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:08:43 INFO - Display Serial Number: 1600x1200 60 03:08:43 INFO - Main Display: Yes 03:08:43 INFO - Mirror: Off 03:08:43 INFO - Online: Yes 03:08:43 INFO - Rotation: Supported 03:08:43 INFO - Return code: 0 03:08:43 INFO - Running post-action listener: _resource_record_post_action 03:08:43 INFO - Running post-run listener: _resource_record_post_run 03:08:44 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 32448000; Write bytes: 656258560; Read time: 320; Write time: 2033 03:08:44 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 346696192; Write bytes: 346331136; Read time: 26854; Write time: 668 03:08:44 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 30682624; Write bytes: 299138560; Read time: 302; Write time: 1337 03:08:44 INFO - Running post-run listener: _upload_blobber_files 03:08:44 INFO - Blob upload gear active. 03:08:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:08:44 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:08:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:08:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:08:44 INFO - (blobuploader) - INFO - Open directory for files ... 03:08:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:08:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:08:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:08:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:08:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:08:45 INFO - (blobuploader) - INFO - Done attempting. 03:08:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:08:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:08:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:08:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:08:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:08:46 INFO - (blobuploader) - INFO - Done attempting. 03:08:46 INFO - (blobuploader) - INFO - Iteration through files over. 03:08:46 INFO - Return code: 0 03:08:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:08:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:08:46 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/528bfdb3a8e44ba3338e93c03f3ec8bb493452ba2f1a2448ef12a4af80cca8c87cda4130d050e19c2553e20e1e0fd9466013feaa945d8509e25894254524f710", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8ea36bbe4860867aa4df9d7f792191c0a8ecb2d01e78df0e2c582bc32fecca522d88cd0840246a884c4aeacc413c7c9d1218435455146265bba3b62f4735c5ef"} 03:08:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:08:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:08:46 INFO - Contents: 03:08:46 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/528bfdb3a8e44ba3338e93c03f3ec8bb493452ba2f1a2448ef12a4af80cca8c87cda4130d050e19c2553e20e1e0fd9466013feaa945d8509e25894254524f710", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8ea36bbe4860867aa4df9d7f792191c0a8ecb2d01e78df0e2c582bc32fecca522d88cd0840246a884c4aeacc413c7c9d1218435455146265bba3b62f4735c5ef"} 03:08:46 INFO - Running post-run listener: copy_logs_to_upload_dir 03:08:46 INFO - Copying logs to upload dir... 03:08:46 INFO - mkdir: /builds/slave/test/build/upload/logs 03:08:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1323.276138 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 3 secs) (at 2016-05-16 03:08:46.709605) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 03:08:46.713783) ========= 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.KnW6RfeADG/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.LUVOeJMpmB/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/528bfdb3a8e44ba3338e93c03f3ec8bb493452ba2f1a2448ef12a4af80cca8c87cda4130d050e19c2553e20e1e0fd9466013feaa945d8509e25894254524f710", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8ea36bbe4860867aa4df9d7f792191c0a8ecb2d01e78df0e2c582bc32fecca522d88cd0840246a884c4aeacc413c7c9d1218435455146265bba3b62f4735c5ef"} build_url:https://queue.taskcluster.net/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010464 build_url: 'https://queue.taskcluster.net/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/528bfdb3a8e44ba3338e93c03f3ec8bb493452ba2f1a2448ef12a4af80cca8c87cda4130d050e19c2553e20e1e0fd9466013feaa945d8509e25894254524f710", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8ea36bbe4860867aa4df9d7f792191c0a8ecb2d01e78df0e2c582bc32fecca522d88cd0840246a884c4aeacc413c7c9d1218435455146265bba3b62f4735c5ef"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 03:08:46.747809) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 03:08:46.748370) ========= 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.KnW6RfeADG/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.LUVOeJMpmB/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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.004966 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 03:08:46.799823) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 03:08:46.800405) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 03:08:46.800999) ========= ========= Total master_lag: 0.18 =========