builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0350 starttime: 1463305993.83 results: success (0) buildid: 20160515004019 builduid: 642a012a63264eaa81a12f0a5a7a60eb revision: f00f9e754ce71fc5efa3e4334afea5cf6ad6c215 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-15 02:53:13.831058) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-15 02:53:13.831513) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-15 02:53:13.831789) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-15 02:53:13.986592) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-15 02:53:13.986874) ========= 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.mdxxC485bu/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.Tr2SNcNOtE/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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-15 02:53:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 20.2M=0.001s 2016-05-15 02:53:14 (20.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.616690 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-15 02:53:14.623363) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-15 02:53:14.623682) ========= 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.mdxxC485bu/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.Tr2SNcNOtE/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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.025853 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-15 02:53:14.672099) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-15 02:53:14.672419) ========= 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.mdxxC485bu/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.Tr2SNcNOtE/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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-15 02:53:14,692 truncating revision to first 12 chars 2016-05-15 02:53:14,692 Setting DEBUG logging. 2016-05-15 02:53:14,692 attempt 1/10 2016-05-15 02:53:14,692 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-15 02:53:15,836 unpacking tar archive at: mozilla-aurora-f00f9e754ce7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.392297 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-15 02:53:16.081270) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-15 02:53:16.081562) ========= script_repo_revision: f00f9e754ce71fc5efa3e4334afea5cf6ad6c215 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-15 02:53:16.082359) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-15 02:53:16.082625) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-15 02:53:16.099254) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-05-15 02:53:16.099518) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mdxxC485bu/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.Tr2SNcNOtE/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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:53:16 INFO - MultiFileLogger online at 20160515 02:53:16 in /builds/slave/test 02:53:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:53:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:53:16 INFO - {'append_to_log': False, 02:53:16 INFO - 'base_work_dir': '/builds/slave/test', 02:53:16 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:53:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:53:16 INFO - 'branch': 'Mozilla-Aurora', 02:53:16 INFO - 'buildbot_json_path': 'buildprops.json', 02:53:16 INFO - 'config_files': ('talos/mac_config.py',), 02:53:16 INFO - 'default_actions': ('clobber', 02:53:16 INFO - 'read-buildbot-config', 02:53:16 INFO - 'download-and-extract', 02:53:16 INFO - 'populate-webroot', 02:53:16 INFO - 'create-virtualenv', 02:53:16 INFO - 'install', 02:53:16 INFO - 'run-tests'), 02:53:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:53:16 INFO - 'download_minidump_stackwalk': True, 02:53:16 INFO - 'download_symbols': 'ondemand', 02:53:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:53:16 INFO - 'tooltool.py': '/tools/tooltool.py', 02:53:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:53:16 INFO - '/tools/misc-python/virtualenv.py')}, 02:53:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:53:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:53:16 INFO - 'installer_path': 'installer.exe', 02:53:16 INFO - 'log_level': 'info', 02:53:16 INFO - 'log_name': 'talos', 02:53:16 INFO - 'log_to_console': True, 02:53:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:53:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:53:16 INFO - 'opt_config_files': (), 02:53:16 INFO - 'pip_index': False, 02:53:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:53:16 INFO - 'cmd': ('bash', 02:53:16 INFO - '-c', 02:53:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:53:16 INFO - 'enabled': True, 02:53:16 INFO - 'halt_on_failure': False, 02:53:16 INFO - 'name': 'check_screen_resolution'},), 02:53:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:53:16 INFO - 'cmd': ('bash', 02:53:16 INFO - '-c', 02:53:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:53:16 INFO - 'enabled': True, 02:53:16 INFO - 'halt_on_failure': False, 02:53:16 INFO - 'name': 'check_screen_resolution'},), 02:53:16 INFO - 'run_cmd_checks_enabled': True, 02:53:16 INFO - 'sps_profile': False, 02:53:16 INFO - 'sps_profile_interval': 0, 02:53:16 INFO - 'suite': 'dromaeojs', 02:53:16 INFO - 'system_bits': '32', 02:53:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:53:16 INFO - 'title': 't-yosemite-r7-0350', 02:53:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:53:16 INFO - 'use_talos_json': True, 02:53:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:53:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:53:16 INFO - 'work_dir': 'build'} 02:53:16 INFO - ##### 02:53:16 INFO - ##### Running clobber step. 02:53:16 INFO - ##### 02:53:16 INFO - Running pre-action listener: _resource_record_pre_action 02:53:16 INFO - Running main action method: clobber 02:53:16 INFO - rmtree: /builds/slave/test/build 02:53:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:53:17 INFO - Running post-action listener: _resource_record_post_action 02:53:17 INFO - ##### 02:53:17 INFO - ##### Running read-buildbot-config step. 02:53:17 INFO - ##### 02:53:17 INFO - Running pre-action listener: _resource_record_pre_action 02:53:17 INFO - Running main action method: read_buildbot_config 02:53:17 INFO - Using buildbot properties: 02:53:17 INFO - { 02:53:17 INFO - "project": "", 02:53:17 INFO - "product": "firefox", 02:53:17 INFO - "script_repo_revision": "production", 02:53:17 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:53:17 INFO - "repository": "", 02:53:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:53:17 INFO - "buildid": "20160515004019", 02:53:17 INFO - "stage_platform": "macosx64", 02:53:17 INFO - "basedir": "/builds/slave/test", 02:53:17 INFO - "nightly_build": "True", 02:53:17 INFO - "buildnumber": 342, 02:53:17 INFO - "slavename": "t-yosemite-r7-0350", 02:53:17 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 02:53:17 INFO - "platform": "yosemite_r7", 02:53:17 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:53:17 INFO - "branch": "mozilla-aurora", 02:53:17 INFO - "repo_path": "releases/mozilla-aurora", 02:53:17 INFO - "revision": "f00f9e754ce71fc5efa3e4334afea5cf6ad6c215", 02:53:17 INFO - "pgo_build": "False", 02:53:17 INFO - "builduid": "642a012a63264eaa81a12f0a5a7a60eb", 02:53:17 INFO - "slavebuilddir": "test" 02:53:17 INFO - } 02:53:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 02:53:17 INFO - Running post-action listener: _resource_record_post_action 02:53:17 INFO - ##### 02:53:17 INFO - ##### Running download-and-extract step. 02:53:17 INFO - ##### 02:53:17 INFO - Running pre-action listener: _resource_record_pre_action 02:53:17 INFO - Running main action method: download_and_extract 02:53:17 INFO - mkdir: /builds/slave/test/build/tests 02:53: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:53:17 INFO - https://queue.taskcluster.net/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:53:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:53:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:53:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/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:53:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/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:53:18 INFO - Downloaded 1199 bytes. 02:53:18 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:53:18 INFO - Using the following test package requirements: 02:53:18 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:53:18 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:53:18 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 02:53:18 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:53:18 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 02:53:18 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:53:18 INFO - u'jsshell-mac.zip'], 02:53:18 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:53:18 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 02:53:18 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:53:18 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:53:18 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 02:53:18 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:53:18 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 02:53:18 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:53:18 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 02:53:18 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:53:18 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 02:53:18 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:53:18 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:53:18 INFO - https://queue.taskcluster.net/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:53:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:53:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:53:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/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:53:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/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:53:21 INFO - Downloaded 18102283 bytes. 02:53:21 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:53:21 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:53:24 INFO - Return code: 0 02:53:24 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:53:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:53:24 INFO - https://queue.taskcluster.net/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:53:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:53:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:53:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/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:53:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/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:53:32 INFO - Downloaded 18102283 bytes. 02:53:32 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:53:32 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:53:35 INFO - Return code: 0 02:53:35 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:53:35 INFO - https://queue.taskcluster.net/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:53:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:53:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:53:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/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:53:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/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:53:36 INFO - Downloaded 10840145 bytes. 02:53:36 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:53:36 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:53:36 INFO - Return code: 0 02:53:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:53:36 INFO - https://queue.taskcluster.net/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 02:53:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:53:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:53:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 02:53:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/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:53:40 INFO - Downloaded 114150943 bytes. 02:53:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:53:40 INFO - mkdir: /builds/slave/test/properties 02:53:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:53:40 INFO - Writing to file /builds/slave/test/properties/build_url 02:53:40 INFO - Contents: 02:53:40 INFO - build_url:https://queue.taskcluster.net/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:53:41 INFO - Running post-action listener: _resource_record_post_action 02:53:41 INFO - Running post-action listener: set_extra_try_arguments 02:53:41 INFO - ##### 02:53:41 INFO - ##### Running populate-webroot step. 02:53:41 INFO - ##### 02:53:41 INFO - Running pre-action listener: _resource_record_pre_action 02:53:41 INFO - Running main action method: populate_webroot 02:53:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:53:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:53:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:53:41 INFO - '--tppagecycles', 02:53:41 INFO - '7'], 02:53:41 INFO - 'tests': ['tsvgm']}}, 02:53:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:53:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:53:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:53:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:53:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:53:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:53:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:53:41 INFO - 'talos_options': ['--disable-e10s'], 02:53:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:53:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:53:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:53:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:53:41 INFO - 'talos_options': ['--disable-e10s'], 02:53:41 INFO - 'tests': ['damp', 'tps']}, 02:53:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:53:41 INFO - 'tests': ['damp', 'tps']}, 02:53:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:53:41 INFO - 'tests': ['dromaeo_dom']}, 02:53:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:53:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:53:41 INFO - 'tests': ['a11yr', 02:53:41 INFO - 'ts_paint', 02:53:41 INFO - 'tpaint', 02:53:41 INFO - 'sessionrestore', 02:53:41 INFO - 'sessionrestore_no_auto_restore', 02:53:41 INFO - 'tabpaint']}, 02:53:41 INFO - 'other-e10s': {'tests': ['a11yr', 02:53:41 INFO - 'ts_paint', 02:53:41 INFO - 'tpaint', 02:53:41 INFO - 'sessionrestore', 02:53:41 INFO - 'sessionrestore_no_auto_restore', 02:53:41 INFO - 'tabpaint']}, 02:53:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:53:41 INFO - 'ts_paint', 02:53:41 INFO - 'tpaint', 02:53:41 INFO - 'sessionrestore', 02:53:41 INFO - 'sessionrestore_no_auto_restore', 02:53:41 INFO - 'tabpaint']}, 02:53:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:53:41 INFO - 'ts_paint', 02:53:41 INFO - 'tpaint', 02:53:41 INFO - 'sessionrestore', 02:53:41 INFO - 'sessionrestore_no_auto_restore', 02:53:41 INFO - 'tabpaint']}, 02:53:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:53:41 INFO - 'tests': ['a11yr', 02:53:41 INFO - 'ts_paint', 02:53:41 INFO - 'tpaint', 02:53:41 INFO - 'sessionrestore', 02:53:41 INFO - 'sessionrestore_no_auto_restore', 02:53:41 INFO - 'tabpaint']}, 02:53:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:53:41 INFO - 'tests': ['a11yr', 02:53:41 INFO - 'ts_paint', 02:53:41 INFO - 'tpaint', 02:53:41 INFO - 'sessionrestore', 02:53:41 INFO - 'sessionrestore_no_auto_restore', 02:53:41 INFO - 'tabpaint']}, 02:53:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:53:41 INFO - 'tests': ['tsvgx', 02:53:41 INFO - 'tsvgr_opacity', 02:53:41 INFO - 'tart', 02:53:41 INFO - 'tscrollx', 02:53:41 INFO - 'cart']}, 02:53:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:53:41 INFO - 'tsvgr_opacity', 02:53:41 INFO - 'tart', 02:53:41 INFO - 'tscrollx', 02:53:41 INFO - 'cart']}, 02:53:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:53:41 INFO - 'talos_options': ['--disable-e10s'], 02:53:41 INFO - 'tests': ['tp5o']}, 02:53:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:53:41 INFO - 'tests': ['tp5o']}, 02:53:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:53:41 INFO - 'talos_options': ['--disable-e10s', 02:53:41 INFO - '--xperf_path', 02:53:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:53:41 INFO - 'tests': ['tp5n']}, 02:53:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:53:41 INFO - 'talos_options': ['--xperf_path', 02:53:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:53:41 INFO - 'tests': ['tp5n']}}, 02:53:41 INFO - 'talos.zip': {'path': '', 02:53:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:53:41 INFO - Running post-action listener: _resource_record_post_action 02:53:41 INFO - ##### 02:53:41 INFO - ##### Running create-virtualenv step. 02:53:41 INFO - ##### 02:53:41 INFO - Running pre-action listener: _resource_record_pre_action 02:53:41 INFO - Running main action method: create_virtualenv 02:53:41 INFO - Creating virtualenv /builds/slave/test/build/venv 02:53:41 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:53:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:53:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:53:41 INFO - Using real prefix '/tools/python27' 02:53:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:53:42 INFO - Installing distribute.............................................................................................................................................................................................done. 02:53:45 INFO - Installing pip.................done. 02:53:45 INFO - Return code: 0 02:53:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:53: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:53:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:53:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:53: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:53:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:53:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:53:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10deada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10debd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe7accb080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd4ce00>, '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 0x10dbb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd47b20>, '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.mdxxC485bu/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.Tr2SNcNOtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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:53:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:53:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:53:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mdxxC485bu/Render', 02:53:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:53:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:53:45 INFO - 'HOME': '/Users/cltbld', 02:53:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:53:45 INFO - 'LOGNAME': 'cltbld', 02:53:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:53:45 INFO - 'MOZ_NO_REMOTE': '1', 02:53:45 INFO - 'NO_EM_RESTART': '1', 02:53:45 INFO - 'PAGER': '/bin/cat', 02:53:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:53:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:53:45 INFO - 'PWD': '/builds/slave/test', 02:53:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:53:45 INFO - 'SHELL': '/bin/bash', 02:53:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tr2SNcNOtE/Listeners', 02:53:45 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 02:53:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:53:45 INFO - 'USER': 'cltbld', 02:53:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:53:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:53:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:53:45 INFO - 'XPC_FLAGS': '0x0', 02:53:45 INFO - 'XPC_SERVICE_NAME': '0', 02:53:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:53:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:53:45 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:53:45 INFO - Cleaning up... 02:53:45 INFO - Return code: 0 02:53:45 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:53: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:53:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:53:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:53: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:53:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:53:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:53:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10deada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10debd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe7accb080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd4ce00>, '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 0x10dbb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd47b20>, '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.mdxxC485bu/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.Tr2SNcNOtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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:53:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 02:53:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 02:53:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mdxxC485bu/Render', 02:53:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:53:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:53:45 INFO - 'HOME': '/Users/cltbld', 02:53:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:53:45 INFO - 'LOGNAME': 'cltbld', 02:53:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:53:45 INFO - 'MOZ_NO_REMOTE': '1', 02:53:45 INFO - 'NO_EM_RESTART': '1', 02:53:45 INFO - 'PAGER': '/bin/cat', 02:53:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:53:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:53:45 INFO - 'PWD': '/builds/slave/test', 02:53:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:53:45 INFO - 'SHELL': '/bin/bash', 02:53:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tr2SNcNOtE/Listeners', 02:53:45 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 02:53:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:53:45 INFO - 'USER': 'cltbld', 02:53:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:53:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:53:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:53:45 INFO - 'XPC_FLAGS': '0x0', 02:53:45 INFO - 'XPC_SERVICE_NAME': '0', 02:53:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:53:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:53:45 INFO - Downloading/unpacking psutil>=0.7.1 02:53:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:53:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:53:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:53:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:53:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:53:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:53:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:53:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:53:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:53:48 INFO - Installing collected packages: psutil 02:53:48 INFO - Running setup.py install for psutil 02:53:48 INFO - building 'psutil._psutil_osx' extension 02:53:48 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:53:49 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:53:49 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:53:49 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:53:49 INFO - building 'psutil._psutil_posix' extension 02:53:49 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:53:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:53:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:53:49 INFO - ^ 02:53:49 INFO - 1 warning generated. 02:53:49 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:53:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:53:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:53:49 INFO - Successfully installed psutil 02:53:49 INFO - Cleaning up... 02:53:49 INFO - Return code: 0 02:53:49 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:53:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:53:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:53:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:53:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:53:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:53:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:53:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10deada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10debd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe7accb080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd4ce00>, '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 0x10dbb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd47b20>, '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.mdxxC485bu/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.Tr2SNcNOtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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:53:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 02:53:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 02:53:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mdxxC485bu/Render', 02:53:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:53:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:53:49 INFO - 'HOME': '/Users/cltbld', 02:53:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:53:49 INFO - 'LOGNAME': 'cltbld', 02:53:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:53:49 INFO - 'MOZ_NO_REMOTE': '1', 02:53:49 INFO - 'NO_EM_RESTART': '1', 02:53:49 INFO - 'PAGER': '/bin/cat', 02:53:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:53:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:53:49 INFO - 'PWD': '/builds/slave/test', 02:53:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:53:49 INFO - 'SHELL': '/bin/bash', 02:53:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tr2SNcNOtE/Listeners', 02:53:49 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 02:53:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:53:49 INFO - 'USER': 'cltbld', 02:53:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:53:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:53:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:53:49 INFO - 'XPC_FLAGS': '0x0', 02:53:49 INFO - 'XPC_SERVICE_NAME': '0', 02:53:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:53:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:53:50 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:53:50 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:53:50 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:53:50 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:53:50 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:53:50 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:53:50 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:53:52 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:53:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:53:52 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:53:52 INFO - Installing collected packages: mozsystemmonitor 02:53:52 INFO - Running setup.py install for mozsystemmonitor 02:53:52 INFO - Successfully installed mozsystemmonitor 02:53:52 INFO - Cleaning up... 02:53:52 INFO - Return code: 0 02:53:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:53:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:53:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:53:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:53:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:53:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:53:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:53:52 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 0x10deada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10debd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe7accb080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd4ce00>, '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 0x10dbb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd47b20>, '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.mdxxC485bu/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.Tr2SNcNOtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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:53:52 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:53:52 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:53:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mdxxC485bu/Render', 02:53:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:53:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:53:52 INFO - 'HOME': '/Users/cltbld', 02:53:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:53:52 INFO - 'LOGNAME': 'cltbld', 02:53:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:53:52 INFO - 'MOZ_NO_REMOTE': '1', 02:53:52 INFO - 'NO_EM_RESTART': '1', 02:53:52 INFO - 'PAGER': '/bin/cat', 02:53:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:53:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:53:52 INFO - 'PWD': '/builds/slave/test', 02:53:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:53:52 INFO - 'SHELL': '/bin/bash', 02:53:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tr2SNcNOtE/Listeners', 02:53:52 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 02:53:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:53:52 INFO - 'USER': 'cltbld', 02:53:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:53:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:53:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:53:52 INFO - 'XPC_FLAGS': '0x0', 02:53:52 INFO - 'XPC_SERVICE_NAME': '0', 02:53:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:53:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:53:53 INFO - Downloading/unpacking blobuploader==1.2.4 02:53:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:53:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:53:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:53:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:53:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:53:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:53:55 INFO - Downloading blobuploader-1.2.4.tar.gz 02:53:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:53:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:53:55 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:53:55 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:53:55 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:53:55 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:53:55 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:53:55 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:53:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:53:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:53:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:53:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:53:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:53:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:53:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:53:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:53:56 INFO - Downloading docopt-0.6.1.tar.gz 02:53:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:53:56 INFO - Installing collected packages: blobuploader, requests, docopt 02:53:56 INFO - Running setup.py install for blobuploader 02:53:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:53:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:53:56 INFO - Running setup.py install for requests 02:53:57 INFO - Running setup.py install for docopt 02:53:57 INFO - Successfully installed blobuploader requests docopt 02:53:57 INFO - Cleaning up... 02:53:57 INFO - Return code: 0 02:53:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:53: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:53:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:53:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:53: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:53:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:53:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:53:57 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 0x10deada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10debd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe7accb080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd4ce00>, '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 0x10dbb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd47b20>, '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.mdxxC485bu/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.Tr2SNcNOtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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:53:57 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:53:57 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:53:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mdxxC485bu/Render', 02:53:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:53:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:53:57 INFO - 'HOME': '/Users/cltbld', 02:53:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:53:57 INFO - 'LOGNAME': 'cltbld', 02:53:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:53:57 INFO - 'MOZ_NO_REMOTE': '1', 02:53:57 INFO - 'NO_EM_RESTART': '1', 02:53:57 INFO - 'PAGER': '/bin/cat', 02:53:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:53:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:53:57 INFO - 'PWD': '/builds/slave/test', 02:53:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:53:57 INFO - 'SHELL': '/bin/bash', 02:53:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tr2SNcNOtE/Listeners', 02:53:57 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 02:53:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:53:57 INFO - 'USER': 'cltbld', 02:53:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:53:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:53:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:53:57 INFO - 'XPC_FLAGS': '0x0', 02:53:57 INFO - 'XPC_SERVICE_NAME': '0', 02:53:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:53:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:53:57 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-ZoznGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:53:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:53:57 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-_Lujmn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:53:58 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-_dRWjQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:53:58 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-s8dtGI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:53:58 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-sqeIVy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:53:58 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-YccpaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:53:58 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-8gC2gO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:53:58 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-P0NGqI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:53:58 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-qke2P8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:53:58 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-TIyY1a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:53:58 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-z2rMPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:53:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:53:59 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-Uoq4Ge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:53:59 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-heV7x9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:53:59 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-HZMGlf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:53:59 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-NLNvw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:53:59 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-B8nL_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:53:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:53:59 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-ljWbSL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:53:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:53:59 INFO - Running setup.py install for manifestparser 02:53:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:53:59 INFO - Running setup.py install for mozcrash 02:53:59 INFO - Running setup.py install for mozdebug 02:54:00 INFO - Running setup.py install for mozdevice 02:54:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:54:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:54:00 INFO - Running setup.py install for mozfile 02:54:00 INFO - Running setup.py install for mozhttpd 02:54:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:54:00 INFO - Running setup.py install for mozinfo 02:54:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:54:00 INFO - Running setup.py install for mozInstall 02:54:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:54:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:54:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:54:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:54:00 INFO - Running setup.py install for mozleak 02:54:00 INFO - Running setup.py install for mozlog 02:54:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:54:01 INFO - Running setup.py install for moznetwork 02:54:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:54:01 INFO - Running setup.py install for mozprocess 02:54:01 INFO - Running setup.py install for mozprofile 02:54:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:54:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:54:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:54:01 INFO - Running setup.py install for mozrunner 02:54:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:54:01 INFO - Running setup.py install for mozscreenshot 02:54:01 INFO - Running setup.py install for moztest 02:54:02 INFO - Running setup.py install for mozversion 02:54:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:54:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:54:02 INFO - Cleaning up... 02:54:02 INFO - Return code: 0 02:54:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:54:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:54:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:54:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:54:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:54:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:54:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:54:02 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 0x10deada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10debd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe7accb080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd4ce00>, '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 0x10dbb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd47b20>, '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.mdxxC485bu/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.Tr2SNcNOtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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:54:02 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:54:02 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:54:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mdxxC485bu/Render', 02:54:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:54:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:54:02 INFO - 'HOME': '/Users/cltbld', 02:54:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:54:02 INFO - 'LOGNAME': 'cltbld', 02:54:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:54:02 INFO - 'MOZ_NO_REMOTE': '1', 02:54:02 INFO - 'NO_EM_RESTART': '1', 02:54:02 INFO - 'PAGER': '/bin/cat', 02:54:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:54:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:54:02 INFO - 'PWD': '/builds/slave/test', 02:54:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:54:02 INFO - 'SHELL': '/bin/bash', 02:54:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tr2SNcNOtE/Listeners', 02:54:02 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 02:54:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:54:02 INFO - 'USER': 'cltbld', 02:54:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:54:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:54:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:54:02 INFO - 'XPC_FLAGS': '0x0', 02:54:02 INFO - 'XPC_SERVICE_NAME': '0', 02:54:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:54:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:54:02 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-K6kZO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:54:02 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:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:54:02 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-66m0YU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:54:02 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:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:54:02 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-bLUThw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:54:02 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:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:54:02 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-VMz_KF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:54:02 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:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:54:02 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-xSleFM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:54:02 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:54:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:54:02 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-fcU5_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:54:03 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:54:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:54:03 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-Pp17JN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:54:03 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:54:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:54:03 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-mL4WNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:54:03 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:54:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:54:03 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-0jYvPJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:54:03 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:54:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:54:03 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-7usr3U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:54:03 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:54:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:54:03 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-1ETBLb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:54:03 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:54:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:54:03 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-qaKg95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:54:03 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:54:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:54:03 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-W3jI0D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:54:03 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:54:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:54:03 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-wfNkz3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:54:03 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:54:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:54:03 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-dJ29PE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:54:04 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:54:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:54:04 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-6dZWWV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:54:04 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:54:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:54:04 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-tnLN83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:54:04 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:54:04 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:54:04 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:54:04 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:54:04 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:54:04 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:54:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:54:04 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:54:04 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:54:04 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:54:04 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:54:04 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:54:04 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:54:06 INFO - Downloading blessings-1.6.tar.gz 02:54:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:54:06 INFO - Installing collected packages: blessings 02:54:06 INFO - Running setup.py install for blessings 02:54:06 INFO - Successfully installed blessings 02:54:06 INFO - Cleaning up... 02:54:07 INFO - Return code: 0 02:54:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:54:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:54:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:54:07 INFO - Reading from file tmpfile_stdout 02:54:07 INFO - Current package versions: 02:54:07 INFO - blessings == 1.6 02:54:07 INFO - blobuploader == 1.2.4 02:54:07 INFO - docopt == 0.6.1 02:54:07 INFO - manifestparser == 1.1 02:54:07 INFO - mozInstall == 1.12 02:54:07 INFO - mozcrash == 0.17 02:54:07 INFO - mozdebug == 0.1 02:54:07 INFO - mozdevice == 0.48 02:54:07 INFO - mozfile == 1.2 02:54:07 INFO - mozhttpd == 0.7 02:54:07 INFO - mozinfo == 0.9 02:54:07 INFO - mozleak == 0.1 02:54:07 INFO - mozlog == 3.1 02:54:07 INFO - moznetwork == 0.27 02:54:07 INFO - mozprocess == 0.22 02:54:07 INFO - mozprofile == 0.28 02:54:07 INFO - mozrunner == 6.11 02:54:07 INFO - mozscreenshot == 0.1 02:54:07 INFO - mozsystemmonitor == 0.0 02:54:07 INFO - moztest == 0.7 02:54:07 INFO - mozversion == 1.4 02:54:07 INFO - psutil == 3.1.1 02:54:07 INFO - requests == 1.2.3 02:54:07 INFO - wsgiref == 0.1.2 02:54:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:54:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:54:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:54:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:54:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:54:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:54:07 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 0x10deada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10debd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe7accb080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd4ce00>, '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 0x10dbb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd47b20>, '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.mdxxC485bu/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.Tr2SNcNOtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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:54:07 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:54:07 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:54:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mdxxC485bu/Render', 02:54:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:54:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:54:07 INFO - 'HOME': '/Users/cltbld', 02:54:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:54:07 INFO - 'LOGNAME': 'cltbld', 02:54:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:54:07 INFO - 'MOZ_NO_REMOTE': '1', 02:54:07 INFO - 'NO_EM_RESTART': '1', 02:54:07 INFO - 'PAGER': '/bin/cat', 02:54:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:54:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:54:07 INFO - 'PWD': '/builds/slave/test', 02:54:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:54:07 INFO - 'SHELL': '/bin/bash', 02:54:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tr2SNcNOtE/Listeners', 02:54:07 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 02:54:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:54:07 INFO - 'USER': 'cltbld', 02:54:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:54:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:54:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:54:07 INFO - 'XPC_FLAGS': '0x0', 02:54:07 INFO - 'XPC_SERVICE_NAME': '0', 02:54:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:54:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:54:07 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:54:07 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:54:07 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:54:07 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:54:07 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:54:07 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:54:07 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:54:07 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:54:07 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:54:07 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:54:07 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:54:07 INFO - Cleaning up... 02:54:07 INFO - Return code: 0 02:54:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:54:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:54:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:54:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:54:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:54:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:54:07 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 0x10deada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10debd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe7accb080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd4ce00>, '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 0x10dbb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd47b20>, '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.mdxxC485bu/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.Tr2SNcNOtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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:54:07 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:54:07 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:54:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mdxxC485bu/Render', 02:54:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:54:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:54:07 INFO - 'HOME': '/Users/cltbld', 02:54:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:54:07 INFO - 'LOGNAME': 'cltbld', 02:54:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:54:07 INFO - 'MOZ_NO_REMOTE': '1', 02:54:07 INFO - 'NO_EM_RESTART': '1', 02:54:07 INFO - 'PAGER': '/bin/cat', 02:54:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:54:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:54:07 INFO - 'PWD': '/builds/slave/test', 02:54:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:54:07 INFO - 'SHELL': '/bin/bash', 02:54:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tr2SNcNOtE/Listeners', 02:54:07 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 02:54:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:54:07 INFO - 'USER': 'cltbld', 02:54:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:54:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:54:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:54:07 INFO - 'XPC_FLAGS': '0x0', 02:54:07 INFO - 'XPC_SERVICE_NAME': '0', 02:54:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:54:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:54:07 INFO - Downloading/unpacking jsonschema 02:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:54:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:54:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:54:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:54:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:54:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:54:10 INFO - Downloading/unpacking functools32 (from jsonschema) 02:54:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:54:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:54:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:54:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:54:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:54:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:54:10 INFO - Downloading functools32-3.2.3-2.tar.gz 02:54:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:54:10 INFO - warning: no files found matching '*.txt' 02:54:10 INFO - no previously-included directories found matching 'build' 02:54:10 INFO - no previously-included directories found matching 'dist' 02:54:10 INFO - no previously-included directories found matching '.git*' 02:54:10 INFO - Installing collected packages: jsonschema, functools32 02:54:10 INFO - Running setup.py install for functools32 02:54:10 INFO - warning: no files found matching '*.txt' 02:54:10 INFO - no previously-included directories found matching 'build' 02:54:10 INFO - no previously-included directories found matching 'dist' 02:54:10 INFO - no previously-included directories found matching '.git*' 02:54:10 INFO - Successfully installed jsonschema functools32 02:54:10 INFO - Cleaning up... 02:54:11 INFO - Return code: 0 02:54:11 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:54:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:54:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:54:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:54:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:54:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:54:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:54:11 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 0x10deada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10debd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe7accb080>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd4ce00>, '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 0x10dbb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd47b20>, '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.mdxxC485bu/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.Tr2SNcNOtE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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:54:11 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:54:11 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:54:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mdxxC485bu/Render', 02:54:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:54:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:54:11 INFO - 'HOME': '/Users/cltbld', 02:54:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:54:11 INFO - 'LOGNAME': 'cltbld', 02:54:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:54:11 INFO - 'MOZ_NO_REMOTE': '1', 02:54:11 INFO - 'NO_EM_RESTART': '1', 02:54:11 INFO - 'PAGER': '/bin/cat', 02:54:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:54:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:54:11 INFO - 'PWD': '/builds/slave/test', 02:54:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:54:11 INFO - 'SHELL': '/bin/bash', 02:54:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tr2SNcNOtE/Listeners', 02:54:11 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 02:54:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:54:11 INFO - 'USER': 'cltbld', 02:54:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:54:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:54:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:54:11 INFO - 'XPC_FLAGS': '0x0', 02:54:11 INFO - 'XPC_SERVICE_NAME': '0', 02:54:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:54:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:54:11 INFO - Downloading/unpacking flake8 02:54:11 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:54:11 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:54:11 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:54:11 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:54:11 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:54:11 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:54:13 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:54:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:54:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:54:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:54:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:54:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:54:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:54:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:54:14 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:54:14 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:54:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:54:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:54:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:54:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:54:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:54:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:54:14 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:54:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:54:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:54:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:54:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:54:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:54:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:54:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:54:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:54:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:54:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:54:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:54:14 INFO - no previously-included directories found matching 'docs/_build' 02:54:14 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:54:15 INFO - Running setup.py install for pep8 02:54:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:54:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:54:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:54:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:54:15 INFO - no previously-included directories found matching 'docs/_build' 02:54:15 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:54:15 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:54:15 INFO - Cleaning up... 02:54:15 INFO - Return code: 0 02:54:15 INFO - Running post-action listener: _resource_record_post_action 02:54:15 INFO - Running post-action listener: _start_resource_monitoring 02:54:15 INFO - Starting resource monitoring. 02:54:15 INFO - ##### 02:54:15 INFO - ##### Running install step. 02:54:15 INFO - ##### 02:54:15 INFO - Running pre-action listener: _resource_record_pre_action 02:54:15 INFO - Running main action method: install 02:54:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:54:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:54:15 INFO - Reading from file tmpfile_stdout 02:54:15 INFO - Detecting whether we're running mozinstall >=1.0... 02:54:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:54:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:54:15 INFO - Reading from file tmpfile_stdout 02:54:15 INFO - Output received: 02:54:15 INFO - Usage: mozinstall [options] installer 02:54:15 INFO - Options: 02:54:15 INFO - -h, --help show this help message and exit 02:54:15 INFO - -d DEST, --destination=DEST 02:54:15 INFO - Directory to install application into. [default: 02:54:15 INFO - "/builds/slave/test"] 02:54:15 INFO - --app=APP Application being installed. [default: firefox] 02:54:15 INFO - mkdir: /builds/slave/test/build/application 02:54:15 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:54:15 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:54:46 INFO - Reading from file tmpfile_stdout 02:54:46 INFO - Output received: 02:54:46 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:54:46 INFO - Running post-action listener: _resource_record_post_action 02:54:46 INFO - ##### 02:54:46 INFO - ##### Running run-tests step. 02:54:46 INFO - ##### 02:54:46 INFO - Running pre-action listener: _resource_record_pre_action 02:54:46 INFO - Running main action method: run_tests 02:54:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:54:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:54:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:54:46 INFO - 2016-05-15 02:54:46.839 screenresolution[1653:9218] starting screenresolution argv=screenresolution get 02:54:46 INFO - 2016-05-15 02:54:46.841 screenresolution[1653:9218] Display 0: 1600x1200x32@60 02:54:46 INFO - 2016-05-15 02:54:46.847 screenresolution[1654:9220] starting screenresolution argv=screenresolution list 02:54:46 INFO - Available Modes on Display 0 02:54:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:54:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:54:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:54:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:54:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:54:47 INFO - Intel Iris: 02:54:47 INFO - Chipset Model: Intel Iris 02:54:47 INFO - Type: GPU 02:54:47 INFO - Bus: Built-In 02:54:47 INFO - VRAM (Dynamic, Max): 1536 MB 02:54:47 INFO - Vendor: Intel (0x8086) 02:54:47 INFO - Device ID: 0x0a2e 02:54:47 INFO - Revision ID: 0x0009 02:54:47 INFO - Displays: 02:54:47 INFO - Display: 02:54:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:54:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:54:47 INFO - Display Serial Number: 1600x1200 60 02:54:47 INFO - Main Display: Yes 02:54:47 INFO - Mirror: Off 02:54:47 INFO - Online: Yes 02:54:47 INFO - Rotation: Supported 02:54:47 INFO - Return code: 0 02:54:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:54:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:54:47 INFO - Python 2.7.3 02:54:47 INFO - Return code: 0 02:54:47 INFO - grabbing minidump binary from tooltool 02:54:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:54:47 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 0x10dd4ce00>, '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 0x10dbb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dd47b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:54:47 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:54:47 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:54:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:54:47 INFO - Return code: 0 02:54:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:54:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:54:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:54:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:54:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:54:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:54:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:54:48 INFO - Return code: 0 02:54:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0350', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 02:54:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0350 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 02:54:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mdxxC485bu/Render', 02:54:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:54:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:54:48 INFO - 'HOME': '/Users/cltbld', 02:54:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:54:48 INFO - 'LOGNAME': 'cltbld', 02:54:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:54:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:54:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:54:48 INFO - 'MOZ_NO_REMOTE': '1', 02:54:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:54:48 INFO - 'NO_EM_RESTART': '1', 02:54:48 INFO - 'PAGER': '/bin/cat', 02:54:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:54:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:54:48 INFO - 'PWD': '/builds/slave/test', 02:54:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:54:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:54:48 INFO - 'SHELL': '/bin/bash', 02:54:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tr2SNcNOtE/Listeners', 02:54:48 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 02:54:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:54:48 INFO - 'USER': 'cltbld', 02:54:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:54:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:54:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:54:48 INFO - 'XPC_FLAGS': '0x0', 02:54:48 INFO - 'XPC_SERVICE_NAME': '0', 02:54:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:54:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0350', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 02:54:48 INFO - mozversion application_buildid: 20160515004019 02:54:48 INFO - mozversion application_changeset: f00f9e754ce71fc5efa3e4334afea5cf6ad6c215 02:54:48 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:54:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:54:48 INFO - mozversion application_name: Firefox 02:54:48 INFO - mozversion application_remotingname: firefox-dev 02:54:48 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:54:48 INFO - mozversion application_vendor: Mozilla 02:54:48 INFO - mozversion application_version: 48.0a2 02:54:48 INFO - mozversion platform_buildid: 20160515004019 02:54:48 INFO - mozversion platform_changeset: f00f9e754ce71fc5efa3e4334afea5cf6ad6c215 02:54:48 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:54:48 INFO - mozversion platform_version: 48.0a2 02:54:48 INFO - using testdate: 1463306088 02:54:48 INFO - actual date: 1463306088 02:54:48 INFO - starting webserver on 'localhost:49276' 02:54:48 INFO - SUITE-START | Running 2 tests 02:54:48 INFO - TEST-START | dromaeo_css 02:54:48 INFO - operating with platform_type : mac_ 02:54:48 INFO - Initialising browser for dromaeo_css test... 02:54:48 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/tmpa4oGsB/profile http://localhost:49276/getInfo.html) 02:54:51 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 02:54:51 INFO - PROCESS | 1672 | colorDepth:24 02:54:51 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 02:54:51 INFO - PROCESS | 1672 | __metrics 02:54:51 INFO - PROCESS | 1672 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:54:51 ERROR - PROCESS | 1672 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:54:51 INFO - TEST-INFO | 1672: exit 0 02:54:51 INFO - Browser initialized. 02:54:51 INFO - Running cycle 1/1 for dromaeo_css test... 02:54:51 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/tmpa4oGsB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:55:03 INFO - PROCESS | 1676 | RSS: Main: 210993152 02:55:03 INFO - PROCESS | 1676 | 02:55:55 INFO - PROCESS | 1676 | 1463306155416 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:58:05 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 02:58:07 INFO - PROCESS | 1676 | RSS: Main: 320466944 02:58:07 INFO - PROCESS | 1676 | 03:01:09 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 03:01:10 INFO - PROCESS | 1676 | RSS: Main: 357527552 03:01:10 INFO - PROCESS | 1676 | 03:04:12 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 03:04:13 INFO - PROCESS | 1676 | RSS: Main: 360407040 03:04:13 INFO - PROCESS | 1676 | 03:07:15 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 03:07:16 INFO - PROCESS | 1676 | RSS: Main: 361717760 03:07:16 INFO - PROCESS | 1676 | 03:10:19 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 03:10:20 INFO - PROCESS | 1676 | RSS: Main: 329793536 03:10:20 INFO - PROCESS | 1676 | 03:13:22 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 03:13:22 INFO - PROCESS | 1676 | RSS: Main: 367767552 03:13:22 INFO - PROCESS | 1676 | 03:13:22 INFO - PROCESS | 1676 | __start_tp_report 03:13:22 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 03:13:22 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 03:13:22 INFO - PROCESS | 1676 | |i|pagename|runs| 03:13:22 INFO - PROCESS | 1676 | |0;dojo.html;2981.835999020053;3409.2329225924163;3471.774334668248;3499.125218695327;3541.238633693757;5765.048766953458;5774.393688662688;5774.65352078875;5784.652920824743;5811.970940145304;5766.682832444214;5808.477237048664;5821.7380217890195;5825.912611310834;5842.853928651786;5185.948140518584;5210.687358758477;5226.8693282667855;5291.86770330741;5395.271638328834;5265.183896496049;5277;5279.260903473517;5280.656757310781;5291.417944026154;4244.469441319835;5198.168293073109;5258.763355649005;5277.947220527784;5279.656822306557;5248.055350037011;5287.629865909388;5305.946940530584;5309.867253318659;5311.65257517267;5113.284140220372;5133.076046311662;5142.742862856842;5210.843674689765;5213.713245771481;5562.249096371978;5594.888102237973;5598.916016259759;5612.915806262909;5637.3517045539875;5184.896302073975;6239.500839932794;6265.373462653743;6285.182926219608;6299.5275354348605;6161.7535298588;6223.408776166302;6326.588771729869;6334.841628959267;6370.71331790066;4888.437829649636;5849.61977471463;5860.765569377178;5880.970595146997;5891.705414729289;6283.560150789493;6319.0837328587095;6327.955887278547;6330.525210609223;6337.683115844282;6052.89039994805;6135.233095863017;6211.565190436717;6219.222597175353;6234.937650623436;6326.4622507087415;6330.3669633036325;6357.332480089524;6362.172917520693;6371.426571608577;6237.438630523275;6266.373362663697;6269.905951410732;6286.937130628727;6301.086342480315;6113.658745237732;6201.875962480815;6221.47117495018;6325.557210995278;6329.620222786648;4944.826931057396;4967.3542439482635;4977.427595826454;4982.128127577659;4993.650444468925;4474.395956545824;4476.485204201558;4495.617872480811;4497.415336006364;4502.5047244803045;5692.089265717465;5755.395683453261;5784.65292082468;5845.386234445407;5863.208466857005;6305.464035557029;6325.3674632537295;6338.746450141943;6354;6355.586886852339;4860.246661767432;5761.222234998306;5781.682007489544;5785.681787501728;5789.507891829157;5335.919961200585;5389.326334208224;5418.83743487696;5460.453954604508;5477.616566840363;6315.463185629089;6352.237731472161;6359.650219237894;6367.808965731036;6373.713182906873;6139.1855040903665;6288.993760998218;6353.236342191161;6374.4900407966325;6385.138006369267;5747.683877386784;6083.221157652979;6302.117703521602;6323.399277068856;6372.713227904848;6278.372162783685;6280.0893870389455;6306.337834527492;6306.84232894163;6308.936910630835;4407.515173330995;6179.474744646755;6360.204974378203;6361.8727625448155;6390.616563006234;6005;6115.909724157246;6125.784909797561;6128.411459112376;6145.324014358506;6060.515158787374;6117.816465506042;6124.14262003311;6129.08063790435;6160.907586386297;5871.588988770745;5979.701014949323;6004.099385092271;6013.639181649115;6021.638701677914;6075.2405949256345;6139.938600613937;6141.84645383869;6144.10910417978;6153.938460615515;6092.543059270483;6094.878102438015;6108.816735497942;6118.418750218722;6119.959606866754 03:13:22 INFO - PROCESS | 1676 | |1;ext.html;541.8022421816037;544.7167472914082;561.4357570641506;572.5162237908968;620.2123303404679;38797;39116.99999999996;39530.60469395305;40791.388129178085;40854.7957260214;43201.56798432015;43843;43918.90202744933;43985.56014439862;44050;8584.828303433927;8750.781230469258;9579.568919398627;9812.803743925117;9821.852672209887;9618.374805637612;11781.528738850478;11783.998360139398;11787.057035437145;11789.17475776696;11503.539858405695;11590.130740194478;11596.536138554447;11598.246114002604;11611.883881161208;8625.913740862605;8808.427452215585;8813.250873675745;9448.57481413338;11458.656240312763;11337.829932551018;11457.656270311903;11478.39302497651;11483.196176267664;11501.88498115025;38635.22729545421;39041;39074.60925390738;39114.02214944621;39118.41322380167;3297.4394352959816;3709.1839795245005;3721.627837216257;3730.9626903730887;3828.3874580067054;27850.16449506518;28595.142145735663;29038.983635572866;29148.417031659246;29369.41261174786;2749.5325794614964;2799.8320100794017;2805.4108637186214;2813.690494045674;2842.2468045967835;493349.9999999964;494742;495535;495720;497177.51411242713;50853.49146508487;61283.6935815327;61730.382696173365;61816.69091654513;61939;68319.29201769896;69635;69729.65135174393;69779;69812.3018769816;17218.827811721723;17575.472735817944;17590.944543327187;17593.208305626278;17604.207810648553;12354.135210535176;12560.246385216733;13475.865241347461;13603.591892243248;13613.795793063113;19263.22947082101;19291.228350865807;19316;19318.71021934672;19377.612447750966;8012.759617211493;8155.551444670481;8163.55093471956;8186.54973976437;8190.672373105128;956.4118067388506;974.6734843827255;1075.575147816608;1089.2974031749477;1091.345192884263;30068.398632027674;30449.08652740421;30577;32541;33245.335093297996;1063.8297872340388;1071.132382769959;1120.843081968525;1136.6817291158488;1182.9467673954694;12488.687782805502;12980.610581682564;14633.926830365781;15057.096574205583;15428.460003900034;621524;626509;637333;638583;640195;60930.0860487093;61266.38733612607;61534;61749.69125154345;61939;1463.2464280895347;1605.149270886432;1608.453125937187;1658.3499418078848;1675.1875340459867;28768;29526.261843454085;29578.704212957597;29596.260093497836;30379.240518986317;53191.67020824356;54479.45520544744;54521.454785451635;54605.63485912884;54681.72659136679;371024;422036.88981554896;443287;444278;687107;29683.554746678386;33686.326273473904;34605.78879739197;35276.47085293774;35811.74658886927;36801;43361.78319108384;44300.556994429644;45019.32471012872;45692.54307456883;35905.28189436249;36332.636673633984;37112.44331334978;37166.62833371632;37866.8106659465;36491.817540912125;36734.8163259182;37005;37316.813415932746;37464.812675936446;32272.354552908342;33108.50337244895;37205;38521;38991;32273.193170170933;33789;33996.66003340034;34122.48816267708;34161.82919085389;25087.498250034532;25389.492210156062;25603.488708599118;25877.611835822856;25911.22266332013 03:13:22 INFO - PROCESS | 1676 | |2;jquery.html;400.93384591542394;401.2696891657183;404.64593480704383;404.81176253042327;413.9441175441315;13239.93380033101;18767.249310027582;19089.331873384435;21317.99999999998;21335.67996480052;16658;18099.819001809978;20338.28815991437;20680.17279308826;20717.896410517926;5188.273641690166;5228.346456692913;5833.300003999526;6080.331163572004;6086.817395478142;4244;5892.852678683024;5975.402459754033;5979.043353063511;5985.1022346647915;6282.623042617434;6582.17722784652;6587.275399706028;6598.142241508597;6599.769008084718;6555.50833687473;6556.508261880378;6559.245686746038;7542.509736867086;7974.920250797476;7702.499337543042;7705.267999540035;7712;7724.30481256684;7724.6523906424345;28908;29161.541922903984;29184;30537.23656908573;30701.38597228043;2480.3179125740385;2494.2018554062706;2543.7964962803117;2982.1799005273506;3125.8746851133574;15298.311575979107;16086.75869861953;16105.436309729103;16290.348386064543;16439.753403698953;1826.4155470249525;1831.7710286214224;1845.2987864611362;1851.9166637501344;1873.4660621722744;122311;127087.09369359467;128016.35991820075;128049;131122.68877311295;21448.57102857934;21609.35171944844;21710.891445542988;21747.456313592287;21937.670934935988;35916.64083359185;35979.64020359815;36514.90455286346;36984.63015369866;36988.260234795154;7442.255774422514;8033.758987230392;8356.456830306011;8736.606852691644;9031.412958157698;9134.81730365389;9136.086391360943;9143.954280228556;9206.861897071549;9207.447553146832;3803.5245594300713;4063.89840253996;4514.735873955297;4703.106409782131;4703.941613137017;1936.2351871010871;1977.32770857909;1982.8413726901813;1985.5036240939764;1985.8212760851593;901.4275934781462;922.5387306346827;978.7308490165119;1007.652359935821;1033.194108595297;20306;21026.97789630335;21262.681059784114;21453.570928581343;22197.24809420328;883.3772190605607;953.4136506048869;970.3401686853008;990.8810942686923;1020.1481762728205;11027.779444411068;11288.322700637824;11339.262947908359;11370.431478426046;11413.657590272305;522881;613872;642084;643049;643339;129860.35069824591;130124.3493782544;134999.32500337437;135366.32316838353;137097;912.8083102548406;1028.7068185567077;1040.6565833275151;1055.2138656700806;1060.0406631998055;18164.703538611164;18586.721199182295;18782.718259226396;18870;18983.24067037303;50996.23505647492;52383.73808130935;52404;53536;53819.46180538145;66690.66618667697;67479;79844.60077699574;80488;82888.58555707183;7155.785326440215;7541.321281084729;8143.5928203590765;8158.143394943683;8272.33821294307;8942.642294308154;11563;12339.53209358114;12654.177478463871;12732.427040783;10844.240903136704;10858.891411086104;10897;10939.78120437571;10991.450427478434;10799.460026998775;10853;10869;11039.337639741641;11044.779104418027;6401.391867772554;10859.2398532102;11023.61417350389;11313.434328283718;11486.483108260316;7616.3906887449975;8076.4750291230885;8148.5518296494265;8189.549574773445;8473.195046470575;10580.206484513847;10954.123670106532;11161.051310638686;11172.497237624164;11213.66359009231 03:13:22 INFO - PROCESS | 1676 | |3;mootools.html;1252.1735654468052;1547.5280039587924;1563.398091734682;1788.9373871914477;1931.044133154089;7800.570968596725;8502.744917652473;8650.524221167832;8653.091425400336;8654.567271636408;8364.37267204959;8623.353248506355;8624.525651089203;8628.352873534493;8635.740927772176;2153.676948457728;2367.538330025646;2432.343267317823;2432.7810497055216;2452.7792498675117;2464.778169964703;2474.4927289905527;2479.293401380607;2479.367761220886;2484.813638977075;2339.9353294251073;2419.842710223839;2442.621393683973;2443.9755602443925;2446.522928029035;2156.9245076422408;2356.8468049576722;2409.638554216864;2417.02110645189;2417.851520527744;2381.392744850052;2415.236877079724;2420.0198919437657;2422.987885060581;2423.8788060597076;8534.786630334229;8573.314134869195;8573.699920502815;8577.871331930026;8602.483850968962;5207.453217412193;5211.2443695663915;5349.812756553502;5360.517553420211;5382.569394448435;3517.7889326640484;3564.1267889367273;3632.400653892105;3641.1261297288725;3646.6353364663587;1012.8987101289886;1017.9745506362326;1041.2607048995224;1045.9215558833118;1049.2550289294534;11882.584109556125;11902.523899044028;11919;11921.165518413714;11922.821157682642;4750.809967601326;4812.230043193073;4838.225883858566;4873.829415970495;4920.040592084509;8348.206920342558;9817;9938.652147174958;9945.20438364929;9953.25350598708;4615.14619795339;5413.025655382069;5444.8366549003595;5450.754716037709;5452.182172674131;4366.476022877275;4516.864494065183;4745.217039188496;4900.950990490121;4902.50974902514;3983.50206224222;4013.2776100301644;4016.7991600419864;4086.5096188457574;4099.938500922489;1141.9265890063314;1142.8400023996646;1322.5899971009019;1328.3955800110925;1335.2589312931416;806.5846089264033;908.2724025684988;908.6365453818526;926.3191554207652;927.8608208768738;5058.671186372874;5737.3115226172295;5815.214945982323;5863.970680146572;5866.589338746332;455.897423079811;463.55267167183155;467.0775218942561;472.15955599033776;475.92441083151635;3859.2474467478573;3898.3762597984187;3910.413437984268;3933.4296527003426;3937.42907278443;10319.1744660427;10428.5828566858;10590.099841513558;10590.947045264878;10914.56341746337;10451.268411211142;10487.108595769292;10592;10595.629152979764;10632.46837658114;7961.681532738741;8286.58567071656;8302.211289927447;8376.623051962677;8380.413371063967;5261.973690131525;5585.469380408855;5634.183043458757;5750.8849823002465;5869.853253668522;7335.853282934205;8674.78313042179;9477.478738669439;9501.809963800548;9633.421994680342;9772.169365604002;9777.85333219988;9807.85288220663;9938.304318697621;9941.155001824925;6137.6931153441255;6453.762125664268;6924.238333783381;7435.739749108755;7511.47419680639;7596.924030759843;7654.196309387657;7672.194419585863;7773.533587984739;7779.688812447665;7166.677499512431;7246.565206087652;7282.198958114709;7324.047873776375;7429.33136017761;7100.218975912542;7148.249433809375;7195.5322904013165;7211.8557628848175;7241.818954525968;6845.452363810784;6875.037494750839;6939.514234003571;6972.51192416526;6975.337342952412;4663.743494107856;4737.170995075917;4764.571188592905;4796;4805.918668299604;4757.333973243818;4813.446453657874;4814.301926220608;4869.853904382874;5025.321581586439 03:13:22 INFO - PROCESS | 1676 | |4;prototype.html;326.823515301737;333.0723933844243;338.8712289330054;340.2752137946174;341.51334348553337;6655.733770649174;6662.100616416782;6679.9332006679915;6707.7316907323775;6772.322767723237;5401.837944861651;6677.33226677332;6688.097106890568;6709.094272273242;6741.966290168554;4679.9236175679525;4777.928331075037;4780.665353425262;4845.6123510119105;4913.82801601944;4804.231322988305;4850.2724591311235;4863.756812159378;4882.7558622068755;4893.608511319104;4073.5111786585653;4864.489228630996;4875.317455556207;4906.705597664134;4927.926081108786;4129.958700413003;4425.690201685868;4736.407949006374;4796.112719146969;4839.588634965997;4123.670106391512;4143.6892233082635;4144.937825932613;4157.064660451404;4166.708330416872;6379.872402551923;6402.071699603532;6420.1974753155855;6450.065220324788;6452.161219041542;5824.242848429677;5830.038043722823;5833.79581714638;5833.88332233353;5839.036558967808;6876.69054892531;7055.753048643272;7115.501914865961;7127.679254433563;7141.821599436087;4749.311349854286;4761.2382018876815;4762.3094651275715;4784.928226076611;4799.496052914464;7532.736354227631;7552.282533159341;7574.962125189394;7579.621018949086;7602.087749470099;4839.564439200489;4841.564259216688;4854.150523658416;4856.538628830255;4857.975710121497;4036.354183330653;4897.314375987365;4914.361133052752;4928.162212423897;4937.876553086202;5635.87282543493;5825;5835.503982161479;5838.416158384128;5846.473817356458;5830.941690583125;7193.064901562868;7213.927860721431;7226.783196504113;7229.638518074181;1675.31312162013;1711.7090094683933;1714.262866967216;1714.5627864894373;1717.2015013019006;1035.4770840725384;1086.521930350659;1095.2005036323528;1114.509615769059;1119.2501024313729;6480;6541.182352205974;6547.148870646881;6561.245456772437;6566.704498297588;7254.202037775841;7406.962965185247;7414.110306763223;7428.62856857155;7431.51695139825;6669.233038200572;6673.332666733288;6678.799636010927;6688.699008544627;6689;3879.7478163919254;3930.5479869814767;3940.802959851996;3945.0137465633593;3954.5649978502342;7552.169261381244;7775.455718099792;7799.259070388304;7905.090914544786;7908.169642187488;6710.328967103251;7236.638168091575;7732.30409263169;7746.264104910138;7758.9612051939375;7873.25204105609;7879.369650428067;7880.448368614142;7888.368930485547;7907.2488113629115;6704.396604305441;6850.417714494124;7213.783586492414;7447.255274472509;7485.1766305707415;5115.820946267012;7368.6315684214505;7378.741744038933;7381.889271661037;7390.48723434035;5954.910676339945;6776.322367763184;7731.84022396645;7798.649060792164;7863.213678632091;4662.974145687943;4699.788509517011;4717.858464246078;4733.148033354029;4741.217699079613;7070.46971477131;7429.8142546436775;7436.182019977907;7457;7457.888131677921;5830.9416905832095;7071.681774320065;7180.748673796392;7187.532810367309;7223;7174.3184397482155;7190.856182876209;7201.315874992077;7202.459815513753;7231.602261875437;7028.840241360118;7037.7184912602925;7038.085048943809;7043.859122817413;7050.25972272904;6929.688164032731;7059.152901651834;7189.173245076674;7201.351878330765;7204.351608355273;6718.865622687421;6883.346082122191;6925.203601585882;7033.859322813412;7039.190493093155 03:13:22 INFO - PROCESS | 1676 | |5;yui.html;705.9329363710448;755.6184127015856;756.4100001998443;760.368893818131;770.4144849914063;14895.031822931516;15003.099814011168;15261.38954441821;15272.389104435822;15272.541823745278;13807.240601766898;14007.859921400783;14037.15777053375;14041.22773247471;14041.578752637412;744.743063643045;766.5668897073164;927.3832901120763;956.6603855631255;960.0543464687278;929.0384452092086;953.5518306395983;957.8754761880946;958.2956526952685;959.0122174160625;997.4912794474798;1001.0140012088087;1007.0382784440878;1008.0222184481041;1010.2473657125452;779.4582764978371;979.0111986893218;995.1740055753743;996.4170959988415;997.306871724152;741.4179868803018;743.178787439882;744.7914583916457;746.8431629357841;748.356413484403;14687.045342052732;14756;14756;14756.71270953004;14769.33537990793;12533.43599538023;12739.235645861278;12915;12940.547080852126;12969.157004794659;10177.643782467578;10253.743656408575;10312.278140530167;10330.9483452583;10351.844722329095;3464.8094354810473;3491.720662347006;3504.1414853360834;3589.3898037333456;3752;67900;82470;83620.58189709013;84200;84762;9348.065193480597;9551.522423878778;9592.76018099553;9601.231901447867;9601.807963840683;22563.77436225617;22759.88620056922;22773.544529109324;22843.428914276945;23007.42481437944;7429;7457.291557302047;7458.552486850698;7689.307962283421;7703.653335599912;4009.4787677601726;4055.8986025349013;4094.733842300241;4274.401583778212;4309.181255561433;3652.579953305351;3658.5243918290985;3694.353488139565;3696.2792255509917;3708.1100535871196;643.7521554201572;644.1625886347729;645.1999520594429;668.0913957018452;668.7458765669014;582.1413415212528;586.6362855029909;589.8849724303806;597.9758517418569;622.4615707413055;7828.373730101578;8032.518048917083;8254.504729716236;8255.339572834158;8268.586570671443;527.2118183315968;548.2744749427649;578.1847594891183;603.1706403694919;616.3805375597495;3206.8556914938886;3731.3283608950105;3763.473113764075;3866.941995870064;3882.6699730522655;90826;97415;97507.53738693925;97574;97609.5358569622;75005;75745;76211;76684;77668;69132;69489.65255173692;69817.65091174512;70541;70696.64651676708;7454.552726836408;7507.286807753255;7724.34343080822;7750.961245193738;7760.068791745027;48800;49487.50512494829;50542;50617.74691126521;53017.4698253028;66448.66775666284;70504;71008;71627;72041;10050.246231532517;10074.496275186359;10305;10373.962603739566;10867.51096200657;10545.57817687284;10722.571097156026;10939.671809845398;11104.056155226574;11139.72150696239;10126.240531959986;10442.373457592264;10891.509882055485;11474.77050459003;11886.762264754483;11046.889531104907;11049.723756906142;11305.660830175108;11379.487923043649;11429.971302582808;10959.835602466128;11234.606788762354;11265.662030138781;11325.377104259345;11470.82793758111;9664;9665.3234273603;9717.643543648337;9732.610695572097;9771.755706107404;9387.577559009998;9543.916389662427;9728.319017668695;9768.755781105529;9926.652567160114 03:13:22 INFO - PROCESS | 1676 | __end_tp_report 03:13:22 INFO - PROCESS | 1676 | __start_cc_report 03:13:22 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,1265 03:13:22 INFO - PROCESS | 1676 | __end_cc_report 03:13:22 INFO - PROCESS | 1676 | __startTimestamp1463307202736__endTimestamp 03:13:22 INFO - PROCESS | 1676 | 03:13:22 INFO - PROCESS | 1676 | ------- Summary: start ------- 03:13:22 INFO - PROCESS | 1676 | Number of tests: 6 03:13:22 INFO - PROCESS | 1676 | 03:13:22 INFO - PROCESS | 1676 | [#0] dojo.html Cycles:165 Average:5760.29 Median:6013.64 stddev:669.91 (11.1%) stddev-sans-first:635.52 03:13:22 INFO - PROCESS | 1676 | Values: 2981.8 3409.2 3471.8 3499.1 3541.2 5765.0 5774.4 5774.7 5784.7 5812.0 5766.7 5808.5 5821.7 5825.9 5842.9 5185.9 5210.7 5226.9 5291.9 5395.3 5265.2 5277.0 5279.3 5280.7 5291.4 4244.5 5198.2 5258.8 5277.9 5279.7 5248.1 5287.6 5305.9 5309.9 5311.7 5113.3 5133.1 5142.7 5210.8 5213.7 5562.2 5594.9 5598.9 5612.9 5637.4 5184.9 6239.5 6265.4 6285.2 6299.5 6161.8 6223.4 6326.6 6334.8 6370.7 4888.4 5849.6 5860.8 5881.0 5891.7 6283.6 6319.1 6328.0 6330.5 6337.7 6052.9 6135.2 6211.6 6219.2 6234.9 6326.5 6330.4 6357.3 6362.2 6371.4 6237.4 6266.4 6269.9 6286.9 6301.1 6113.7 6201.9 6221.5 6325.6 6329.6 4944.8 4967.4 4977.4 4982.1 4993.7 4474.4 4476.5 4495.6 4497.4 4502.5 5692.1 5755.4 5784.7 5845.4 5863.2 6305.5 6325.4 6338.7 6354.0 6355.6 4860.2 5761.2 5781.7 5785.7 5789.5 5335.9 5389.3 5418.8 5460.5 5477.6 6315.5 6352.2 6359.7 6367.8 6373.7 6139.2 6289.0 6353.2 6374.5 6385.1 5747.7 6083.2 6302.1 6323.4 6372.7 6278.4 6280.1 6306.3 6306.8 6308.9 4407.5 6179.5 6360.2 6361.9 6390.6 6005.0 6115.9 6125.8 6128.4 6145.3 6060.5 6117.8 6124.1 6129.1 6160.9 5871.6 5979.7 6004.1 6013.6 6021.6 6075.2 6139.9 6141.8 6144.1 6153.9 6092.5 6094.9 6108.8 6118.4 6120.0 03:13:22 INFO - PROCESS | 1676 | 03:13:22 INFO - PROCESS | 1676 | [#1] ext.html Cycles:180 Average:67803.01 Median:36903.00 stddev:144749.11 (392.2%) stddev-sans-first:145067.07 03:13:22 INFO - PROCESS | 1676 | Values: 541.8 544.7 561.4 572.5 620.2 38797.0 39117.0 39530.6 40791.4 40854.8 43201.6 43843.0 43918.9 43985.6 44050.0 8584.8 8750.8 9579.6 9812.8 9821.9 9618.4 11781.5 11784.0 11787.1 11789.2 11503.5 11590.1 11596.5 11598.2 11611.9 8625.9 8808.4 8813.3 9448.6 11458.7 11337.8 11457.7 11478.4 11483.2 11501.9 38635.2 39041.0 39074.6 39114.0 39118.4 3297.4 3709.2 3721.6 3731.0 3828.4 27850.2 28595.1 29039.0 29148.4 29369.4 2749.5 2799.8 2805.4 2813.7 2842.2 493350.0 494742.0 495535.0 495720.0 497177.5 50853.5 61283.7 61730.4 61816.7 61939.0 68319.3 69635.0 69729.7 69779.0 69812.3 17218.8 17575.5 17590.9 17593.2 17604.2 12354.1 12560.2 13475.9 13603.6 13613.8 19263.2 19291.2 19316.0 19318.7 19377.6 8012.8 8155.6 8163.6 8186.5 8190.7 956.4 974.7 1075.6 1089.3 1091.3 30068.4 30449.1 30577.0 32541.0 33245.3 1063.8 1071.1 1120.8 1136.7 1182.9 12488.7 12980.6 14633.9 15057.1 15428.5 621524.0 626509.0 637333.0 638583.0 640195.0 60930.1 61266.4 61534.0 61749.7 61939.0 1463.2 1605.1 1608.5 1658.3 1675.2 28768.0 29526.3 29578.7 29596.3 30379.2 53191.7 54479.5 54521.5 54605.6 54681.7 371024.0 422036.9 443287.0 444278.0 687107.0 29683.6 33686.3 34605.8 35276.5 35811.7 36801.0 43361.8 44300.6 45019.3 45692.5 35905.3 36332.6 37112.4 37166.6 37866.8 36491.8 36734.8 37005.0 37316.8 37464.8 32272.4 33108.5 37205.0 38521.0 38991.0 32273.2 33789.0 33996.7 34122.5 34161.8 25087.5 25389.5 25603.5 25877.6 25911.2 03:13:22 INFO - PROCESS | 1676 | 03:13:22 INFO - PROCESS | 1676 | [#2] jquery.html Cycles:180 Average:36947.75 Median:16083.09 stddev:102638.97 (638.2%) stddev-sans-first:102890.21 03:13:22 INFO - PROCESS | 1676 | Values: 400.9 401.3 404.6 404.8 413.9 13239.9 18767.2 19089.3 21318.0 21335.7 16658.0 18099.8 20338.3 20680.2 20717.9 5188.3 5228.3 5833.3 6080.3 6086.8 4244.0 5892.9 5975.4 5979.0 5985.1 6282.6 6582.2 6587.3 6598.1 6599.8 6555.5 6556.5 6559.2 7542.5 7974.9 7702.5 7705.3 7712.0 7724.3 7724.7 28908.0 29161.5 29184.0 30537.2 30701.4 2480.3 2494.2 2543.8 2982.2 3125.9 15298.3 16086.8 16105.4 16290.3 16439.8 1826.4 1831.8 1845.3 1851.9 1873.5 122311.0 127087.1 128016.4 128049.0 131122.7 21448.6 21609.4 21710.9 21747.5 21937.7 35916.6 35979.6 36514.9 36984.6 36988.3 7442.3 8033.8 8356.5 8736.6 9031.4 9134.8 9136.1 9144.0 9206.9 9207.4 3803.5 4063.9 4514.7 4703.1 4703.9 1936.2 1977.3 1982.8 1985.5 1985.8 901.4 922.5 978.7 1007.7 1033.2 20306.0 21027.0 21262.7 21453.6 22197.2 883.4 953.4 970.3 990.9 1020.1 11027.8 11288.3 11339.3 11370.4 11413.7 522881.0 613872.0 642084.0 643049.0 643339.0 129860.4 130124.3 134999.3 135366.3 137097.0 912.8 1028.7 1040.7 1055.2 1060.0 18164.7 18586.7 18782.7 18870.0 18983.2 50996.2 52383.7 52404.0 53536.0 53819.5 66690.7 67479.0 79844.6 80488.0 82888.6 7155.8 7541.3 8143.6 8158.1 8272.3 8942.6 11563.0 12339.5 12654.2 12732.4 10844.2 10858.9 10897.0 10939.8 10991.5 10799.5 10853.0 10869.0 11039.3 11044.8 6401.4 10859.2 11023.6 11313.4 11486.5 7616.4 8076.5 8148.6 8189.5 8473.2 10580.2 10954.1 11161.1 11172.5 11213.7 03:13:22 INFO - PROCESS | 1676 | 03:13:22 INFO - PROCESS | 1676 | [#3] mootools.html Cycles:180 Average:5585.73 Median:4871.84 stddev:3124.61 (64.1%) stddev-sans-first:3116.40 03:13:22 INFO - PROCESS | 1676 | Values: 1252.2 1547.5 1563.4 1788.9 1931.0 7800.6 8502.7 8650.5 8653.1 8654.6 8364.4 8623.4 8624.5 8628.4 8635.7 2153.7 2367.5 2432.3 2432.8 2452.8 2464.8 2474.5 2479.3 2479.4 2484.8 2339.9 2419.8 2442.6 2444.0 2446.5 2156.9 2356.8 2409.6 2417.0 2417.9 2381.4 2415.2 2420.0 2423.0 2423.9 8534.8 8573.3 8573.7 8577.9 8602.5 5207.5 5211.2 5349.8 5360.5 5382.6 3517.8 3564.1 3632.4 3641.1 3646.6 1012.9 1018.0 1041.3 1045.9 1049.3 11882.6 11902.5 11919.0 11921.2 11922.8 4750.8 4812.2 4838.2 4873.8 4920.0 8348.2 9817.0 9938.7 9945.2 9953.3 4615.1 5413.0 5444.8 5450.8 5452.2 4366.5 4516.9 4745.2 4901.0 4902.5 3983.5 4013.3 4016.8 4086.5 4099.9 1141.9 1142.8 1322.6 1328.4 1335.3 806.6 908.3 908.6 926.3 927.9 5058.7 5737.3 5815.2 5864.0 5866.6 455.9 463.6 467.1 472.2 475.9 3859.2 3898.4 3910.4 3933.4 3937.4 10319.2 10428.6 10590.1 10590.9 10914.6 10451.3 10487.1 10592.0 10595.6 10632.5 7961.7 8286.6 8302.2 8376.6 8380.4 5262.0 5585.5 5634.2 5750.9 5869.9 7335.9 8674.8 9477.5 9501.8 9633.4 9772.2 9777.9 9807.9 9938.3 9941.2 6137.7 6453.8 6924.2 7435.7 7511.5 7596.9 7654.2 7672.2 7773.5 7779.7 7166.7 7246.6 7282.2 7324.0 7429.3 7100.2 7148.2 7195.5 7211.9 7241.8 6845.5 6875.0 6939.5 6972.5 6975.3 4663.7 4737.2 4764.6 4796.0 4805.9 4757.3 4813.4 4814.3 4869.9 5025.3 03:13:22 INFO - PROCESS | 1676 | 03:13:22 INFO - PROCESS | 1676 | [#4] prototype.html Cycles:180 Average:5821.48 Median:6658.92 stddev:1864.10 (28.0%) stddev-sans-first:1823.13 03:13:22 INFO - PROCESS | 1676 | Values: 326.8 333.1 338.9 340.3 341.5 6655.7 6662.1 6679.9 6707.7 6772.3 5401.8 6677.3 6688.1 6709.1 6742.0 4679.9 4777.9 4780.7 4845.6 4913.8 4804.2 4850.3 4863.8 4882.8 4893.6 4073.5 4864.5 4875.3 4906.7 4927.9 4130.0 4425.7 4736.4 4796.1 4839.6 4123.7 4143.7 4144.9 4157.1 4166.7 6379.9 6402.1 6420.2 6450.1 6452.2 5824.2 5830.0 5833.8 5833.9 5839.0 6876.7 7055.8 7115.5 7127.7 7141.8 4749.3 4761.2 4762.3 4784.9 4799.5 7532.7 7552.3 7575.0 7579.6 7602.1 4839.6 4841.6 4854.2 4856.5 4858.0 4036.4 4897.3 4914.4 4928.2 4937.9 5635.9 5825.0 5835.5 5838.4 5846.5 5830.9 7193.1 7213.9 7226.8 7229.6 1675.3 1711.7 1714.3 1714.6 1717.2 1035.5 1086.5 1095.2 1114.5 1119.3 6480.0 6541.2 6547.1 6561.2 6566.7 7254.2 7407.0 7414.1 7428.6 7431.5 6669.2 6673.3 6678.8 6688.7 6689.0 3879.7 3930.5 3940.8 3945.0 3954.6 7552.2 7775.5 7799.3 7905.1 7908.2 6710.3 7236.6 7732.3 7746.3 7759.0 7873.3 7879.4 7880.4 7888.4 7907.2 6704.4 6850.4 7213.8 7447.3 7485.2 5115.8 7368.6 7378.7 7381.9 7390.5 5954.9 6776.3 7731.8 7798.6 7863.2 4663.0 4699.8 4717.9 4733.1 4741.2 7070.5 7429.8 7436.2 7457.0 7457.9 5830.9 7071.7 7180.7 7187.5 7223.0 7174.3 7190.9 7201.3 7202.5 7231.6 7028.8 7037.7 7038.1 7043.9 7050.3 6929.7 7059.2 7189.2 7201.4 7204.4 6718.9 6883.3 6925.2 7033.9 7039.2 03:13:22 INFO - PROCESS | 1676 | 03:13:22 INFO - PROCESS | 1676 | [#5] yui.html Cycles:180 Average:18407.11 Median:600.57 stddev:26085.86 (4343.5%) stddev-sans-first:26125.18 03:13:22 INFO - PROCESS | 1676 | Values: 705.9 755.6 756.4 760.4 770.4 14895.0 15003.1 15261.4 15272.4 15272.5 13807.2 14007.9 14037.2 14041.2 14041.6 744.7 766.6 927.4 956.7 960.1 929.0 953.6 957.9 958.3 959.0 997.5 1001.0 1007.0 1008.0 1010.2 779.5 979.0 995.2 996.4 997.3 741.4 743.2 744.8 746.8 748.4 14687.0 14756.0 14756.0 14756.7 14769.3 12533.4 12739.2 12915.0 12940.5 12969.2 10177.6 10253.7 10312.3 10330.9 10351.8 3464.8 3491.7 3504.1 3589.4 3752.0 67900.0 82470.0 83620.6 84200.0 84762.0 9348.1 9551.5 9592.8 9601.2 9601.8 22563.8 22759.9 22773.5 22843.4 23007.4 7429.0 7457.3 7458.6 7689.3 7703.7 4009.5 4055.9 4094.7 4274.4 4309.2 3652.6 3658.5 3694.4 3696.3 3708.1 643.8 644.2 645.2 668.1 668.7 582.1 586.6 589.9 598.0 622.5 7828.4 8032.5 8254.5 8255.3 8268.6 527.2 548.3 578.2 603.2 616.4 3206.9 3731.3 3763.5 3866.9 3882.7 90826.0 97415.0 97507.5 97574.0 97609.5 75005.0 75745.0 76211.0 76684.0 77668.0 69132.0 69489.7 69817.7 70541.0 70696.6 7454.6 7507.3 7724.3 7751.0 7760.1 48800.0 49487.5 50542.0 50617.7 53017.5 66448.7 70504.0 71008.0 71627.0 72041.0 10050.2 10074.5 10305.0 10374.0 10867.5 10545.6 10722.6 10939.7 11104.1 11139.7 10126.2 10442.4 10891.5 11474.8 11886.8 11046.9 11049.7 11305.7 11379.5 11430.0 10959.8 11234.6 11265.7 11325.4 11470.8 9664.0 9665.3 9717.6 9732.6 9771.8 9387.6 9543.9 9728.3 9768.8 9926.7 03:13:22 INFO - PROCESS | 1676 | -------- Summary: end -------- 03:13:22 INFO - PROCESS | 1676 | 03:13:23 INFO - TEST-INFO | 1676: exit 0 03:13:23 INFO - TEST-OK | dromaeo_css | took 1114830ms 03:13:23 INFO - TEST-START | kraken 03:13:23 INFO - operating with platform_type : mac_ 03:13:23 INFO - Initialising browser for kraken test... 03:13:23 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/tmptQNX8X/profile http://localhost:49276/getInfo.html) 03:13:25 INFO - PROCESS | 1682 | __metrics Screen width/height:1600/1200 03:13:25 INFO - PROCESS | 1682 | colorDepth:24 03:13:25 INFO - PROCESS | 1682 | Browser inner width/height: 1024/689 03:13:25 INFO - PROCESS | 1682 | __metrics 03:13:25 INFO - PROCESS | 1682 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:13:25 ERROR - PROCESS | 1682 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:13:26 INFO - TEST-INFO | 1682: exit 0 03:13:26 INFO - Browser initialized. 03:13:26 INFO - Running cycle 1/1 for kraken test... 03:13:26 INFO - TEST-INFO | started process 1685 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/tmptQNX8X/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:13:38 INFO - PROCESS | 1685 | RSS: Main: 211697664 03:13:38 INFO - PROCESS | 1685 | 03:13:40 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:13:41 INFO - PROCESS | 1685 | RSS: Main: 272125952 03:13:41 INFO - PROCESS | 1685 | 03:13:44 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:13:45 INFO - PROCESS | 1685 | RSS: Main: 286265344 03:13:45 INFO - PROCESS | 1685 | 03:13:48 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:13:49 INFO - PROCESS | 1685 | RSS: Main: 374792192 03:13:49 INFO - PROCESS | 1685 | 03:13:51 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:13:51 INFO - PROCESS | 1685 | RSS: Main: 280268800 03:13:51 INFO - PROCESS | 1685 | 03:13:53 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:13:54 INFO - PROCESS | 1685 | RSS: Main: 280604672 03:13:54 INFO - PROCESS | 1685 | 03:13:57 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:13:57 INFO - PROCESS | 1685 | RSS: Main: 319021056 03:13:57 INFO - PROCESS | 1685 | 03:14:00 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:14:01 INFO - PROCESS | 1685 | RSS: Main: 319324160 03:14:01 INFO - PROCESS | 1685 | 03:14:04 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:14:04 INFO - PROCESS | 1685 | RSS: Main: 318087168 03:14:04 INFO - PROCESS | 1685 | 03:14:06 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:14:07 INFO - PROCESS | 1685 | RSS: Main: 309735424 03:14:07 INFO - PROCESS | 1685 | 03:14:08 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:14:09 INFO - PROCESS | 1685 | RSS: Main: 316211200 03:14:09 INFO - PROCESS | 1685 | 03:14:11 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:14:12 INFO - PROCESS | 1685 | RSS: Main: 319721472 03:14:12 INFO - PROCESS | 1685 | 03:14:15 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:14:15 INFO - PROCESS | 1685 | RSS: Main: 324517888 03:14:15 INFO - PROCESS | 1685 | 03:14:18 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:14:19 INFO - PROCESS | 1685 | RSS: Main: 322416640 03:14:19 INFO - PROCESS | 1685 | 03:14:21 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:14:21 INFO - PROCESS | 1685 | RSS: Main: 386498560 03:14:21 INFO - PROCESS | 1685 | 03:14:21 INFO - PROCESS | 1685 | __start_tp_report 03:14:21 INFO - PROCESS | 1685 | _x_x_mozilla_page_load 03:14:21 INFO - PROCESS | 1685 | _x_x_mozilla_page_load_details 03:14:21 INFO - PROCESS | 1685 | |i|pagename|runs| 03:14:21 INFO - PROCESS | 1685 | |0;ai-astar;113;113;116;161;130;110;113;112;112;132 03:14:21 INFO - PROCESS | 1685 | |1;audio-beat-detection;156;122;158;157;145;172;161;127;213;148 03:14:21 INFO - PROCESS | 1685 | |2;audio-dft;183;187;186;185;184;184;183;188;183;185 03:14:21 INFO - PROCESS | 1685 | |3;audio-fft;72;79;77;149;56;56;57;66;137;57 03:14:21 INFO - PROCESS | 1685 | |4;audio-oscillator;90;89;144;76;82;86;123;79;77;126 03:14:21 INFO - PROCESS | 1685 | |5;imaging-gaussian-blur;86;121;112;85;86;83;88;86;89;131 03:14:21 INFO - PROCESS | 1685 | |6;imaging-darkroom;93;94;93;92;93;93;94;94;93;92 03:14:21 INFO - PROCESS | 1685 | |7;imaging-desaturate;90;88;85;86;86;85;87;86;86;86 03:14:21 INFO - PROCESS | 1685 | |8;json-parse-financial;62;64;66;63;66;65;64;85;56;58 03:14:21 INFO - PROCESS | 1685 | |9;json-stringify-tinderbox;61;65;69;70;76;68;67;68;69;68 03:14:21 INFO - PROCESS | 1685 | |10;stanford-crypto-aes;79;80;81;81;81;80;77;81;81;85 03:14:21 INFO - PROCESS | 1685 | |11;stanford-crypto-ccm;133;144;117;114;149;115;141;147;171;134 03:14:21 INFO - PROCESS | 1685 | |12;stanford-crypto-pbkdf2;165;166;176;167;165;165;202;214;185;165 03:14:21 INFO - PROCESS | 1685 | |13;stanford-crypto-sha256-iterative;67;65;70;68;66;69;78;88;75;66 03:14:21 INFO - PROCESS | 1685 | __end_tp_report 03:14:21 INFO - PROCESS | 1685 | __start_cc_report 03:14:21 INFO - PROCESS | 1685 | _x_x_mozilla_cycle_collect,1975 03:14:21 INFO - PROCESS | 1685 | __end_cc_report 03:14:21 INFO - PROCESS | 1685 | __startTimestamp1463307261314__endTimestamp 03:14:21 INFO - PROCESS | 1685 | 03:14:21 INFO - PROCESS | 1685 | ------- Summary: start ------- 03:14:21 INFO - PROCESS | 1685 | Number of tests: 14 03:14:21 INFO - PROCESS | 1685 | 03:14:21 INFO - PROCESS | 1685 | [#0] ai-astar Cycles:10 Average:121.20 Median:114.50 stddev:15.99 (14.0%) stddev-sans-first:16.68 03:14:21 INFO - PROCESS | 1685 | Values: 113.0 113.0 116.0 161.0 130.0 110.0 113.0 112.0 112.0 132.0 03:14:21 INFO - PROCESS | 1685 | 03:14:21 INFO - PROCESS | 1685 | [#1] audio-beat-detection Cycles:10 Average:155.90 Median:157.50 stddev:25.20 (16.0%) stddev-sans-first:26.73 03:14:21 INFO - PROCESS | 1685 | Values: 156.0 122.0 158.0 157.0 145.0 172.0 161.0 127.0 213.0 148.0 03:14:21 INFO - PROCESS | 1685 | 03:14:21 INFO - PROCESS | 1685 | [#2] audio-dft Cycles:10 Average:184.80 Median:185.00 stddev:1.75 (0.9%) stddev-sans-first:1.73 03:14:21 INFO - PROCESS | 1685 | Values: 183.0 187.0 186.0 185.0 184.0 184.0 183.0 188.0 183.0 185.0 03:14:21 INFO - PROCESS | 1685 | 03:14:21 INFO - PROCESS | 1685 | [#3] audio-fft Cycles:10 Average:80.60 Median:61.50 stddev:34.13 (55.5%) stddev-sans-first:36.06 03:14:21 INFO - PROCESS | 1685 | Values: 72.0 79.0 77.0 149.0 56.0 56.0 57.0 66.0 137.0 57.0 03:14:21 INFO - PROCESS | 1685 | 03:14:21 INFO - PROCESS | 1685 | [#4] audio-oscillator Cycles:10 Average:97.20 Median:80.50 stddev:24.38 (30.3%) stddev-sans-first:25.72 03:14:21 INFO - PROCESS | 1685 | Values: 90.0 89.0 144.0 76.0 82.0 86.0 123.0 79.0 77.0 126.0 03:14:21 INFO - PROCESS | 1685 | 03:14:21 INFO - PROCESS | 1685 | [#5] imaging-gaussian-blur Cycles:10 Average:96.70 Median:86.00 stddev:17.65 (20.5%) stddev-sans-first:18.29 03:14:21 INFO - PROCESS | 1685 | Values: 86.0 121.0 112.0 85.0 86.0 83.0 88.0 86.0 89.0 131.0 03:14:21 INFO - PROCESS | 1685 | 03:14:21 INFO - PROCESS | 1685 | [#6] imaging-darkroom Cycles:10 Average:93.10 Median:93.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 03:14:21 INFO - PROCESS | 1685 | Values: 93.0 94.0 93.0 92.0 93.0 93.0 94.0 94.0 93.0 92.0 03:14:21 INFO - PROCESS | 1685 | 03:14:21 INFO - PROCESS | 1685 | [#7] imaging-desaturate Cycles:10 Average:86.50 Median:86.00 stddev:1.51 (1.8%) stddev-sans-first:0.93 03:14:21 INFO - PROCESS | 1685 | Values: 90.0 88.0 85.0 86.0 86.0 85.0 87.0 86.0 86.0 86.0 03:14:21 INFO - PROCESS | 1685 | 03:14:21 INFO - PROCESS | 1685 | [#8] json-parse-financial Cycles:10 Average:64.90 Median:64.50 stddev:7.80 (12.1%) stddev-sans-first:8.20 03:14:21 INFO - PROCESS | 1685 | Values: 62.0 64.0 66.0 63.0 66.0 65.0 64.0 85.0 56.0 58.0 03:14:21 INFO - PROCESS | 1685 | 03:14:21 INFO - PROCESS | 1685 | [#9] json-stringify-tinderbox Cycles:10 Average:68.10 Median:68.50 stddev:3.78 (5.5%) stddev-sans-first:3.02 03:14:21 INFO - PROCESS | 1685 | Values: 61.0 65.0 69.0 70.0 76.0 68.0 67.0 68.0 69.0 68.0 03:14:21 INFO - PROCESS | 1685 | 03:14:21 INFO - PROCESS | 1685 | [#10] stanford-crypto-aes Cycles:10 Average:80.60 Median:81.00 stddev:2.01 (2.5%) stddev-sans-first:2.05 03:14:21 INFO - PROCESS | 1685 | Values: 79.0 80.0 81.0 81.0 81.0 80.0 77.0 81.0 81.0 85.0 03:14:21 INFO - PROCESS | 1685 | 03:14:21 INFO - PROCESS | 1685 | [#11] stanford-crypto-ccm Cycles:10 Average:136.50 Median:142.50 stddev:17.95 (12.6%) stddev-sans-first:19.00 03:14:21 INFO - PROCESS | 1685 | Values: 133.0 144.0 117.0 114.0 149.0 115.0 141.0 147.0 171.0 134.0 03:14:21 INFO - PROCESS | 1685 | 03:14:21 INFO - PROCESS | 1685 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.00 Median:171.50 stddev:17.81 (10.4%) stddev-sans-first:18.36 03:14:21 INFO - PROCESS | 1685 | Values: 165.0 166.0 176.0 167.0 165.0 165.0 202.0 214.0 185.0 165.0 03:14:21 INFO - PROCESS | 1685 | 03:14:21 INFO - PROCESS | 1685 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.20 Median:69.50 stddev:7.22 (10.4%) stddev-sans-first:7.50 03:14:21 INFO - PROCESS | 1685 | Values: 67.0 65.0 70.0 68.0 66.0 69.0 78.0 88.0 75.0 66.0 03:14:21 INFO - PROCESS | 1685 | -------- Summary: end -------- 03:14:21 INFO - PROCESS | 1685 | 03:14:22 INFO - TEST-INFO | 1685: exit 0 03:14:22 INFO - TEST-OK | kraken | took 58781ms 03:14:22 INFO - SUITE-END | took 1173s 03:14:22 INFO - Completed test suite (00:19:34) 03:14:22 INFO - javascript benchmark 03:14:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2981.835999020053, 3409.2329225924163, 3471.774334668248, 3499.125218695327, 3541.238633693757, 5765.048766953458, 5774.393688662688, 5774.65352078875, 5784.652920824743, 5811.970940145304, 5766.682832444214, 5808.477237048664, 5821.7380217890195, 5825.912611310834, 5842.853928651786, 5185.948140518584, 5210.687358758477, 5226.8693282667855, 5291.86770330741, 5395.271638328834, 5265.183896496049, 5277.0, 5279.260903473517, 5280.656757310781, 5291.417944026154, 4244.469441319835, 5198.168293073109, 5258.763355649005, 5277.947220527784, 5279.656822306557, 5248.055350037011, 5287.629865909388, 5305.946940530584, 5309.867253318659, 5311.65257517267, 5113.284140220372, 5133.076046311662, 5142.742862856842, 5210.843674689765, 5213.713245771481, 5562.249096371978, 5594.888102237973, 5598.916016259759, 5612.915806262909, 5637.3517045539875, 5184.896302073975, 6239.500839932794, 6265.373462653743, 6285.182926219608, 6299.5275354348605, 6161.7535298588, 6223.408776166302, 6326.588771729869, 6334.841628959267, 6370.71331790066, 4888.437829649636, 5849.61977471463, 5860.765569377178, 5880.970595146997, 5891.705414729289, 6283.560150789493, 6319.0837328587095, 6327.955887278547, 6330.525210609223, 6337.683115844282, 6052.89039994805, 6135.233095863017, 6211.565190436717, 6219.222597175353, 6234.937650623436, 6326.4622507087415, 6330.3669633036325, 6357.332480089524, 6362.172917520693, 6371.426571608577, 6237.438630523275, 6266.373362663697, 6269.905951410732, 6286.937130628727, 6301.086342480315, 6113.658745237732, 6201.875962480815, 6221.47117495018, 6325.557210995278, 6329.620222786648, 4944.826931057396, 4967.3542439482635, 4977.427595826454, 4982.128127577659, 4993.650444468925, 4474.395956545824, 4476.485204201558, 4495.617872480811, 4497.415336006364, 4502.5047244803045, 5692.089265717465, 5755.395683453261, 5784.65292082468, 5845.386234445407, 5863.208466857005, 6305.464035557029, 6325.3674632537295, 6338.746450141943, 6354.0, 6355.586886852339, 4860.246661767432, 5761.222234998306, 5781.682007489544, 5785.681787501728, 5789.507891829157, 5335.919961200585, 5389.326334208224, 5418.83743487696, 5460.453954604508, 5477.616566840363, 6315.463185629089, 6352.237731472161, 6359.650219237894, 6367.808965731036, 6373.713182906873, 6139.1855040903665, 6288.993760998218, 6353.236342191161, 6374.4900407966325, 6385.138006369267, 5747.683877386784, 6083.221157652979, 6302.117703521602, 6323.399277068856, 6372.713227904848, 6278.372162783685, 6280.0893870389455, 6306.337834527492, 6306.84232894163, 6308.936910630835, 4407.515173330995, 6179.474744646755, 6360.204974378203, 6361.8727625448155, 6390.616563006234, 6005.0, 6115.909724157246, 6125.784909797561, 6128.411459112376, 6145.324014358506, 6060.515158787374, 6117.816465506042, 6124.14262003311, 6129.08063790435, 6160.907586386297, 5871.588988770745, 5979.701014949323, 6004.099385092271, 6013.639181649115, 6021.638701677914, 6075.2405949256345, 6139.938600613937, 6141.84645383869, 6144.10910417978, 6153.938460615515, 6092.543059270483, 6094.878102438015, 6108.816735497942, 6118.418750218722, 6119.959606866754], "value": 5718.647432970139, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [541.8022421816037, 544.7167472914082, 561.4357570641506, 572.5162237908968, 620.2123303404679, 38797.0, 39116.99999999996, 39530.60469395305, 40791.388129178085, 40854.7957260214, 43201.56798432015, 43843.0, 43918.90202744933, 43985.56014439862, 44050.0, 8584.828303433927, 8750.781230469258, 9579.568919398627, 9812.803743925117, 9821.852672209887, 9618.374805637612, 11781.528738850478, 11783.998360139398, 11787.057035437145, 11789.17475776696, 11503.539858405695, 11590.130740194478, 11596.536138554447, 11598.246114002604, 11611.883881161208, 8625.913740862605, 8808.427452215585, 8813.250873675745, 9448.57481413338, 11458.656240312763, 11337.829932551018, 11457.656270311903, 11478.39302497651, 11483.196176267664, 11501.88498115025, 38635.22729545421, 39041.0, 39074.60925390738, 39114.02214944621, 39118.41322380167, 3297.4394352959816, 3709.1839795245005, 3721.627837216257, 3730.9626903730887, 3828.3874580067054, 27850.16449506518, 28595.142145735663, 29038.983635572866, 29148.417031659246, 29369.41261174786, 2749.5325794614964, 2799.8320100794017, 2805.4108637186214, 2813.690494045674, 2842.2468045967835, 493349.9999999964, 494742.0, 495535.0, 495720.0, 497177.51411242713, 50853.49146508487, 61283.6935815327, 61730.382696173365, 61816.69091654513, 61939.0, 68319.29201769896, 69635.0, 69729.65135174393, 69779.0, 69812.3018769816, 17218.827811721723, 17575.472735817944, 17590.944543327187, 17593.208305626278, 17604.207810648553, 12354.135210535176, 12560.246385216733, 13475.865241347461, 13603.591892243248, 13613.795793063113, 19263.22947082101, 19291.228350865807, 19316.0, 19318.71021934672, 19377.612447750966, 8012.759617211493, 8155.551444670481, 8163.55093471956, 8186.54973976437, 8190.672373105128, 956.4118067388506, 974.6734843827255, 1075.575147816608, 1089.2974031749477, 1091.345192884263, 30068.398632027674, 30449.08652740421, 30577.0, 32541.0, 33245.335093297996, 1063.8297872340388, 1071.132382769959, 1120.843081968525, 1136.6817291158488, 1182.9467673954694, 12488.687782805502, 12980.610581682564, 14633.926830365781, 15057.096574205583, 15428.460003900034, 621524.0, 626509.0, 637333.0, 638583.0, 640195.0, 60930.0860487093, 61266.38733612607, 61534.0, 61749.69125154345, 61939.0, 1463.2464280895347, 1605.149270886432, 1608.453125937187, 1658.3499418078848, 1675.1875340459867, 28768.0, 29526.261843454085, 29578.704212957597, 29596.260093497836, 30379.240518986317, 53191.67020824356, 54479.45520544744, 54521.454785451635, 54605.63485912884, 54681.72659136679, 371024.0, 422036.88981554896, 443287.0, 444278.0, 687107.0, 29683.554746678386, 33686.326273473904, 34605.78879739197, 35276.47085293774, 35811.74658886927, 36801.0, 43361.78319108384, 44300.556994429644, 45019.32471012872, 45692.54307456883, 35905.28189436249, 36332.636673633984, 37112.44331334978, 37166.62833371632, 37866.8106659465, 36491.817540912125, 36734.8163259182, 37005.0, 37316.813415932746, 37464.812675936446, 32272.354552908342, 33108.50337244895, 37205.0, 38521.0, 38991.0, 32273.193170170933, 33789.0, 33996.66003340034, 34122.48816267708, 34161.82919085389, 25087.498250034532, 25389.492210156062, 25603.488708599118, 25877.611835822856, 25911.22266332013], "value": 20501.494348647728, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.93384591542394, 401.2696891657183, 404.64593480704383, 404.81176253042327, 413.9441175441315, 13239.93380033101, 18767.249310027582, 19089.331873384435, 21317.99999999998, 21335.67996480052, 16658.0, 18099.819001809978, 20338.28815991437, 20680.17279308826, 20717.896410517926, 5188.273641690166, 5228.346456692913, 5833.300003999526, 6080.331163572004, 6086.817395478142, 4244.0, 5892.852678683024, 5975.402459754033, 5979.043353063511, 5985.1022346647915, 6282.623042617434, 6582.17722784652, 6587.275399706028, 6598.142241508597, 6599.769008084718, 6555.50833687473, 6556.508261880378, 6559.245686746038, 7542.509736867086, 7974.920250797476, 7702.499337543042, 7705.267999540035, 7712.0, 7724.30481256684, 7724.6523906424345, 28908.0, 29161.541922903984, 29184.0, 30537.23656908573, 30701.38597228043, 2480.3179125740385, 2494.2018554062706, 2543.7964962803117, 2982.1799005273506, 3125.8746851133574, 15298.311575979107, 16086.75869861953, 16105.436309729103, 16290.348386064543, 16439.753403698953, 1826.4155470249525, 1831.7710286214224, 1845.2987864611362, 1851.9166637501344, 1873.4660621722744, 122311.0, 127087.09369359467, 128016.35991820075, 128049.0, 131122.68877311295, 21448.57102857934, 21609.35171944844, 21710.891445542988, 21747.456313592287, 21937.670934935988, 35916.64083359185, 35979.64020359815, 36514.90455286346, 36984.63015369866, 36988.260234795154, 7442.255774422514, 8033.758987230392, 8356.456830306011, 8736.606852691644, 9031.412958157698, 9134.81730365389, 9136.086391360943, 9143.954280228556, 9206.861897071549, 9207.447553146832, 3803.5245594300713, 4063.89840253996, 4514.735873955297, 4703.106409782131, 4703.941613137017, 1936.2351871010871, 1977.32770857909, 1982.8413726901813, 1985.5036240939764, 1985.8212760851593, 901.4275934781462, 922.5387306346827, 978.7308490165119, 1007.652359935821, 1033.194108595297, 20306.0, 21026.97789630335, 21262.681059784114, 21453.570928581343, 22197.24809420328, 883.3772190605607, 953.4136506048869, 970.3401686853008, 990.8810942686923, 1020.1481762728205, 11027.779444411068, 11288.322700637824, 11339.262947908359, 11370.431478426046, 11413.657590272305, 522881.0, 613872.0, 642084.0, 643049.0, 643339.0, 129860.35069824591, 130124.3493782544, 134999.32500337437, 135366.32316838353, 137097.0, 912.8083102548406, 1028.7068185567077, 1040.6565833275151, 1055.2138656700806, 1060.0406631998055, 18164.703538611164, 18586.721199182295, 18782.718259226396, 18870.0, 18983.24067037303, 50996.23505647492, 52383.73808130935, 52404.0, 53536.0, 53819.46180538145, 66690.66618667697, 67479.0, 79844.60077699574, 80488.0, 82888.58555707183, 7155.785326440215, 7541.321281084729, 8143.5928203590765, 8158.143394943683, 8272.33821294307, 8942.642294308154, 11563.0, 12339.53209358114, 12654.177478463871, 12732.427040783, 10844.240903136704, 10858.891411086104, 10897.0, 10939.78120437571, 10991.450427478434, 10799.460026998775, 10853.0, 10869.0, 11039.337639741641, 11044.779104418027, 6401.391867772554, 10859.2398532102, 11023.61417350389, 11313.434328283718, 11486.483108260316, 7616.3906887449975, 8076.4750291230885, 8148.5518296494265, 8189.549574773445, 8473.195046470575, 10580.206484513847, 10954.123670106532, 11161.051310638686, 11172.497237624164, 11213.66359009231], "value": 10365.184313284748, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1252.1735654468052, 1547.5280039587924, 1563.398091734682, 1788.9373871914477, 1931.044133154089, 7800.570968596725, 8502.744917652473, 8650.524221167832, 8653.091425400336, 8654.567271636408, 8364.37267204959, 8623.353248506355, 8624.525651089203, 8628.352873534493, 8635.740927772176, 2153.676948457728, 2367.538330025646, 2432.343267317823, 2432.7810497055216, 2452.7792498675117, 2464.778169964703, 2474.4927289905527, 2479.293401380607, 2479.367761220886, 2484.813638977075, 2339.9353294251073, 2419.842710223839, 2442.621393683973, 2443.9755602443925, 2446.522928029035, 2156.9245076422408, 2356.8468049576722, 2409.638554216864, 2417.02110645189, 2417.851520527744, 2381.392744850052, 2415.236877079724, 2420.0198919437657, 2422.987885060581, 2423.8788060597076, 8534.786630334229, 8573.314134869195, 8573.699920502815, 8577.871331930026, 8602.483850968962, 5207.453217412193, 5211.2443695663915, 5349.812756553502, 5360.517553420211, 5382.569394448435, 3517.7889326640484, 3564.1267889367273, 3632.400653892105, 3641.1261297288725, 3646.6353364663587, 1012.8987101289886, 1017.9745506362326, 1041.2607048995224, 1045.9215558833118, 1049.2550289294534, 11882.584109556125, 11902.523899044028, 11919.0, 11921.165518413714, 11922.821157682642, 4750.809967601326, 4812.230043193073, 4838.225883858566, 4873.829415970495, 4920.040592084509, 8348.206920342558, 9817.0, 9938.652147174958, 9945.20438364929, 9953.25350598708, 4615.14619795339, 5413.025655382069, 5444.8366549003595, 5450.754716037709, 5452.182172674131, 4366.476022877275, 4516.864494065183, 4745.217039188496, 4900.950990490121, 4902.50974902514, 3983.50206224222, 4013.2776100301644, 4016.7991600419864, 4086.5096188457574, 4099.938500922489, 1141.9265890063314, 1142.8400023996646, 1322.5899971009019, 1328.3955800110925, 1335.2589312931416, 806.5846089264033, 908.2724025684988, 908.6365453818526, 926.3191554207652, 927.8608208768738, 5058.671186372874, 5737.3115226172295, 5815.214945982323, 5863.970680146572, 5866.589338746332, 455.897423079811, 463.55267167183155, 467.0775218942561, 472.15955599033776, 475.92441083151635, 3859.2474467478573, 3898.3762597984187, 3910.413437984268, 3933.4296527003426, 3937.42907278443, 10319.1744660427, 10428.5828566858, 10590.099841513558, 10590.947045264878, 10914.56341746337, 10451.268411211142, 10487.108595769292, 10592.0, 10595.629152979764, 10632.46837658114, 7961.681532738741, 8286.58567071656, 8302.211289927447, 8376.623051962677, 8380.413371063967, 5261.973690131525, 5585.469380408855, 5634.183043458757, 5750.8849823002465, 5869.853253668522, 7335.853282934205, 8674.78313042179, 9477.478738669439, 9501.809963800548, 9633.421994680342, 9772.169365604002, 9777.85333219988, 9807.85288220663, 9938.304318697621, 9941.155001824925, 6137.6931153441255, 6453.762125664268, 6924.238333783381, 7435.739749108755, 7511.47419680639, 7596.924030759843, 7654.196309387657, 7672.194419585863, 7773.533587984739, 7779.688812447665, 7166.677499512431, 7246.565206087652, 7282.198958114709, 7324.047873776375, 7429.33136017761, 7100.218975912542, 7148.249433809375, 7195.5322904013165, 7211.8557628848175, 7241.818954525968, 6845.452363810784, 6875.037494750839, 6939.514234003571, 6972.51192416526, 6975.337342952412, 4663.743494107856, 4737.170995075917, 4764.571188592905, 4796.0, 4805.918668299604, 4757.333973243818, 4813.446453657874, 4814.301926220608, 4869.853904382874, 5025.321581586439], "value": 4443.127708183976, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.823515301737, 333.0723933844243, 338.8712289330054, 340.2752137946174, 341.51334348553337, 6655.733770649174, 6662.100616416782, 6679.9332006679915, 6707.7316907323775, 6772.322767723237, 5401.837944861651, 6677.33226677332, 6688.097106890568, 6709.094272273242, 6741.966290168554, 4679.9236175679525, 4777.928331075037, 4780.665353425262, 4845.6123510119105, 4913.82801601944, 4804.231322988305, 4850.2724591311235, 4863.756812159378, 4882.7558622068755, 4893.608511319104, 4073.5111786585653, 4864.489228630996, 4875.317455556207, 4906.705597664134, 4927.926081108786, 4129.958700413003, 4425.690201685868, 4736.407949006374, 4796.112719146969, 4839.588634965997, 4123.670106391512, 4143.6892233082635, 4144.937825932613, 4157.064660451404, 4166.708330416872, 6379.872402551923, 6402.071699603532, 6420.1974753155855, 6450.065220324788, 6452.161219041542, 5824.242848429677, 5830.038043722823, 5833.79581714638, 5833.88332233353, 5839.036558967808, 6876.69054892531, 7055.753048643272, 7115.501914865961, 7127.679254433563, 7141.821599436087, 4749.311349854286, 4761.2382018876815, 4762.3094651275715, 4784.928226076611, 4799.496052914464, 7532.736354227631, 7552.282533159341, 7574.962125189394, 7579.621018949086, 7602.087749470099, 4839.564439200489, 4841.564259216688, 4854.150523658416, 4856.538628830255, 4857.975710121497, 4036.354183330653, 4897.314375987365, 4914.361133052752, 4928.162212423897, 4937.876553086202, 5635.87282543493, 5825.0, 5835.503982161479, 5838.416158384128, 5846.473817356458, 5830.941690583125, 7193.064901562868, 7213.927860721431, 7226.783196504113, 7229.638518074181, 1675.31312162013, 1711.7090094683933, 1714.262866967216, 1714.5627864894373, 1717.2015013019006, 1035.4770840725384, 1086.521930350659, 1095.2005036323528, 1114.509615769059, 1119.2501024313729, 6480.0, 6541.182352205974, 6547.148870646881, 6561.245456772437, 6566.704498297588, 7254.202037775841, 7406.962965185247, 7414.110306763223, 7428.62856857155, 7431.51695139825, 6669.233038200572, 6673.332666733288, 6678.799636010927, 6688.699008544627, 6689.0, 3879.7478163919254, 3930.5479869814767, 3940.802959851996, 3945.0137465633593, 3954.5649978502342, 7552.169261381244, 7775.455718099792, 7799.259070388304, 7905.090914544786, 7908.169642187488, 6710.328967103251, 7236.638168091575, 7732.30409263169, 7746.264104910138, 7758.9612051939375, 7873.25204105609, 7879.369650428067, 7880.448368614142, 7888.368930485547, 7907.2488113629115, 6704.396604305441, 6850.417714494124, 7213.783586492414, 7447.255274472509, 7485.1766305707415, 5115.820946267012, 7368.6315684214505, 7378.741744038933, 7381.889271661037, 7390.48723434035, 5954.910676339945, 6776.322367763184, 7731.84022396645, 7798.649060792164, 7863.213678632091, 4662.974145687943, 4699.788509517011, 4717.858464246078, 4733.148033354029, 4741.217699079613, 7070.46971477131, 7429.8142546436775, 7436.182019977907, 7457.0, 7457.888131677921, 5830.9416905832095, 7071.681774320065, 7180.748673796392, 7187.532810367309, 7223.0, 7174.3184397482155, 7190.856182876209, 7201.315874992077, 7202.459815513753, 7231.602261875437, 7028.840241360118, 7037.7184912602925, 7038.085048943809, 7043.859122817413, 7050.25972272904, 6929.688164032731, 7059.152901651834, 7189.173245076674, 7201.351878330765, 7204.351608355273, 6718.865622687421, 6883.346082122191, 6925.203601585882, 7033.859322813412, 7039.190493093155], "value": 5205.611916534616, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.9329363710448, 755.6184127015856, 756.4100001998443, 760.368893818131, 770.4144849914063, 14895.031822931516, 15003.099814011168, 15261.38954441821, 15272.389104435822, 15272.541823745278, 13807.240601766898, 14007.859921400783, 14037.15777053375, 14041.22773247471, 14041.578752637412, 744.743063643045, 766.5668897073164, 927.3832901120763, 956.6603855631255, 960.0543464687278, 929.0384452092086, 953.5518306395983, 957.8754761880946, 958.2956526952685, 959.0122174160625, 997.4912794474798, 1001.0140012088087, 1007.0382784440878, 1008.0222184481041, 1010.2473657125452, 779.4582764978371, 979.0111986893218, 995.1740055753743, 996.4170959988415, 997.306871724152, 741.4179868803018, 743.178787439882, 744.7914583916457, 746.8431629357841, 748.356413484403, 14687.045342052732, 14756.0, 14756.0, 14756.71270953004, 14769.33537990793, 12533.43599538023, 12739.235645861278, 12915.0, 12940.547080852126, 12969.157004794659, 10177.643782467578, 10253.743656408575, 10312.278140530167, 10330.9483452583, 10351.844722329095, 3464.8094354810473, 3491.720662347006, 3504.1414853360834, 3589.3898037333456, 3752.0, 67900.0, 82470.0, 83620.58189709013, 84200.0, 84762.0, 9348.065193480597, 9551.522423878778, 9592.76018099553, 9601.231901447867, 9601.807963840683, 22563.77436225617, 22759.88620056922, 22773.544529109324, 22843.428914276945, 23007.42481437944, 7429.0, 7457.291557302047, 7458.552486850698, 7689.307962283421, 7703.653335599912, 4009.4787677601726, 4055.8986025349013, 4094.733842300241, 4274.401583778212, 4309.181255561433, 3652.579953305351, 3658.5243918290985, 3694.353488139565, 3696.2792255509917, 3708.1100535871196, 643.7521554201572, 644.1625886347729, 645.1999520594429, 668.0913957018452, 668.7458765669014, 582.1413415212528, 586.6362855029909, 589.8849724303806, 597.9758517418569, 622.4615707413055, 7828.373730101578, 8032.518048917083, 8254.504729716236, 8255.339572834158, 8268.586570671443, 527.2118183315968, 548.2744749427649, 578.1847594891183, 603.1706403694919, 616.3805375597495, 3206.8556914938886, 3731.3283608950105, 3763.473113764075, 3866.941995870064, 3882.6699730522655, 90826.0, 97415.0, 97507.53738693925, 97574.0, 97609.5358569622, 75005.0, 75745.0, 76211.0, 76684.0, 77668.0, 69132.0, 69489.65255173692, 69817.65091174512, 70541.0, 70696.64651676708, 7454.552726836408, 7507.286807753255, 7724.34343080822, 7750.961245193738, 7760.068791745027, 48800.0, 49487.50512494829, 50542.0, 50617.74691126521, 53017.4698253028, 66448.66775666284, 70504.0, 71008.0, 71627.0, 72041.0, 10050.246231532517, 10074.496275186359, 10305.0, 10373.962603739566, 10867.51096200657, 10545.57817687284, 10722.571097156026, 10939.671809845398, 11104.056155226574, 11139.72150696239, 10126.240531959986, 10442.373457592264, 10891.509882055485, 11474.77050459003, 11886.762264754483, 11046.889531104907, 11049.723756906142, 11305.660830175108, 11379.487923043649, 11429.971302582808, 10959.835602466128, 11234.606788762354, 11265.662030138781, 11325.377104259345, 11470.82793758111, 9664.0, 9665.3234273603, 9717.643543648337, 9732.610695572097, 9771.755706107404, 9387.577559009998, 9543.916389662427, 9728.319017668695, 9768.755781105529, 9926.652567160114], "value": 6925.966214915804, "unit": "score"}], "name": "dromaeo_css", "value": 7613.007186598351, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 116.0, 161.0, 130.0, 110.0, 113.0, 112.0, 112.0, 132.0], "value": 121.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 122.0, 158.0, 157.0, 145.0, 172.0, 161.0, 127.0, 213.0, 148.0], "value": 155.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 187.0, 186.0, 185.0, 184.0, 184.0, 183.0, 188.0, 183.0, 185.0], "value": 184.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 79.0, 77.0, 149.0, 56.0, 56.0, 57.0, 66.0, 137.0, 57.0], "value": 80.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 144.0, 76.0, 82.0, 86.0, 123.0, 79.0, 77.0, 126.0], "value": 97.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 121.0, 112.0, 85.0, 86.0, 83.0, 88.0, 86.0, 89.0, 131.0], "value": 96.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 93.0, 92.0, 93.0, 93.0, 94.0, 94.0, 93.0, 92.0], "value": 93.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 85.0, 86.0, 86.0, 85.0, 87.0, 86.0, 86.0, 86.0], "value": 86.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 64.0, 66.0, 63.0, 66.0, 65.0, 64.0, 85.0, 56.0, 58.0], "value": 64.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 65.0, 69.0, 70.0, 76.0, 68.0, 67.0, 68.0, 69.0, 68.0], "value": 68.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 81.0, 81.0, 81.0, 80.0, 77.0, 81.0, 81.0, 85.0], "value": 80.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 144.0, 117.0, 114.0, 149.0, 115.0, 141.0, 147.0, 171.0, 134.0], "value": 136.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 176.0, 167.0, 165.0, 165.0, 202.0, 214.0, 185.0, 165.0], "value": 177.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 70.0, 68.0, 66.0, 69.0, 78.0, 88.0, 75.0, 66.0], "value": 71.2, "unit": "score"}], "name": "kraken", "value": 1514.3, "alertThreshold": 2.0}]} 03:14:22 INFO - Return code: 0 03:14:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:14:22 ERROR - # TBPL SUCCESS # 03:14:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:14:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:14:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:14:22 INFO - 2016-05-15 03:14:22.619 screenresolution[1689:11539] starting screenresolution argv=screenresolution get 03:14:22 INFO - 2016-05-15 03:14:22.622 screenresolution[1689:11539] Display 0: 1600x1200x32@60 03:14:22 INFO - 2016-05-15 03:14:22.628 screenresolution[1690:11542] starting screenresolution argv=screenresolution list 03:14:22 INFO - Available Modes on Display 0 03:14:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:14:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:14:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:14:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:14:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:14:22 INFO - Intel Iris: 03:14:22 INFO - Chipset Model: Intel Iris 03:14:22 INFO - Type: GPU 03:14:22 INFO - Bus: Built-In 03:14:22 INFO - VRAM (Dynamic, Max): 1536 MB 03:14:22 INFO - Vendor: Intel (0x8086) 03:14:22 INFO - Device ID: 0x0a2e 03:14:22 INFO - Revision ID: 0x0009 03:14:22 INFO - Displays: 03:14:22 INFO - Display: 03:14:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:14:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:14:22 INFO - Display Serial Number: 1600x1200 60 03:14:22 INFO - Main Display: Yes 03:14:22 INFO - Mirror: Off 03:14:22 INFO - Online: Yes 03:14:22 INFO - Rotation: Supported 03:14:22 INFO - Return code: 0 03:14:22 INFO - Running post-action listener: _resource_record_post_action 03:14:22 INFO - Running post-run listener: _resource_record_post_run 03:14:23 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 31383040; Write bytes: 655715840; Read time: 195; Write time: 1833 03:14:23 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 348742144; Write bytes: 346400256; Read time: 26466; Write time: 701 03:14:23 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 29597184; Write bytes: 298522624; Read time: 180; Write time: 1096 03:14:23 INFO - Running post-run listener: _upload_blobber_files 03:14:23 INFO - Blob upload gear active. 03:14:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:14:23 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:14:23 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:14:23 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:14:24 INFO - (blobuploader) - INFO - Open directory for files ... 03:14:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:14:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:14:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:14:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:14:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:14:27 INFO - (blobuploader) - INFO - Done attempting. 03:14:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:14:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:14:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:14:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:14:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:14:28 INFO - (blobuploader) - INFO - Done attempting. 03:14:28 INFO - (blobuploader) - INFO - Iteration through files over. 03:14:28 INFO - Return code: 0 03:14:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:14:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:14:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/62635dc3076038a11026f2a96edc941a68309eb301421366fe4101024b824975404c9f0eefe2da6514faa9b0bcd53af536a04077b9dbe1681b4b4524cf51e9b6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bf47c0fcdbef3c539271036e34796091ab45bad14eda426d3954078f390fff59377eddc8a06e6f3f8a996b4aa68673bd4820e5bfa270ae9b32152cefd3d96daf"} 03:14:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:14:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:14:28 INFO - Contents: 03:14:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/62635dc3076038a11026f2a96edc941a68309eb301421366fe4101024b824975404c9f0eefe2da6514faa9b0bcd53af536a04077b9dbe1681b4b4524cf51e9b6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bf47c0fcdbef3c539271036e34796091ab45bad14eda426d3954078f390fff59377eddc8a06e6f3f8a996b4aa68673bd4820e5bfa270ae9b32152cefd3d96daf"} 03:14:28 INFO - Running post-run listener: copy_logs_to_upload_dir 03:14:28 INFO - Copying logs to upload dir... 03:14:28 INFO - mkdir: /builds/slave/test/build/upload/logs 03:14:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.158589 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-05-15 03:14:28.321483) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-15 03:14:28.325190) ========= 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.mdxxC485bu/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.Tr2SNcNOtE/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/62635dc3076038a11026f2a96edc941a68309eb301421366fe4101024b824975404c9f0eefe2da6514faa9b0bcd53af536a04077b9dbe1681b4b4524cf51e9b6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bf47c0fcdbef3c539271036e34796091ab45bad14eda426d3954078f390fff59377eddc8a06e6f3f8a996b4aa68673bd4820e5bfa270ae9b32152cefd3d96daf"} build_url:https://queue.taskcluster.net/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011179 build_url: 'https://queue.taskcluster.net/v1/task/UUIn5fQ0S82GmNcxbe4D2Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/62635dc3076038a11026f2a96edc941a68309eb301421366fe4101024b824975404c9f0eefe2da6514faa9b0bcd53af536a04077b9dbe1681b4b4524cf51e9b6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bf47c0fcdbef3c539271036e34796091ab45bad14eda426d3954078f390fff59377eddc8a06e6f3f8a996b4aa68673bd4820e5bfa270ae9b32152cefd3d96daf"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-15 03:14:28.357753) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-15 03:14:28.358473) ========= 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.mdxxC485bu/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.Tr2SNcNOtE/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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.005104 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-15 03:14:28.411420) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-15 03:14:28.412016) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-15 03:14:28.412362) ========= ========= Total master_lag: 0.19 =========