builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0345 starttime: 1463053472.89 results: success (0) buildid: 20160512025507 builduid: 88eae7355762482f817f8aea258c440a revision: c3f5e6079284a7b7053c41f05d0fe06ff031db03 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 04:44:32.890412) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 04:44:32.890932) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 04:44:32.891256) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 04:44:32.909679) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 04:44:32.910012) ========= 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.P948Ohnh78/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.z1tjb06gAi/Listeners TMPDIR=/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/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-12 04:44:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 154M=0s 2016-05-12 04:44:32 (154 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.090318 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 04:44:33.016444) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 04:44:33.016918) ========= 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.P948Ohnh78/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.z1tjb06gAi/Listeners TMPDIR=/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/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.019926 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 04:44:33.066073) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-12 04:44:33.066497) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c3f5e6079284a7b7053c41f05d0fe06ff031db03 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev c3f5e6079284a7b7053c41f05d0fe06ff031db03 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.P948Ohnh78/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.z1tjb06gAi/Listeners TMPDIR=/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/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-12 04:44:33,071 truncating revision to first 12 chars 2016-05-12 04:44:33,072 Setting DEBUG logging. 2016-05-12 04:44:33,072 attempt 1/10 2016-05-12 04:44:33,072 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c3f5e6079284?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-12 04:44:33,809 unpacking tar archive at: mozilla-central-c3f5e6079284/testing/mozharness/ program finished with exit code 0 elapsedTime=0.997796 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-12 04:44:34.081669) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 04:44:34.082030) ========= script_repo_revision: c3f5e6079284a7b7053c41f05d0fe06ff031db03 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 04:44:34.082463) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 04:44:34.082775) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 04:44:34.096368) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-05-12 04:44:34.096793) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.P948Ohnh78/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.z1tjb06gAi/Listeners TMPDIR=/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/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 04:44:34 INFO - MultiFileLogger online at 20160512 04:44:34 in /builds/slave/test 04:44:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:44:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:44:34 INFO - {'append_to_log': False, 04:44:34 INFO - 'base_work_dir': '/builds/slave/test', 04:44:34 INFO - 'blob_upload_branch': 'Firefox', 04:44:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:44:34 INFO - 'branch': 'Firefox', 04:44:34 INFO - 'buildbot_json_path': 'buildprops.json', 04:44:34 INFO - 'config_files': ('talos/mac_config.py',), 04:44:34 INFO - 'default_actions': ('clobber', 04:44:34 INFO - 'read-buildbot-config', 04:44:34 INFO - 'download-and-extract', 04:44:34 INFO - 'populate-webroot', 04:44:34 INFO - 'create-virtualenv', 04:44:34 INFO - 'install', 04:44:34 INFO - 'run-tests'), 04:44:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:44:34 INFO - 'download_minidump_stackwalk': True, 04:44:34 INFO - 'download_symbols': 'ondemand', 04:44:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:44:34 INFO - 'tooltool.py': '/tools/tooltool.py', 04:44:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:44:34 INFO - '/tools/misc-python/virtualenv.py')}, 04:44:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:44:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:44:34 INFO - 'installer_path': 'installer.exe', 04:44:34 INFO - 'log_level': 'info', 04:44:34 INFO - 'log_name': 'talos', 04:44:34 INFO - 'log_to_console': True, 04:44:34 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:44:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:44:34 INFO - 'opt_config_files': (), 04:44:34 INFO - 'pip_index': False, 04:44:34 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:44:34 INFO - 'cmd': ('bash', 04:44:34 INFO - '-c', 04:44:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:44:34 INFO - 'enabled': True, 04:44:34 INFO - 'halt_on_failure': False, 04:44:34 INFO - 'name': 'check_screen_resolution'},), 04:44:34 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:44:34 INFO - 'cmd': ('bash', 04:44:34 INFO - '-c', 04:44:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:44:34 INFO - 'enabled': True, 04:44:34 INFO - 'halt_on_failure': False, 04:44:34 INFO - 'name': 'check_screen_resolution'},), 04:44:34 INFO - 'run_cmd_checks_enabled': True, 04:44:34 INFO - 'sps_profile': False, 04:44:34 INFO - 'sps_profile_interval': 0, 04:44:34 INFO - 'suite': 'dromaeojs-e10s', 04:44:34 INFO - 'system_bits': '32', 04:44:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:44:34 INFO - 'title': 't-yosemite-r7-0345', 04:44:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:44:34 INFO - 'use_talos_json': True, 04:44:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:44:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:44:34 INFO - 'work_dir': 'build'} 04:44:34 INFO - ##### 04:44:34 INFO - ##### Running clobber step. 04:44:34 INFO - ##### 04:44:34 INFO - Running pre-action listener: _resource_record_pre_action 04:44:34 INFO - Running main action method: clobber 04:44:34 INFO - rmtree: /builds/slave/test/build 04:44:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:44:35 INFO - Running post-action listener: _resource_record_post_action 04:44:35 INFO - ##### 04:44:35 INFO - ##### Finished clobber step (success) 04:44:35 INFO - ##### 04:44:35 INFO - ##### 04:44:35 INFO - ##### Running read-buildbot-config step. 04:44:35 INFO - ##### 04:44:35 INFO - Running pre-action listener: _resource_record_pre_action 04:44:35 INFO - Running main action method: read_buildbot_config 04:44:35 INFO - Using buildbot properties: 04:44:35 INFO - { 04:44:35 INFO - "project": "", 04:44:35 INFO - "product": "firefox", 04:44:35 INFO - "script_repo_revision": "production", 04:44:35 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:44:35 INFO - "repository": "", 04:44:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:44:35 INFO - "buildid": "20160512025507", 04:44:35 INFO - "pgo_build": "False", 04:44:35 INFO - "basedir": "/builds/slave/test", 04:44:35 INFO - "buildnumber": 10, 04:44:35 INFO - "slavename": "t-yosemite-r7-0345", 04:44:35 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 04:44:35 INFO - "platform": "yosemite_r7", 04:44:35 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:44:35 INFO - "branch": "mozilla-central", 04:44:35 INFO - "repo_path": "mozilla-central", 04:44:35 INFO - "revision": "c3f5e6079284a7b7053c41f05d0fe06ff031db03", 04:44:35 INFO - "stage_platform": "macosx64", 04:44:35 INFO - "builduid": "88eae7355762482f817f8aea258c440a", 04:44:35 INFO - "slavebuilddir": "test" 04:44:35 INFO - } 04:44:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 04:44:35 INFO - Running post-action listener: _resource_record_post_action 04:44:35 INFO - ##### 04:44:35 INFO - ##### Finished read-buildbot-config step (success) 04:44:35 INFO - ##### 04:44:35 INFO - ##### 04:44:35 INFO - ##### Running download-and-extract step. 04:44:35 INFO - ##### 04:44:35 INFO - Running pre-action listener: _resource_record_pre_action 04:44:35 INFO - Running main action method: download_and_extract 04:44:35 INFO - mkdir: /builds/slave/test/build/tests 04:44: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')]} 04:44:35 INFO - https://queue.taskcluster.net/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:44:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:44:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:44:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 04:44:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 04:44:41 INFO - Downloaded 1199 bytes. 04:44:41 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 04:44:41 INFO - Using the following test package requirements: 04:44:41 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:44:41 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:44:41 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 04:44:41 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:44:41 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 04:44:41 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:44:41 INFO - u'jsshell-mac.zip'], 04:44:41 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:44:41 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 04:44:41 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:44:41 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:44:41 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 04:44:41 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:44:41 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 04:44:41 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:44:41 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 04:44:41 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:44:41 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 04:44:41 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:44:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:44:41 INFO - https://queue.taskcluster.net/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:44:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:44:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:44:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:44:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:44:43 INFO - Downloaded 18151194 bytes. 04:44:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:44:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:44:46 INFO - Return code: 0 04:44:46 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:44:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:44:46 INFO - https://queue.taskcluster.net/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:44:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:44:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:44:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:44:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:44:52 INFO - Downloaded 18151194 bytes. 04:44:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:44:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:44:55 INFO - Return code: 0 04:44:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:44:55 INFO - https://queue.taskcluster.net/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:44:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:44:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:44:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:44:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:44:56 INFO - Downloaded 10840239 bytes. 04:44:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:44:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:44:56 INFO - Return code: 0 04:44:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:44:56 INFO - https://queue.taskcluster.net/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:44:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:44:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:44:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 04:44:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 04:44:59 INFO - Downloaded 109833510 bytes. 04:44:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:44:59 INFO - mkdir: /builds/slave/test/properties 04:44:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:44:59 INFO - Writing to file /builds/slave/test/properties/build_url 04:44:59 INFO - Contents: 04:44:59 INFO - build_url:https://queue.taskcluster.net/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:45:01 INFO - Running post-action listener: _resource_record_post_action 04:45:01 INFO - Running post-action listener: set_extra_try_arguments 04:45:01 INFO - ##### 04:45:01 INFO - ##### Finished download-and-extract step (success) 04:45:01 INFO - ##### 04:45:01 INFO - ##### 04:45:01 INFO - ##### Running populate-webroot step. 04:45:01 INFO - ##### 04:45:01 INFO - Running pre-action listener: _resource_record_pre_action 04:45:01 INFO - Running main action method: populate_webroot 04:45:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:45:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:45:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:45:01 INFO - '--tppagecycles', 04:45:01 INFO - '7'], 04:45:01 INFO - 'tests': ['tsvgm']}}, 04:45:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:45:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:45:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:45:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:45:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:45:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:45:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:45:01 INFO - 'talos_options': ['--disable-e10s'], 04:45:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:45:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:45:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:45:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:45:01 INFO - 'talos_options': ['--disable-e10s'], 04:45:01 INFO - 'tests': ['damp', 'tps']}, 04:45:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:45:01 INFO - 'tests': ['damp', 'tps']}, 04:45:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:45:01 INFO - 'tests': ['dromaeo_dom']}, 04:45:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:45:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:45:01 INFO - 'tests': ['a11yr', 04:45:01 INFO - 'ts_paint', 04:45:01 INFO - 'tpaint', 04:45:01 INFO - 'sessionrestore', 04:45:01 INFO - 'sessionrestore_no_auto_restore', 04:45:01 INFO - 'tabpaint']}, 04:45:01 INFO - 'other-e10s': {'tests': ['a11yr', 04:45:01 INFO - 'ts_paint', 04:45:01 INFO - 'tpaint', 04:45:01 INFO - 'sessionrestore', 04:45:01 INFO - 'sessionrestore_no_auto_restore', 04:45:01 INFO - 'tabpaint']}, 04:45:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:45:01 INFO - 'ts_paint', 04:45:01 INFO - 'tpaint', 04:45:01 INFO - 'sessionrestore', 04:45:01 INFO - 'sessionrestore_no_auto_restore', 04:45:01 INFO - 'tabpaint']}, 04:45:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:45:01 INFO - 'ts_paint', 04:45:01 INFO - 'tpaint', 04:45:01 INFO - 'sessionrestore', 04:45:01 INFO - 'sessionrestore_no_auto_restore', 04:45:01 INFO - 'tabpaint']}, 04:45:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:45:01 INFO - 'tests': ['a11yr', 04:45:01 INFO - 'ts_paint', 04:45:01 INFO - 'tpaint', 04:45:01 INFO - 'sessionrestore', 04:45:01 INFO - 'sessionrestore_no_auto_restore', 04:45:01 INFO - 'tabpaint']}, 04:45:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:45:01 INFO - 'tests': ['a11yr', 04:45:01 INFO - 'ts_paint', 04:45:01 INFO - 'tpaint', 04:45:01 INFO - 'sessionrestore', 04:45:01 INFO - 'sessionrestore_no_auto_restore', 04:45:01 INFO - 'tabpaint']}, 04:45:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:45:01 INFO - 'tests': ['tsvgx', 04:45:01 INFO - 'tsvgr_opacity', 04:45:01 INFO - 'tart', 04:45:01 INFO - 'tscrollx', 04:45:01 INFO - 'cart']}, 04:45:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:45:01 INFO - 'tsvgr_opacity', 04:45:01 INFO - 'tart', 04:45:01 INFO - 'tscrollx', 04:45:01 INFO - 'cart']}, 04:45:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:45:01 INFO - 'talos_options': ['--disable-e10s'], 04:45:01 INFO - 'tests': ['tp5o']}, 04:45:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:45:01 INFO - 'tests': ['tp5o']}, 04:45:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:45:01 INFO - 'talos_options': ['--disable-e10s', 04:45:01 INFO - '--xperf_path', 04:45:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:45:01 INFO - 'tests': ['tp5n']}, 04:45:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:45:01 INFO - 'talos_options': ['--xperf_path', 04:45:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:45:01 INFO - 'tests': ['tp5n']}}, 04:45:01 INFO - 'talos.zip': {'path': '', 04:45:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:45:01 INFO - Running post-action listener: _resource_record_post_action 04:45:01 INFO - ##### 04:45:01 INFO - ##### Finished populate-webroot step (success) 04:45:01 INFO - ##### 04:45:01 INFO - ##### 04:45:01 INFO - ##### Running create-virtualenv step. 04:45:01 INFO - ##### 04:45:01 INFO - Running pre-action listener: _resource_record_pre_action 04:45:01 INFO - Running main action method: create_virtualenv 04:45:01 INFO - Creating virtualenv /builds/slave/test/build/venv 04:45:01 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:45:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:45:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:45:01 INFO - Using real prefix '/tools/python27' 04:45:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:45:01 INFO - Installing distribute.............................................................................................................................................................................................done. 04:45:04 INFO - Installing pip.................done. 04:45:04 INFO - Return code: 0 04:45:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:45:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:45:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:45:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10551ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105522570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef8958bb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053c0d30>, '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 0x1051e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053b7b20>, '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.P948Ohnh78/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.z1tjb06gAi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/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 04:45:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:45:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:45:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P948Ohnh78/Render', 04:45:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:04 INFO - 'HOME': '/Users/cltbld', 04:45:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:04 INFO - 'LOGNAME': 'cltbld', 04:45:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:04 INFO - 'MOZ_NO_REMOTE': '1', 04:45:04 INFO - 'NO_EM_RESTART': '1', 04:45:04 INFO - 'PAGER': '/bin/cat', 04:45:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:04 INFO - 'PWD': '/builds/slave/test', 04:45:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:04 INFO - 'SHELL': '/bin/bash', 04:45:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z1tjb06gAi/Listeners', 04:45:04 INFO - 'TMPDIR': '/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/', 04:45:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:04 INFO - 'USER': 'cltbld', 04:45:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:04 INFO - 'XPC_FLAGS': '0x0', 04:45:04 INFO - 'XPC_SERVICE_NAME': '0', 04:45:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:05 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:45:05 INFO - Cleaning up... 04:45:05 INFO - Return code: 0 04:45:05 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:45:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:45:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:45:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10551ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105522570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef8958bb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053c0d30>, '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 0x1051e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053b7b20>, '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.P948Ohnh78/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.z1tjb06gAi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/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 04:45:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 04:45:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 04:45:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P948Ohnh78/Render', 04:45:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:05 INFO - 'HOME': '/Users/cltbld', 04:45:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:05 INFO - 'LOGNAME': 'cltbld', 04:45:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:05 INFO - 'MOZ_NO_REMOTE': '1', 04:45:05 INFO - 'NO_EM_RESTART': '1', 04:45:05 INFO - 'PAGER': '/bin/cat', 04:45:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:05 INFO - 'PWD': '/builds/slave/test', 04:45:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:05 INFO - 'SHELL': '/bin/bash', 04:45:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z1tjb06gAi/Listeners', 04:45:05 INFO - 'TMPDIR': '/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/', 04:45:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:05 INFO - 'USER': 'cltbld', 04:45:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:05 INFO - 'XPC_FLAGS': '0x0', 04:45:05 INFO - 'XPC_SERVICE_NAME': '0', 04:45:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:05 INFO - Downloading/unpacking psutil>=0.7.1 04:45:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:45:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:45:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:45:08 INFO - Installing collected packages: psutil 04:45:08 INFO - Running setup.py install for psutil 04:45:08 INFO - building 'psutil._psutil_osx' extension 04:45:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:45:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:45:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:45:08 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 04:45:08 INFO - building 'psutil._psutil_posix' extension 04:45:08 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 04:45:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:45:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:45:08 INFO - ^ 04:45:08 INFO - 1 warning generated. 04:45:08 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 04:45:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:45:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:45:09 INFO - Successfully installed psutil 04:45:09 INFO - Cleaning up... 04:45:09 INFO - Return code: 0 04:45:09 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:45:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:45:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10551ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105522570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef8958bb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053c0d30>, '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 0x1051e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053b7b20>, '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.P948Ohnh78/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.z1tjb06gAi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/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 04:45:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 04:45:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 04:45:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P948Ohnh78/Render', 04:45:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:09 INFO - 'HOME': '/Users/cltbld', 04:45:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:09 INFO - 'LOGNAME': 'cltbld', 04:45:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:09 INFO - 'MOZ_NO_REMOTE': '1', 04:45:09 INFO - 'NO_EM_RESTART': '1', 04:45:09 INFO - 'PAGER': '/bin/cat', 04:45:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:09 INFO - 'PWD': '/builds/slave/test', 04:45:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:09 INFO - 'SHELL': '/bin/bash', 04:45:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z1tjb06gAi/Listeners', 04:45:09 INFO - 'TMPDIR': '/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/', 04:45:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:09 INFO - 'USER': 'cltbld', 04:45:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:09 INFO - 'XPC_FLAGS': '0x0', 04:45:09 INFO - 'XPC_SERVICE_NAME': '0', 04:45:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:09 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:11 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:45:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:45:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:45:11 INFO - Installing collected packages: mozsystemmonitor 04:45:11 INFO - Running setup.py install for mozsystemmonitor 04:45:12 INFO - Successfully installed mozsystemmonitor 04:45:12 INFO - Cleaning up... 04:45:12 INFO - Return code: 0 04:45:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:45:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:45:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10551ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105522570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef8958bb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053c0d30>, '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 0x1051e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053b7b20>, '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.P948Ohnh78/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.z1tjb06gAi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/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 04:45:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:45:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:45:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P948Ohnh78/Render', 04:45:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:12 INFO - 'HOME': '/Users/cltbld', 04:45:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:12 INFO - 'LOGNAME': 'cltbld', 04:45:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:12 INFO - 'MOZ_NO_REMOTE': '1', 04:45:12 INFO - 'NO_EM_RESTART': '1', 04:45:12 INFO - 'PAGER': '/bin/cat', 04:45:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:12 INFO - 'PWD': '/builds/slave/test', 04:45:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:12 INFO - 'SHELL': '/bin/bash', 04:45:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z1tjb06gAi/Listeners', 04:45:12 INFO - 'TMPDIR': '/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/', 04:45:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:12 INFO - 'USER': 'cltbld', 04:45:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:12 INFO - 'XPC_FLAGS': '0x0', 04:45:12 INFO - 'XPC_SERVICE_NAME': '0', 04:45:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:12 INFO - Downloading/unpacking blobuploader==1.2.4 04:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:15 INFO - Downloading blobuploader-1.2.4.tar.gz 04:45:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:45:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:45:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:45:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:16 INFO - Downloading docopt-0.6.1.tar.gz 04:45:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:45:16 INFO - Installing collected packages: blobuploader, requests, docopt 04:45:16 INFO - Running setup.py install for blobuploader 04:45:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:45:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:45:17 INFO - Running setup.py install for requests 04:45:17 INFO - Running setup.py install for docopt 04:45:17 INFO - Successfully installed blobuploader requests docopt 04:45:17 INFO - Cleaning up... 04:45:17 INFO - Return code: 0 04:45:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:45: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')]} 04:45:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45: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')]} 04:45:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10551ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105522570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef8958bb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053c0d30>, '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 0x1051e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053b7b20>, '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.P948Ohnh78/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.z1tjb06gAi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/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 04:45:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:45:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:45:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P948Ohnh78/Render', 04:45:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:17 INFO - 'HOME': '/Users/cltbld', 04:45:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:17 INFO - 'LOGNAME': 'cltbld', 04:45:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:17 INFO - 'MOZ_NO_REMOTE': '1', 04:45:17 INFO - 'NO_EM_RESTART': '1', 04:45:17 INFO - 'PAGER': '/bin/cat', 04:45:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:17 INFO - 'PWD': '/builds/slave/test', 04:45:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:17 INFO - 'SHELL': '/bin/bash', 04:45:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z1tjb06gAi/Listeners', 04:45:17 INFO - 'TMPDIR': '/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/', 04:45:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:17 INFO - 'USER': 'cltbld', 04:45:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:17 INFO - 'XPC_FLAGS': '0x0', 04:45:17 INFO - 'XPC_SERVICE_NAME': '0', 04:45:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:45:18 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-0rFDk0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:45:18 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-R8nszg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:45:18 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-J8K00L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:45:18 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-Nmfqy9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:45:18 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-e6crSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:45:18 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-wZvZnB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:45:18 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-ZBShH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:45:18 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-7l02Dh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:45:18 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-nYya4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:45:19 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-o_Ni8m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:45:19 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-pwKAft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:45:19 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-qe1OKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:45:19 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-UVLSxI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:45:19 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-MrgoIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:19 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-5ZfRZq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:45:19 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-YC7l94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:45:19 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-DzG7W6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:45:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:45:19 INFO - Running setup.py install for manifestparser 04:45:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:45:20 INFO - Running setup.py install for mozcrash 04:45:20 INFO - Running setup.py install for mozdebug 04:45:20 INFO - Running setup.py install for mozdevice 04:45:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:45:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:45:20 INFO - Running setup.py install for mozfile 04:45:20 INFO - Running setup.py install for mozhttpd 04:45:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:45:20 INFO - Running setup.py install for mozinfo 04:45:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:45:20 INFO - Running setup.py install for mozInstall 04:45:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:45:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:45:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:45:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:45:21 INFO - Running setup.py install for mozleak 04:45:21 INFO - Running setup.py install for mozlog 04:45:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:45:21 INFO - Running setup.py install for moznetwork 04:45:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:45:21 INFO - Running setup.py install for mozprocess 04:45:21 INFO - Running setup.py install for mozprofile 04:45:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:45:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:45:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:45:21 INFO - Running setup.py install for mozrunner 04:45:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:45:22 INFO - Running setup.py install for mozscreenshot 04:45:22 INFO - Running setup.py install for moztest 04:45:22 INFO - Running setup.py install for mozversion 04:45:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:45:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:45:22 INFO - Cleaning up... 04:45:22 INFO - Return code: 0 04:45:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:45:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:45:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:45:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:22 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 0x10551ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105522570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef8958bb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053c0d30>, '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 0x1051e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053b7b20>, '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.P948Ohnh78/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.z1tjb06gAi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/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 04:45:22 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 04:45:22 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 04:45:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P948Ohnh78/Render', 04:45:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:22 INFO - 'HOME': '/Users/cltbld', 04:45:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:22 INFO - 'LOGNAME': 'cltbld', 04:45:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:22 INFO - 'MOZ_NO_REMOTE': '1', 04:45:22 INFO - 'NO_EM_RESTART': '1', 04:45:22 INFO - 'PAGER': '/bin/cat', 04:45:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:22 INFO - 'PWD': '/builds/slave/test', 04:45:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:22 INFO - 'SHELL': '/bin/bash', 04:45:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z1tjb06gAi/Listeners', 04:45:22 INFO - 'TMPDIR': '/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/', 04:45:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:22 INFO - 'USER': 'cltbld', 04:45:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:22 INFO - 'XPC_FLAGS': '0x0', 04:45:22 INFO - 'XPC_SERVICE_NAME': '0', 04:45:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:45:22 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-Vi2Ufk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:45:22 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:45:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:45:22 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-D57xVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:45:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:45:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:45:22 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-zIs5_M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:45:23 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)) 04:45:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:45:23 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-KiTSrS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:45:23 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)) 04:45:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:45:23 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-qD6cgd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:45:23 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)) 04:45:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:45:23 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-DNRq2S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:45:23 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)) 04:45:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:45:23 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-Kw42VG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:45:23 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)) 04:45:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:45:23 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-Y1WRJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:45:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:45:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:45:23 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-emWqY1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:45:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:45:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:45:23 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-MIwyQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:45:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 04:45:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:45:23 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-JAiFDl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:45:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:45:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:45:23 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-EyUrKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:45:24 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)) 04:45:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:45:24 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-eDQOUm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:45:24 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)) 04:45:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:45:24 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-3rk2q8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:45:24 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)) 04:45:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:24 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-x9xpDA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:45:24 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)) 04:45:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:45:24 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-WKhNZ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:45:24 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)) 04:45:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:45:24 INFO - Running setup.py (path:/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/pip-r94Cjv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:45:24 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)) 04:45:24 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)) 04:45:24 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)) 04:45:24 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)) 04:45:24 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)) 04:45:24 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)) 04:45:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:27 INFO - Downloading blessings-1.6.tar.gz 04:45:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:45:27 INFO - Installing collected packages: blessings 04:45:27 INFO - Running setup.py install for blessings 04:45:27 INFO - Successfully installed blessings 04:45:27 INFO - Cleaning up... 04:45:27 INFO - Return code: 0 04:45:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:45:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:45:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:45:27 INFO - Reading from file tmpfile_stdout 04:45:27 INFO - Current package versions: 04:45:27 INFO - blessings == 1.6 04:45:27 INFO - blobuploader == 1.2.4 04:45:27 INFO - docopt == 0.6.1 04:45:27 INFO - manifestparser == 1.1 04:45:27 INFO - mozInstall == 1.12 04:45:27 INFO - mozcrash == 0.17 04:45:27 INFO - mozdebug == 0.1 04:45:27 INFO - mozdevice == 0.48 04:45:27 INFO - mozfile == 1.2 04:45:27 INFO - mozhttpd == 0.7 04:45:27 INFO - mozinfo == 0.9 04:45:27 INFO - mozleak == 0.1 04:45:27 INFO - mozlog == 3.2 04:45:27 INFO - moznetwork == 0.27 04:45:27 INFO - mozprocess == 0.22 04:45:27 INFO - mozprofile == 0.28 04:45:27 INFO - mozrunner == 6.11 04:45:27 INFO - mozscreenshot == 0.1 04:45:27 INFO - mozsystemmonitor == 0.0 04:45:27 INFO - moztest == 0.7 04:45:27 INFO - mozversion == 1.4 04:45:27 INFO - psutil == 3.1.1 04:45:27 INFO - requests == 1.2.3 04:45:27 INFO - wsgiref == 0.1.2 04:45:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:45:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:45:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:45:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10551ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105522570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef8958bb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053c0d30>, '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 0x1051e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053b7b20>, '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.P948Ohnh78/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.z1tjb06gAi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/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 04:45:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:45:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:45:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P948Ohnh78/Render', 04:45:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:27 INFO - 'HOME': '/Users/cltbld', 04:45:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:27 INFO - 'LOGNAME': 'cltbld', 04:45:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:27 INFO - 'MOZ_NO_REMOTE': '1', 04:45:27 INFO - 'NO_EM_RESTART': '1', 04:45:27 INFO - 'PAGER': '/bin/cat', 04:45:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:27 INFO - 'PWD': '/builds/slave/test', 04:45:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:27 INFO - 'SHELL': '/bin/bash', 04:45:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z1tjb06gAi/Listeners', 04:45:27 INFO - 'TMPDIR': '/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/', 04:45:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:27 INFO - 'USER': 'cltbld', 04:45:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:27 INFO - 'XPC_FLAGS': '0x0', 04:45:27 INFO - 'XPC_SERVICE_NAME': '0', 04:45:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:45:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:45:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:45:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:45:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:45:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:45:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:45:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:45:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:45:27 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:45:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:45:27 INFO - Cleaning up... 04:45:27 INFO - Return code: 0 04:45:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:45:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:45:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:45:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10551ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105522570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef8958bb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053c0d30>, '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 0x1051e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053b7b20>, '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.P948Ohnh78/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.z1tjb06gAi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/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 04:45:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:45:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:45:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P948Ohnh78/Render', 04:45:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:27 INFO - 'HOME': '/Users/cltbld', 04:45:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:27 INFO - 'LOGNAME': 'cltbld', 04:45:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:27 INFO - 'MOZ_NO_REMOTE': '1', 04:45:27 INFO - 'NO_EM_RESTART': '1', 04:45:27 INFO - 'PAGER': '/bin/cat', 04:45:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:27 INFO - 'PWD': '/builds/slave/test', 04:45:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:27 INFO - 'SHELL': '/bin/bash', 04:45:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z1tjb06gAi/Listeners', 04:45:27 INFO - 'TMPDIR': '/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/', 04:45:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:27 INFO - 'USER': 'cltbld', 04:45:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:27 INFO - 'XPC_FLAGS': '0x0', 04:45:27 INFO - 'XPC_SERVICE_NAME': '0', 04:45:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:28 INFO - Downloading/unpacking jsonschema 04:45:28 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 04:45:28 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 04:45:28 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 04:45:28 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 04:45:28 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 04:45:28 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 04:45:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:45:30 INFO - Downloading/unpacking functools32 (from jsonschema) 04:45:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:30 INFO - Downloading functools32-3.2.3-2.tar.gz 04:45:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:45:31 INFO - warning: no files found matching '*.txt' 04:45:31 INFO - no previously-included directories found matching 'build' 04:45:31 INFO - no previously-included directories found matching 'dist' 04:45:31 INFO - no previously-included directories found matching '.git*' 04:45:31 INFO - Installing collected packages: jsonschema, functools32 04:45:31 INFO - Running setup.py install for functools32 04:45:31 INFO - warning: no files found matching '*.txt' 04:45:31 INFO - no previously-included directories found matching 'build' 04:45:31 INFO - no previously-included directories found matching 'dist' 04:45:31 INFO - no previously-included directories found matching '.git*' 04:45:31 INFO - Successfully installed jsonschema functools32 04:45:31 INFO - Cleaning up... 04:45:31 INFO - Return code: 0 04:45:31 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:45:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:45:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:45:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:45:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:45:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:45:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10551ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105522570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef8958bb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053c0d30>, '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 0x1051e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053b7b20>, '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.P948Ohnh78/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.z1tjb06gAi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/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 04:45:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 04:45:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 04:45:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P948Ohnh78/Render', 04:45:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:45:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:45:31 INFO - 'HOME': '/Users/cltbld', 04:45:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:45:31 INFO - 'LOGNAME': 'cltbld', 04:45:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:45:31 INFO - 'MOZ_NO_REMOTE': '1', 04:45:31 INFO - 'NO_EM_RESTART': '1', 04:45:31 INFO - 'PAGER': '/bin/cat', 04:45:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:45:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:45:31 INFO - 'PWD': '/builds/slave/test', 04:45:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:45:31 INFO - 'SHELL': '/bin/bash', 04:45:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z1tjb06gAi/Listeners', 04:45:31 INFO - 'TMPDIR': '/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/', 04:45:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:45:31 INFO - 'USER': 'cltbld', 04:45:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:45:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:45:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:45:31 INFO - 'XPC_FLAGS': '0x0', 04:45:31 INFO - 'XPC_SERVICE_NAME': '0', 04:45:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:45:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:45:31 INFO - Downloading/unpacking flake8 04:45:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:34 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:45:34 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:45:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:34 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:45:34 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:45:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:34 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:45:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:45:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:45:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:45:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:45:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:45:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:45:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:45:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:45:35 INFO - no previously-included directories found matching 'docs/_build' 04:45:35 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:45:35 INFO - Running setup.py install for pep8 04:45:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:45:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:45:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:45:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:45:35 INFO - no previously-included directories found matching 'docs/_build' 04:45:35 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:45:35 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:45:35 INFO - Cleaning up... 04:45:35 INFO - Return code: 0 04:45:35 INFO - Running post-action listener: _resource_record_post_action 04:45:35 INFO - Running post-action listener: _start_resource_monitoring 04:45:35 INFO - Starting resource monitoring. 04:45:35 INFO - ##### 04:45:35 INFO - ##### Finished create-virtualenv step (success) 04:45:35 INFO - ##### 04:45:35 INFO - ##### 04:45:35 INFO - ##### Running install step. 04:45:35 INFO - ##### 04:45:35 INFO - Running pre-action listener: _resource_record_pre_action 04:45:35 INFO - Running main action method: install 04:45:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:45:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:45:35 INFO - Reading from file tmpfile_stdout 04:45:35 INFO - Detecting whether we're running mozinstall >=1.0... 04:45:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:45:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:45:36 INFO - Reading from file tmpfile_stdout 04:45:36 INFO - Output received: 04:45:36 INFO - Usage: mozinstall [options] installer 04:45:36 INFO - Options: 04:45:36 INFO - -h, --help show this help message and exit 04:45:36 INFO - -d DEST, --destination=DEST 04:45:36 INFO - Directory to install application into. [default: 04:45:36 INFO - "/builds/slave/test"] 04:45:36 INFO - --app=APP Application being installed. [default: firefox] 04:45:36 INFO - mkdir: /builds/slave/test/build/application 04:45:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:45:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:46:09 INFO - Reading from file tmpfile_stdout 04:46:09 INFO - Output received: 04:46:09 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:46:09 INFO - Running post-action listener: _resource_record_post_action 04:46:09 INFO - ##### 04:46:09 INFO - ##### Finished install step (success) 04:46:09 INFO - ##### 04:46:09 INFO - ##### 04:46:09 INFO - ##### Running run-tests step. 04:46:09 INFO - ##### 04:46:09 INFO - Running pre-action listener: _resource_record_pre_action 04:46:09 INFO - Running main action method: run_tests 04:46:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:46:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:46:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:46:09 INFO - 2016-05-12 04:46:09.798 screenresolution[2494:75749] starting screenresolution argv=screenresolution get 04:46:09 INFO - 2016-05-12 04:46:09.801 screenresolution[2494:75749] Display 0: 1024x768x32@60 04:46:09 INFO - 2016-05-12 04:46:09.806 screenresolution[2495:75751] starting screenresolution argv=screenresolution list 04:46:09 INFO - Available Modes on Display 0 04:46:09 INFO - 1024x768x32@60 800x600x32@75 800x600x32@60 04:46:10 INFO - 640x480x32@75 640x480x32@60 Graphics/Displays: 04:46:10 INFO - Intel Iris: 04:46:10 INFO - Chipset Model: Intel Iris 04:46:10 INFO - Type: GPU 04:46:10 INFO - Bus: Built-In 04:46:10 INFO - VRAM (Dynamic, Max): 1536 MB 04:46:10 INFO - Vendor: Intel (0x8086) 04:46:10 INFO - Device ID: 0x0a2e 04:46:10 INFO - Revision ID: 0x0009 04:46:10 INFO - Displays: 04:46:10 INFO - Display: 04:46:10 INFO - Resolution: 1024 x 768 @ 60 Hz 04:46:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:46:10 INFO - Display Serial Number: 1024x768 60 04:46:10 INFO - Main Display: Yes 04:46:10 INFO - Mirror: Off 04:46:10 INFO - Online: Yes 04:46:10 INFO - Rotation: Supported 04:46:10 INFO - Return code: 0 04:46:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:46:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:46:10 INFO - Python 2.7.3 04:46:10 INFO - Return code: 0 04:46:10 INFO - grabbing minidump binary from tooltool 04:46:10 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')]} 04:46:10 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1053c0d30>, '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 0x1051e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1053b7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:46:10 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 04:46:10 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 04:46:10 INFO - Calling ['/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'] with output_timeout 600 04:46:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:46:10 INFO - Return code: 0 04:46:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:46:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:46:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:46:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:46:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:46:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:46:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:46:11 INFO - Return code: 0 04:46:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0345', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:46:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0345 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:46:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.P948Ohnh78/Render', 04:46:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:11 INFO - 'HOME': '/Users/cltbld', 04:46:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:11 INFO - 'LOGNAME': 'cltbld', 04:46:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:46:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:46:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:11 INFO - 'MOZ_NO_REMOTE': '1', 04:46:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:46:11 INFO - 'NO_EM_RESTART': '1', 04:46:11 INFO - 'PAGER': '/bin/cat', 04:46:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:11 INFO - 'PWD': '/builds/slave/test', 04:46:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:46:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:11 INFO - 'SHELL': '/bin/bash', 04:46:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z1tjb06gAi/Listeners', 04:46:11 INFO - 'TMPDIR': '/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/', 04:46:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:11 INFO - 'USER': 'cltbld', 04:46:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:11 INFO - 'XPC_FLAGS': '0x0', 04:46:11 INFO - 'XPC_SERVICE_NAME': '0', 04:46:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0345', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:46:11 INFO - mozversion application_buildid: 20160512025507 04:46:11 INFO - mozversion application_changeset: c3f5e6079284a7b7053c41f05d0fe06ff031db03 04:46:11 INFO - mozversion application_display_name: Nightly 04:46:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:46:11 INFO - mozversion application_name: Firefox 04:46:11 INFO - mozversion application_remotingname: firefox 04:46:11 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:46:11 INFO - mozversion application_vendor: Mozilla 04:46:11 INFO - mozversion application_version: 49.0a1 04:46:11 INFO - mozversion platform_buildid: 20160512025507 04:46:11 INFO - mozversion platform_changeset: c3f5e6079284a7b7053c41f05d0fe06ff031db03 04:46:11 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:46:11 INFO - mozversion platform_version: 49.0a1 04:46:11 INFO - using testdate: 1463053571 04:46:11 INFO - actual date: 1463053571 04:46:11 INFO - starting webserver on 'localhost:58488' 04:46:11 INFO - SUITE-START | Running 2 tests 04:46:11 INFO - TEST-START | dromaeo_css 04:46:11 INFO - operating with platform_type : mac_ 04:46:11 INFO - Initialising browser for dromaeo_css test... 04:46:11 INFO - TEST-INFO | started process 2513 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/tmpeehxC0/profile http://localhost:58488/getInfo.html) 04:46:14 INFO - PROCESS | 2513 | __metrics Screen width/height:1024/768 04:46:14 INFO - PROCESS | 2513 | colorDepth:24 04:46:14 INFO - PROCESS | 2513 | Browser inner width/height: 1024/605 04:46:14 INFO - PROCESS | 2513 | __metrics 04:46:14 ERROR - PROCESS | 2513 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 04:46:15 INFO - TEST-INFO | 2513: exit 0 04:46:15 INFO - Browser initialized. 04:46:15 INFO - Running cycle 1/1 for dromaeo_css test... 04:46:15 INFO - TEST-INFO | started process 2516 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/tmpeehxC0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:46:27 INFO - PROCESS | 2516 | RSS: Main: 249036800 04:46:27 INFO - PROCESS | 2516 | 04:47:16 INFO - PROCESS | 2516 | 1463053636861 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] 04:49:27 INFO - PROCESS | 2516 | Cycle 1(1): loaded http://localhost:58488/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58488/tests/dromaeo/cssquery-ext.html) 04:49:28 INFO - PROCESS | 2516 | RSS: Main: 258977792 04:49:28 INFO - PROCESS | 2516 | 04:52:31 INFO - PROCESS | 2516 | Cycle 1(1): loaded http://localhost:58488/tests/dromaeo/cssquery-ext.html (next: http://localhost:58488/tests/dromaeo/cssquery-jquery.html) 04:52:32 INFO - PROCESS | 2516 | RSS: Main: 269275136 04:52:32 INFO - PROCESS | 2516 | 04:55:33 INFO - PROCESS | 2516 | Cycle 1(1): loaded http://localhost:58488/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58488/tests/dromaeo/cssquery-mootools.html) 04:55:34 INFO - PROCESS | 2516 | RSS: Main: 271634432 04:55:34 INFO - PROCESS | 2516 | 04:58:36 INFO - PROCESS | 2516 | Cycle 1(1): loaded http://localhost:58488/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58488/tests/dromaeo/cssquery-prototype.html) 04:58:36 INFO - PROCESS | 2516 | RSS: Main: 273342464 04:58:36 INFO - PROCESS | 2516 | 05:01:39 INFO - PROCESS | 2516 | Cycle 1(1): loaded http://localhost:58488/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58488/tests/dromaeo/cssquery-yui.html) 05:01:40 INFO - PROCESS | 2516 | RSS: Main: 276119552 05:01:40 INFO - PROCESS | 2516 | 05:04:43 INFO - PROCESS | 2516 | Cycle 1(1): loaded http://localhost:58488/tests/dromaeo/cssquery-yui.html (next: http://localhost:58488/tests/dromaeo/cssquery-dojo.html) 05:04:43 INFO - PROCESS | 2516 | RSS: Main: 277245952 05:04:43 INFO - PROCESS | 2516 | 05:04:43 INFO - PROCESS | 2516 | __start_tp_report 05:04:43 INFO - PROCESS | 2516 | _x_x_mozilla_page_load 05:04:43 INFO - PROCESS | 2516 | _x_x_mozilla_page_load_details 05:04:43 INFO - PROCESS | 2516 | |i|pagename|runs| 05:04:43 INFO - PROCESS | 2516 | |0;dojo.html;3356.412627790137;3357.160709822546;3378.3412234614243;3396.184915620252;3399.2691571312193;5579.581531385147;5608.551315894729;5636.7745290188395;5667.4615911488345;5718.313802343714;5559.638623489471;5582.246396736439;5599.776008959637;5648.4633959773755;5711.200431939532;4202.411662367271;5082.695038297705;5136.563392111675;5216.26972223889;5268.367795864501;449.1310961477049;5111.64218504703;5155.432902380735;5195.168772996321;5198.090334191501;4686.320483529886;5119.283300337956;5145.562627176676;5152.175651895716;5157.510036546522;5177.792888284464;5190.351206099238;5197;5201.9075994041295;5222.060029194743;4716.221823399169;5047.419546752134;5102.897942041139;5131.3329267195395;5196.480351964811;5485.149801780731;5602.1596760485845;5604.327480702301;5618.803341883015;5667.263255776723;5123.538881500683;5915.142304365843;6113.510919126459;6143.201383820119;6165.630062196283;6096.768593606644;6136.641621527207;6153.353897840707;6261.655608941506;6264.718087686065;5712.714364281853;5712.80005199816;5717.399673034354;5724.112762521816;5733.770649174028;6096.073160812928;6160.8767824643255;6310.337414571404;6319.9052014218905;6319.905201421982;4834.492378300298;5959.404059594006;5960.940390596039;5978.940210597925;5988.6406815590335;6147.262328520606;6154.815355539341;6175.660338681416;6176.35148309421;6212.72042758077;5961.076033214858;5980.641161530322;6045.818625441244;6101.176341193882;6134.263888333428;6052.334243233241;6058.515318774487;6067.1202675611785;6086.117512960596;6086.939130608637;4422.668299877522;4427.693830320063;4811.663183577116;4826.61387089032;4865.537773911473;4269.402283680287;4284.1645879053285;4299.699021068495;4314.201872653569;4339.7613131278085;5544.473275038865;5645.491905728422;5666.206731057655;5683.8010669626365;5704.942950570524;4694.342792009122;6152.507799376039;6155.169052177988;6188.319284878659;6222.097795819672;5586.888262234733;5658.519025882846;5659.518940890071;5680.715964201773;5688.431156884361;5270.657407268592;5282.102042652758;5290.1800220965815;5293.788248470018;5305.44292849253;6301.936980630227;6306.810795676137;6309.179806625109;6315.778947736899;6329.208848893888;6074.514038876968;6125.264968203844;6150.661713605795;6159.29168145669;6184.969075154775;6279.686015699288;6289.842753931005;6305.684715764285;6306.085617585332;6311.147995020613;5137.460566640449;5912.67480288571;6061.302950160788;6064.151018857276;6087.9391206087375;5224.772178538116;5890.293164820249;5900.937831190427;6115.266168059861;6124.38756124384;5921.259842519685;5953.523718102628;5959;5966.343702192668;5979.431953964367;5799.188113664006;5842.474177323891;5937.762489500372;5954.374790647093;5963.7912673056235;5674.2623458950075;5721.399253078367;5741.942580574141;5742.511886489695;5751.971240143773;4919.655624106278;5902.468777810018;5914.378990206139;5917.142014407801;5936.079907614327;5735.770569177186;5754.798582049608;5779.7110144493445;5802.47777700009;5813.273340832396 05:04:43 INFO - PROCESS | 2516 | |1;ext.html;481.8867566121963;529.8569386265708;540.0333403208257;558.0847410247197;570.9286339207599;38662.61337386629;41420.47925926296;41459.79270103649;41469.37795933063;41557.16885662293;42368;42941.570584294146;43096.78451607745;43109.568904310945;43140.78429607856;9621.567029483655;9824.90175098247;9828.312018158731;9833;9853.950730246339;8321.375896807733;9565.186959108483;9652;9685.709428717115;9705.660301889435;8201.58992050398;9713.22294216461;9720.173785228264;9722.611095556169;9732.56203470842;9626.663066792664;9651.131398174162;9677.612895484172;9687.660931867422;9701.563429645683;8582.914170858274;9146.222571081466;9583.712488625353;9598.616055357777;9601.567929443121;39151;39499.60500394988;39549.80225098885;39596.40605390922;39618;3206.3907857506997;3360.1431634932937;3366.3099064691924;3417.094469965461;3618.095476130967;28120.437591248265;29665.11004669863;29665.258368540744;29725.959591414412;29747;2633.789296856247;2642.167717169105;2812.9015484457946;2830.7735381169455;2833.39082097348;471885;477131;477342;477681;478602.60698696633;62820.68589657023;63233.68383158055;63775.72448551003;63929;64014.679926601;66871.99692004624;67465.66267168632;67788;67921.3207867925;68094.3190568088;14220.00459967813;16410.671786564202;16871.493855184362;16899.493015209562;16927.407540736014;12992.805107923388;13036.608901732963;13066.82398584132;13112.213267203999;13120.212787232796;17173.828261717474;17710.02594857295;17927.551811204565;17937.37219197322;17949.641007179784;6875.4155896749335;6969.58182509051;6970.198427180939;7013.228544860061;7034.331738484836;931.3154831199063;937.7749340158313;1025.1593693171676;1068.3056013591229;1073.3988966178965;28572.85713571419;29819.105426837228;29878.25304367408;30316.696833031827;30547.847260763552;822.995885020583;991.8313886639289;1007.6422869881234;1018.0379541333517;1135.2961164078324;12365.505379784889;12929.965602751758;13609.047366684237;15116.09303441775;15439.459618913284;634442;641827;648526;649046;650335;61192;61523.23145458465;61526.76946461046;61583.692081540205;61861;1336.4988129451456;1390.0269811132248;1446.3563714147103;1480.8297045839652;1481.1705444950646;22448.77551224467;26550.601740973514;28734.712652873204;29252;29519.55720664149;51685.48314516957;52188;52573.737131315625;52618.158364457064;52651;585089;588819;589092;589880;591389;39066;39671.2065758689;39709.40435893406;39900;39937.600623993385;45727.77136114298;46206.76896615495;46630;47100.7644961773;47371.52628473809;37437.62562374341;37982.62017379901;38042.80978595089;38482.80758596301;38998.80500597479;37359.06602334963;37628.623713762514;37731.43402848904;37848.05379865415;38115.61884381121;37090.629093709795;37242.441363380116;37518.2496350066;38293.61706383012;38834.02914927036;32623.34753304968;33038.5044224332;33799;33819.3236135271;33891.15272118217;19509.60980780348;21449.946810097408;23507.942142603966;25613.3596660085;26396.4720705581 05:04:43 INFO - PROCESS | 2516 | |2;jquery.html;389.9103206262558;393.6968534228644;394.1820722001846;395.0794648469067;416.444047196992;15269.847301526981;20271.898640506795;20919.790802091975;21541.784582154174;21576.784232157675;17254.741178882294;20193.99030048499;20620.793792062075;21295.57408851822;21554;5561.165825126233;6032.457078862902;6051.848703782397;6053;6091.329953705111;5548.667079975195;5893.469587737103;5914;6025.578209525335;6028.1861948636815;5692.943070569303;5935.584509084366;5965.448983264361;5966.105084237356;5989.9101513477335;6066.514678825705;6097.7804439111915;6120.602160859575;6133.6626485543275;6144.754209831602;5667.319921609392;5870.765169393219;5871.442212989802;5879.500242479395;5921.289445266552;13626.591202263948;13776.24230667313;13845.723085538233;13919.930400348036;22034;1785.866060045489;1901.5531350132708;1985.384530795458;1993.4219076467807;2176.4994051368276;10008.59965601375;10025;10064.849027264598;10146.949265253626;10165.949170254175;1742.8605711543046;1767.0281345260184;1767.7701898753207;1775.7158854583333;1810.4478134169074;112356;118002.40998795036;133525;135607.32196339054;136719;9444.386114902509;10331.01855323743;11232.719182020515;11919.463624136935;11977.401129943468;15261.160636164896;15329.616759580877;15339.079655220723;15346.616334591501;15364.462243821636;8570.142985701379;8634.22291993711;8686.218240358397;8719.476831390137;8765.824683506295;8486.024107227748;8522.57387130641;8606.784830379165;8615.052344242129;8627.525486098324;4580.977095114503;4646.256598944153;4666.1536462757695;4694.507076756891;4729.5743383095;1943.3489780923549;1994.9601007979759;1997.2909617085725;1999.780024197337;2028.4523178741656;901.4230892228848;918.5223683684446;923.5290002098843;989.8267803134567;1032.5301987595626;18404.723929141073;20256.088476018616;21208.25771098004;21359.359219223446;21458.678119828215;980.8871979722423;983.2969426859829;1030.4384110659707;1042.2912419554775;1052.2581579986092;9107.806691449745;10099.192064634812;11212.383318917375;11213.359247947299;11214.990650841464;255918.72040639678;256441;258590;259017;259110;105214;135685;141476;143156;144514.5548544501;1017.5471914997755;1020.9642662506925;1021.2953062386929;1023.0025724918145;1035.570238351076;18736.53158671044;18779.530511737317;18832;19072;19079.713804293224;35685.46471802977;45413.09173816571;54909.90180196454;55147.44852551423;55789;64455.67772161297;65549;77677.61161194158;83114.58442707748;86547;6399.7440102395385;6741.797746067626;7107.82230444243;8014.358851292;8692;11699.649010529698;12220.87779122233;12960.287184204959;13334.199948002763;13352.332383380986;10381.065704086366;10795.56817727282;10957.726056848323;11326.773464530499;11468;8853.557322133996;10169;10694.037536621741;10962.177836662442;11397.601083962021;10072.395656260647;10804.513796879002;11234;11670;11817.527298907948;5574.832755017356;7391.926080739124;7450.435408564045;7742.961285193538;7820.608969551386;9565.473898935625;10687.984641459048;10713.89286107129;10796.082333001783;10974.176936729618 05:04:43 INFO - PROCESS | 2516 | |3;mootools.html;1608.9517314480565;1625.0249850089945;1816.191794651379;1864.5431869192041;1910.5032691500205;8344.582770861447;8830.293576513879;8912.688055918044;9404.153626173644;9407.153356197941;8168.50988940664;8522.14778522149;9020.503872287021;9159.358844880844;9173.266138708905;2446.3150317911013;2479.962800557984;2480.9751902481016;2483.217786397288;2489.614109812982;2433.1605596069417;2498.287987923442;2504.7620476054744;2504.949901001979;2507.6865391826022;2437.073911913479;2449.8530088194766;2451.142100264911;2457.34129462612;2477.3311205974464;2252.0991603358625;2290.6449500327476;2426.660267562534;2435.6224785158242;2443.6701045358914;2406.0856874387796;2416.6375043743406;2424.696912885889;2437.427204606916;2438.2319569335605;8487.726840973903;8942;9232.446053236827;9298.674546390845;9313.720588382359;4956.909479914413;5521.3926468088475;5594.216809646653;5674.035413979634;5716.314042314907;3631.182983828644;3651.6713495785507;3657.104009517685;3711.628837116267;3721.274351501458;790.7864876483375;982.3172894838091;1009.0968583118068;1032.267090365841;1063.1123012284675;12219.877801221963;12428.62714118578;12430.751384972249;12456.688582785413;12456.94116000141;4927.137750893579;4941.925871111936;4967.602591792648;4987.152184128634;5014.7994080236385;10508.527116279633;10511.211659125596;10511.57953681844;10517.42154181512;10560.630377936735;5493.285872836505;5571.164325351229;5604.943950560442;5605.355384130877;5617.438256174431;4263.936040959388;4496.977515112403;4918.926216106761;5035.874103147451;5070.3155074064525;4085.8569950051606;4116.997956345938;4182.605970461131;4199.4330765346285;4215.641670458045;1351.6283022168786;1367.7401293754156;1374.8556401577691;1375.7454870848926;1378.400395827818;845.9636944742764;854.4873076154257;906.850369688994;909.5997760985146;918.2011649864661;5820.650760954356;5861.736221870026;5981.641101533922;5991;5995.280566332068;916.7433118726767;930.9301802364714;944.6976967370513;969.3214749675255;1011.6054738651932;3857.9421308680394;3916.941245881314;3995.001249687578;4012.1373904610064;4059.6752259819145;11115.166362522843;11556.76886462266;11570.363630000262;11623.12826537996;11628.53485860573;9904.504774761233;11380.94309528447;11433;11574.247673901338;11579.826302605468;8408.663653453916;8589.65641374338;8618.353623478264;8646.048934617187;8681.6527338907;4948.703077815343;5111.696517388191;5798.82603521895;5927.940720592739;5971.223740913653;10153.730783652043;10375.221858360503;10595.788084238426;10644.627438039632;10709.303895246783;7970.920290797249;10466.476676166008;10522.526486307981;10698.679039628823;10841.07850832688;6716.429103526255;7109.928900710926;7249.202587715453;7260.201377848327;7368.410527157707;7401.33387995083;7931.611967905709;8801.427907186013;8894.955525222333;8911;7364.631768411451;7754.457187996786;7832.765017049498;7836.686532538634;7859.724909628364;7549.131849837339;7595.430342724208;7657.88513172314;7784.727534536264;7822.06135263772;7237.819054523679;7267.273272672691;7335.77992660221;7347.05303094691;7359.963200183965;3885.579720182949;4247.554006829362;4376.6717496187275;4385.561443855589;4503;4922;4986.102501549756;5005.749712514433;5015.097282489187;5051.974740126276 05:04:43 INFO - PROCESS | 2516 | |4;prototype.html;276.5437028902311;301.52058227418416;321.8503395920897;322.253982031597;325.8094015001224;4842.66101372904;5049.924251136236;5482.847362766411;5577.63745356552;5579.776808927643;5107.157319042353;5380.166074258486;5410.675359478434;5410.729463526827;5474.72626368682;4110.280700877342;4157.833686652545;4164.750114993095;4166.749995000295;4170.499540055198;3736.532933383327;3741.681957033655;3919.1377896862646;4102.548719640837;4179.602937720927;3818;3981.3430783920617;4028.879133625996;4048.412980117881;4062.1672557125867;3847.7306588538813;4025.2553277643437;4047.4535937648484;4085.142120154771;4155.231282212785;2975.8065725727756;3542.36237477254;3550.609432962372;3557.6975957043683;3594.245208506217;4787.832425865113;5165.354330708661;5177.867390228895;5197.8960420791755;5225.589090945461;4120.1553681495225;4287;4429.534898835608;4597.287420449836;4644.6052085572765;4384.934225986581;5020.874478138039;5694.668618857973;5736.6558006519745;5754.683492407916;3179.761517886168;3616.385214513539;3626.854925802991;3704.666580007812;3885.8834234972996;5847.415258474161;5917.260342457193;5942.3760505146765;5956.702164891738;5990.760369585211;3909.4722212500947;3925.9607403925597;3941.684665226775;3943.8422463101156;3970.6029397060065;3697.242065376591;3919.7452165609143;3983.063979964706;4008.2985477541897;4041.656459201001;4618.822200338892;4628.652851036119;4648.047150334173;4667.719936803803;4682.391289132391;4886.315915771795;4985.376827896513;5405.675659460445;5734;5778.88442231153;1241.472374240944;1266.6326765237977;1361.5098564516766;1364.1542243808901;1365.556194236877;755.6372940988356;760.3688938181407;762.5729591428818;807.6255807924682;839.5508403004363;4130.905310092828;4150.086980864205;4206.8737937861915;4322.3516472528745;4365.519792822787;3873.2640798248244;4301.956980430218;4672.719636821734;4897.804087836518;5068.518490743373;3945.980270098631;4301.913961720748;4449.377087207794;4479.350494178326;4485.407680273521;2377.833551651368;2398.0407836865393;2478.3556275368533;2604.5311843867908;2648.7086420494115;4565.634749220055;4637.698549594265;5027.3464449621315;5097.719625420564;5119.59043276537;4223.619874211336;4452.977735111303;4806.678163505064;4954.851354459372;4976.954839483749;4637.234856248749;4797.496262892416;4906.681065730716;5144.148106681667;5181.922271165936;4129.793510324532;4748.952510474851;4892.8532144035735;4936.160852655129;4994.875128121681;4068.7355321905166;4115.650169735486;4175.937360939528;4594.104149690912;4808.3989501312335;4457.68796184264;4503;4547.613452856544;4770.522947705202;5255.9211611825085;2996.970030299669;3029.6818834022993;3434.914127146841;3440.4323286657423;3493.9867438442566;4475.888102797456;4700.694454860423;4885.65800393969;4892.951070489249;5089.694618322913;4930.8767280818265;5170.224466330081;5181.3005244291535;5525.475079867406;5693.601447898607;4676.392069031004;5193.92209116856;5624.6625202487985;5683.857903552444;5725.595249995095;4771.475137734916;5030.572401345927;5381.67709937405;5510.641808282448;5526.751296191601;4768.690035147704;5466.851961088216;5555.7499912504845;5725.484706376446;5775.7400916957995;5567;5567.526760225426;5572.804951826666;5606.85982850432;5678.858028549319 05:04:43 INFO - PROCESS | 2516 | |5;yui.html;585.3882692586247;623.3766233766233;704.6371118873777;718.4647437658941;725.8986725003732;13887.652808679788;13930.746232839043;14863.777043344346;15197.088174709497;16042.197890105506;14000.579982600513;14005.649858753537;14064.296785160752;14135.363908624111;14144.222067786268;892.5091199840078;920.2959735802117;920.3005715656116;923.7736754495158;926.1988380051241;905.714699869539;910.4764760262875;912.1380295620642;912.8128733609627;914.0174312613933;955.1356022799378;957.7126861941424;957.8754761880981;961.8076384723048;962.4465932089042;938.0244545672492;955.2358113509199;955.4267439536292;956.1967946924548;957.5786653872274;695.3012222716188;702.8910518869584;709.0959027240258;710.3393843725333;710.7974227345234;12460.81308780369;13178.275194864278;13244.072914895962;13285.601431957057;13317.26755028473;11116.666500005012;11190.832137517944;11219.439028048564;11298.887011129866;11397.601083962021;9151.17639412456;9315.39449935752;9388.671396501159;9579.760505987337;9660.13058824702;3178.904632861018;3202.455582550972;3343.164208947763;3429.416999110132;3515.929681406383;81861;82426;85253.57373213094;86063.13936860613;86236.99999999937;8040.758777236692;8152.714654987047;8162.328787621803;8285;8306.958465207636;18747.718784218247;18880.52798680044;18927.90536047311;18973.146208420654;18999.145038473303;6092.939070609325;6610.0745895574655;7306.378957788541;7465.402767778564;7471.551706897603;3629.618890016563;3894.162755007687;3990.2418540477215;4022.1754540319166;4033.415154802537;3437.2609888874013;3556.324298383299;3564.8752293669595;3568.6431356864105;3570.8571657133944;632.2918331468784;634.514596333805;638.3616383616384;638.9776357827453;645.5617628801987;521.9034478621467;556.6409665765558;566.3458705195505;568.0740393159124;588.4439204951366;7508.62456877154;7530.171681115073;7566.621668916532;7669.076417117412;7836.373090152774;473.67789902865695;520.1391696741898;546.7484956919861;585.3970410477215;588.6968211371147;3731.794751288651;3751.5685696144747;3751.5873253942045;3758.4174452959833;3776.5090538229856;87698.12301877027;88550.1144988542;89089;89730.55134724415;90755.54622226978;72209.63895180596;76943;77159.45681086459;79480;80870.99999999882;70372.24069398305;70442;70584.64707676429;70737.2926270741;70772.64613676899;5469.835904922858;7298.927010730037;7299.963500182677;7325.194228634739;7426.108866936002;33390.33219335648;43822;44074.33888491739;44101.55898440975;44501.777491112334;47992;61431.6928415373;62006.20972056178;62845;62920.68539657273;9397.201237894582;9412.717618471457;9424.858627120735;9670.516474176122;9783.119519243302;10249;10355.534000969823;10408.739781505523;10771.246012779031;11086.556537738401;9694.75763105928;9918.900810992087;10126.13927816144;10176.135028522658;10500.422476763852;9545.952270238604;9947.602095916372;10026.548805303633;10040.447775372133;10092;9295.907040929504;9515.857262140935;9839.360441571274;10005.849912251177;10277.023682750127;7478.962605186939;8166.550839703873;8341.082480927214;8408.285295749685;8558.27254683334;6917.28724463381;7552.867069939333;8403.159684031549;8468;8594.570271486276 05:04:43 INFO - PROCESS | 2516 | __end_tp_report 05:04:43 INFO - PROCESS | 2516 | __start_cc_report 05:04:43 INFO - PROCESS | 2516 | _x_x_mozilla_cycle_collect,416 05:04:43 INFO - PROCESS | 2516 | __end_cc_report 05:04:43 INFO - PROCESS | 2516 | __startTimestamp1463054683473__endTimestamp 05:04:43 INFO - PROCESS | 2516 | 05:04:43 INFO - PROCESS | 2516 | ------- Summary: start ------- 05:04:43 INFO - PROCESS | 2516 | Number of tests: 6 05:04:43 INFO - PROCESS | 2516 | 05:04:43 INFO - PROCESS | 2516 | [#0] dojo.html Cycles:165 Average:5568.92 Median:5733.77 stddev:760.80 (13.3%) stddev-sans-first:743.07 05:04:43 INFO - PROCESS | 2516 | Values: 3356.4 3357.2 3378.3 3396.2 3399.3 5579.6 5608.6 5636.8 5667.5 5718.3 5559.6 5582.2 5599.8 5648.5 5711.2 4202.4 5082.7 5136.6 5216.3 5268.4 449.1 5111.6 5155.4 5195.2 5198.1 4686.3 5119.3 5145.6 5152.2 5157.5 5177.8 5190.4 5197.0 5201.9 5222.1 4716.2 5047.4 5102.9 5131.3 5196.5 5485.1 5602.2 5604.3 5618.8 5667.3 5123.5 5915.1 6113.5 6143.2 6165.6 6096.8 6136.6 6153.4 6261.7 6264.7 5712.7 5712.8 5717.4 5724.1 5733.8 6096.1 6160.9 6310.3 6319.9 6319.9 4834.5 5959.4 5960.9 5978.9 5988.6 6147.3 6154.8 6175.7 6176.4 6212.7 5961.1 5980.6 6045.8 6101.2 6134.3 6052.3 6058.5 6067.1 6086.1 6086.9 4422.7 4427.7 4811.7 4826.6 4865.5 4269.4 4284.2 4299.7 4314.2 4339.8 5544.5 5645.5 5666.2 5683.8 5704.9 4694.3 6152.5 6155.2 6188.3 6222.1 5586.9 5658.5 5659.5 5680.7 5688.4 5270.7 5282.1 5290.2 5293.8 5305.4 6301.9 6306.8 6309.2 6315.8 6329.2 6074.5 6125.3 6150.7 6159.3 6185.0 6279.7 6289.8 6305.7 6306.1 6311.1 5137.5 5912.7 6061.3 6064.2 6087.9 5224.8 5890.3 5900.9 6115.3 6124.4 5921.3 5953.5 5959.0 5966.3 5979.4 5799.2 5842.5 5937.8 5954.4 5963.8 5674.3 5721.4 5741.9 5742.5 5752.0 4919.7 5902.5 5914.4 5917.1 5936.1 5735.8 5754.8 5779.7 5802.5 5813.3 05:04:43 INFO - PROCESS | 2516 | 05:04:43 INFO - PROCESS | 2516 | [#1] ext.html Cycles:180 Average:70762.52 Median:39918.80 stddev:153482.98 (384.5%) stddev-sans-first:153822.83 05:04:43 INFO - PROCESS | 2516 | Values: 481.9 529.9 540.0 558.1 570.9 38662.6 41420.5 41459.8 41469.4 41557.2 42368.0 42941.6 43096.8 43109.6 43140.8 9621.6 9824.9 9828.3 9833.0 9854.0 8321.4 9565.2 9652.0 9685.7 9705.7 8201.6 9713.2 9720.2 9722.6 9732.6 9626.7 9651.1 9677.6 9687.7 9701.6 8582.9 9146.2 9583.7 9598.6 9601.6 39151.0 39499.6 39549.8 39596.4 39618.0 3206.4 3360.1 3366.3 3417.1 3618.1 28120.4 29665.1 29665.3 29726.0 29747.0 2633.8 2642.2 2812.9 2830.8 2833.4 471885.0 477131.0 477342.0 477681.0 478602.6 62820.7 63233.7 63775.7 63929.0 64014.7 66872.0 67465.7 67788.0 67921.3 68094.3 14220.0 16410.7 16871.5 16899.5 16927.4 12992.8 13036.6 13066.8 13112.2 13120.2 17173.8 17710.0 17927.6 17937.4 17949.6 6875.4 6969.6 6970.2 7013.2 7034.3 931.3 937.8 1025.2 1068.3 1073.4 28572.9 29819.1 29878.3 30316.7 30547.8 823.0 991.8 1007.6 1018.0 1135.3 12365.5 12930.0 13609.0 15116.1 15439.5 634442.0 641827.0 648526.0 649046.0 650335.0 61192.0 61523.2 61526.8 61583.7 61861.0 1336.5 1390.0 1446.4 1480.8 1481.2 22448.8 26550.6 28734.7 29252.0 29519.6 51685.5 52188.0 52573.7 52618.2 52651.0 585089.0 588819.0 589092.0 589880.0 591389.0 39066.0 39671.2 39709.4 39900.0 39937.6 45727.8 46206.8 46630.0 47100.8 47371.5 37437.6 37982.6 38042.8 38482.8 38998.8 37359.1 37628.6 37731.4 37848.1 38115.6 37090.6 37242.4 37518.2 38293.6 38834.0 32623.3 33038.5 33799.0 33819.3 33891.2 19509.6 21449.9 23507.9 25613.4 26396.5 05:04:43 INFO - PROCESS | 2516 | 05:04:43 INFO - PROCESS | 2516 | [#2] jquery.html Cycles:180 Average:25471.49 Median:21064.02 stddev:49962.85 (237.2%) stddev-sans-first:50067.52 05:04:43 INFO - PROCESS | 2516 | Values: 389.9 393.7 394.2 395.1 416.4 15269.8 20271.9 20919.8 21541.8 21576.8 17254.7 20194.0 20620.8 21295.6 21554.0 5561.2 6032.5 6051.8 6053.0 6091.3 5548.7 5893.5 5914.0 6025.6 6028.2 5692.9 5935.6 5965.4 5966.1 5989.9 6066.5 6097.8 6120.6 6133.7 6144.8 5667.3 5870.8 5871.4 5879.5 5921.3 13626.6 13776.2 13845.7 13919.9 22034.0 1785.9 1901.6 1985.4 1993.4 2176.5 10008.6 10025.0 10064.8 10146.9 10165.9 1742.9 1767.0 1767.8 1775.7 1810.4 112356.0 118002.4 133525.0 135607.3 136719.0 9444.4 10331.0 11232.7 11919.5 11977.4 15261.2 15329.6 15339.1 15346.6 15364.5 8570.1 8634.2 8686.2 8719.5 8765.8 8486.0 8522.6 8606.8 8615.1 8627.5 4581.0 4646.3 4666.2 4694.5 4729.6 1943.3 1995.0 1997.3 1999.8 2028.5 901.4 918.5 923.5 989.8 1032.5 18404.7 20256.1 21208.3 21359.4 21458.7 980.9 983.3 1030.4 1042.3 1052.3 9107.8 10099.2 11212.4 11213.4 11215.0 255918.7 256441.0 258590.0 259017.0 259110.0 105214.0 135685.0 141476.0 143156.0 144514.6 1017.5 1021.0 1021.3 1023.0 1035.6 18736.5 18779.5 18832.0 19072.0 19079.7 35685.5 45413.1 54909.9 55147.4 55789.0 64455.7 65549.0 77677.6 83114.6 86547.0 6399.7 6741.8 7107.8 8014.4 8692.0 11699.6 12220.9 12960.3 13334.2 13352.3 10381.1 10795.6 10957.7 11326.8 11468.0 8853.6 10169.0 10694.0 10962.2 11397.6 10072.4 10804.5 11234.0 11670.0 11817.5 5574.8 7391.9 7450.4 7743.0 7820.6 9565.5 10688.0 10713.9 10796.1 10974.2 05:04:43 INFO - PROCESS | 2516 | 05:04:43 INFO - PROCESS | 2516 | [#3] mootools.html Cycles:180 Average:5872.08 Median:4924.57 stddev:3362.71 (68.3%) stddev-sans-first:3356.89 05:04:43 INFO - PROCESS | 2516 | Values: 1609.0 1625.0 1816.2 1864.5 1910.5 8344.6 8830.3 8912.7 9404.2 9407.2 8168.5 8522.1 9020.5 9159.4 9173.3 2446.3 2480.0 2481.0 2483.2 2489.6 2433.2 2498.3 2504.8 2504.9 2507.7 2437.1 2449.9 2451.1 2457.3 2477.3 2252.1 2290.6 2426.7 2435.6 2443.7 2406.1 2416.6 2424.7 2437.4 2438.2 8487.7 8942.0 9232.4 9298.7 9313.7 4956.9 5521.4 5594.2 5674.0 5716.3 3631.2 3651.7 3657.1 3711.6 3721.3 790.8 982.3 1009.1 1032.3 1063.1 12219.9 12428.6 12430.8 12456.7 12456.9 4927.1 4941.9 4967.6 4987.2 5014.8 10508.5 10511.2 10511.6 10517.4 10560.6 5493.3 5571.2 5604.9 5605.4 5617.4 4263.9 4497.0 4918.9 5035.9 5070.3 4085.9 4117.0 4182.6 4199.4 4215.6 1351.6 1367.7 1374.9 1375.7 1378.4 846.0 854.5 906.9 909.6 918.2 5820.7 5861.7 5981.6 5991.0 5995.3 916.7 930.9 944.7 969.3 1011.6 3857.9 3916.9 3995.0 4012.1 4059.7 11115.2 11556.8 11570.4 11623.1 11628.5 9904.5 11380.9 11433.0 11574.2 11579.8 8408.7 8589.7 8618.4 8646.0 8681.7 4948.7 5111.7 5798.8 5927.9 5971.2 10153.7 10375.2 10595.8 10644.6 10709.3 7970.9 10466.5 10522.5 10698.7 10841.1 6716.4 7109.9 7249.2 7260.2 7368.4 7401.3 7931.6 8801.4 8895.0 8911.0 7364.6 7754.5 7832.8 7836.7 7859.7 7549.1 7595.4 7657.9 7784.7 7822.1 7237.8 7267.3 7335.8 7347.1 7360.0 3885.6 4247.6 4376.7 4385.6 4503.0 4922.0 4986.1 5005.7 5015.1 5052.0 05:04:43 INFO - PROCESS | 2516 | 05:04:43 INFO - PROCESS | 2516 | [#4] prototype.html Cycles:180 Average:4274.85 Median:4632.94 stddev:1301.94 (28.1%) stddev-sans-first:1270.53 05:04:43 INFO - PROCESS | 2516 | Values: 276.5 301.5 321.9 322.3 325.8 4842.7 5049.9 5482.8 5577.6 5579.8 5107.2 5380.2 5410.7 5410.7 5474.7 4110.3 4157.8 4164.8 4166.7 4170.5 3736.5 3741.7 3919.1 4102.5 4179.6 3818.0 3981.3 4028.9 4048.4 4062.2 3847.7 4025.3 4047.5 4085.1 4155.2 2975.8 3542.4 3550.6 3557.7 3594.2 4787.8 5165.4 5177.9 5197.9 5225.6 4120.2 4287.0 4429.5 4597.3 4644.6 4384.9 5020.9 5694.7 5736.7 5754.7 3179.8 3616.4 3626.9 3704.7 3885.9 5847.4 5917.3 5942.4 5956.7 5990.8 3909.5 3926.0 3941.7 3943.8 3970.6 3697.2 3919.7 3983.1 4008.3 4041.7 4618.8 4628.7 4648.0 4667.7 4682.4 4886.3 4985.4 5405.7 5734.0 5778.9 1241.5 1266.6 1361.5 1364.2 1365.6 755.6 760.4 762.6 807.6 839.6 4130.9 4150.1 4206.9 4322.4 4365.5 3873.3 4302.0 4672.7 4897.8 5068.5 3946.0 4301.9 4449.4 4479.4 4485.4 2377.8 2398.0 2478.4 2604.5 2648.7 4565.6 4637.7 5027.3 5097.7 5119.6 4223.6 4453.0 4806.7 4954.9 4977.0 4637.2 4797.5 4906.7 5144.1 5181.9 4129.8 4749.0 4892.9 4936.2 4994.9 4068.7 4115.7 4175.9 4594.1 4808.4 4457.7 4503.0 4547.6 4770.5 5255.9 2997.0 3029.7 3434.9 3440.4 3494.0 4475.9 4700.7 4885.7 4893.0 5089.7 4930.9 5170.2 5181.3 5525.5 5693.6 4676.4 5193.9 5624.7 5683.9 5725.6 4771.5 5030.6 5381.7 5510.6 5526.8 4768.7 5466.9 5555.7 5725.5 5775.7 5567.0 5567.5 5572.8 5606.9 5678.9 05:04:43 INFO - PROCESS | 2516 | 05:04:43 INFO - PROCESS | 2516 | [#5] yui.html Cycles:180 Average:17267.76 Median:35537.57 stddev:25197.64 (70.9%) stddev-sans-first:25237.19 05:04:43 INFO - PROCESS | 2516 | Values: 585.4 623.4 704.6 718.5 725.9 13887.7 13930.7 14863.8 15197.1 16042.2 14000.6 14005.6 14064.3 14135.4 14144.2 892.5 920.3 920.3 923.8 926.2 905.7 910.5 912.1 912.8 914.0 955.1 957.7 957.9 961.8 962.4 938.0 955.2 955.4 956.2 957.6 695.3 702.9 709.1 710.3 710.8 12460.8 13178.3 13244.1 13285.6 13317.3 11116.7 11190.8 11219.4 11298.9 11397.6 9151.2 9315.4 9388.7 9579.8 9660.1 3178.9 3202.5 3343.2 3429.4 3515.9 81861.0 82426.0 85253.6 86063.1 86237.0 8040.8 8152.7 8162.3 8285.0 8307.0 18747.7 18880.5 18927.9 18973.1 18999.1 6092.9 6610.1 7306.4 7465.4 7471.6 3629.6 3894.2 3990.2 4022.2 4033.4 3437.3 3556.3 3564.9 3568.6 3570.9 632.3 634.5 638.4 639.0 645.6 521.9 556.6 566.3 568.1 588.4 7508.6 7530.2 7566.6 7669.1 7836.4 473.7 520.1 546.7 585.4 588.7 3731.8 3751.6 3751.6 3758.4 3776.5 87698.1 88550.1 89089.0 89730.6 90755.5 72209.6 76943.0 77159.5 79480.0 80871.0 70372.2 70442.0 70584.6 70737.3 70772.6 5469.8 7298.9 7300.0 7325.2 7426.1 33390.3 43822.0 44074.3 44101.6 44501.8 47992.0 61431.7 62006.2 62845.0 62920.7 9397.2 9412.7 9424.9 9670.5 9783.1 10249.0 10355.5 10408.7 10771.2 11086.6 9694.8 9918.9 10126.1 10176.1 10500.4 9546.0 9947.6 10026.5 10040.4 10092.0 9295.9 9515.9 9839.4 10005.8 10277.0 7479.0 8166.6 8341.1 8408.3 8558.3 6917.3 7552.9 8403.2 8468.0 8594.6 05:04:43 INFO - PROCESS | 2516 | -------- Summary: end -------- 05:04:43 INFO - PROCESS | 2516 | 05:04:43 INFO - TEST-INFO | 2516: exit 0 05:04:44 INFO - TEST-OK | dromaeo_css | took 1112533ms 05:04:44 INFO - TEST-START | kraken 05:04:44 INFO - operating with platform_type : mac_ 05:04:44 INFO - Initialising browser for kraken test... 05:04:44 INFO - TEST-INFO | started process 2522 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/tmpLJMGdM/profile http://localhost:58488/getInfo.html) 05:04:46 INFO - PROCESS | 2522 | __metrics Screen width/height:1024/768 05:04:46 INFO - PROCESS | 2522 | colorDepth:24 05:04:46 INFO - PROCESS | 2522 | Browser inner width/height: 1024/605 05:04:46 INFO - PROCESS | 2522 | __metrics 05:04:47 ERROR - PROCESS | 2522 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 05:04:47 INFO - TEST-INFO | 2522: exit 0 05:04:47 INFO - Browser initialized. 05:04:47 INFO - Running cycle 1/1 for kraken test... 05:04:47 INFO - TEST-INFO | started process 2525 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/tmpLJMGdM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:04:59 INFO - PROCESS | 2525 | RSS: Main: 248958976 05:04:59 INFO - PROCESS | 2525 | 05:05:02 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:58488/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58488/tests/kraken/driver.html?testName=audio-beat-detection) 05:05:03 INFO - PROCESS | 2525 | RSS: Main: 258736128 05:05:03 INFO - PROCESS | 2525 | 05:05:06 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:58488/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58488/tests/kraken/driver.html?testName=audio-dft) 05:05:06 INFO - PROCESS | 2525 | RSS: Main: 262672384 05:05:06 INFO - PROCESS | 2525 | 05:05:10 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:58488/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58488/tests/kraken/driver.html?testName=audio-fft) 05:05:10 INFO - PROCESS | 2525 | RSS: Main: 263524352 05:05:10 INFO - PROCESS | 2525 | 05:05:12 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:58488/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58488/tests/kraken/driver.html?testName=audio-oscillator) 05:05:13 INFO - PROCESS | 2525 | RSS: Main: 263999488 05:05:13 INFO - PROCESS | 2525 | 05:05:15 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:58488/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58488/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:05:16 INFO - PROCESS | 2525 | RSS: Main: 264298496 05:05:16 INFO - PROCESS | 2525 | 05:05:19 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:58488/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58488/tests/kraken/driver.html?testName=imaging-darkroom) 05:05:20 INFO - PROCESS | 2525 | RSS: Main: 264552448 05:05:20 INFO - PROCESS | 2525 | 05:05:23 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:58488/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58488/tests/kraken/driver.html?testName=imaging-desaturate) 05:05:23 INFO - PROCESS | 2525 | RSS: Main: 264974336 05:05:23 INFO - PROCESS | 2525 | 05:05:27 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:58488/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58488/tests/kraken/driver.html?testName=json-parse-financial) 05:05:27 INFO - PROCESS | 2525 | RSS: Main: 264859648 05:05:27 INFO - PROCESS | 2525 | 05:05:29 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:58488/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58488/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:05:30 INFO - PROCESS | 2525 | RSS: Main: 264073216 05:05:30 INFO - PROCESS | 2525 | 05:05:32 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:58488/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58488/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:05:32 INFO - PROCESS | 2525 | RSS: Main: 264069120 05:05:32 INFO - PROCESS | 2525 | 05:05:35 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:58488/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58488/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:05:35 INFO - PROCESS | 2525 | RSS: Main: 264359936 05:05:35 INFO - PROCESS | 2525 | 05:05:38 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:58488/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58488/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:05:39 INFO - PROCESS | 2525 | RSS: Main: 265240576 05:05:39 INFO - PROCESS | 2525 | 05:05:42 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:58488/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58488/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:05:43 INFO - PROCESS | 2525 | RSS: Main: 266194944 05:05:43 INFO - PROCESS | 2525 | 05:05:44 INFO - PROCESS | 2525 | Cycle 1(1): loaded http://localhost:58488/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58488/tests/kraken/driver.html?testName=ai-astar) 05:05:45 INFO - PROCESS | 2525 | RSS: Main: 268017664 05:05:45 INFO - PROCESS | 2525 | 05:05:45 INFO - PROCESS | 2525 | __start_tp_report 05:05:45 INFO - PROCESS | 2525 | _x_x_mozilla_page_load 05:05:45 INFO - PROCESS | 2525 | _x_x_mozilla_page_load_details 05:05:45 INFO - PROCESS | 2525 | |i|pagename|runs| 05:05:45 INFO - PROCESS | 2525 | |0;ai-astar;120;118;117;115;114;108;115;115;115;116 05:05:45 INFO - PROCESS | 2525 | |1;audio-beat-detection;170;116;149;207;139;140;133;148;199;145 05:05:45 INFO - PROCESS | 2525 | |2;audio-dft;197;215;275;260;195;204;197;202;202;199 05:05:45 INFO - PROCESS | 2525 | |3;audio-fft;70;82;78;177;58;59;50;61;139;62 05:05:45 INFO - PROCESS | 2525 | |4;audio-oscillator;80;87;156;94;112;119;121;83;83;127 05:05:45 INFO - PROCESS | 2525 | |5;imaging-gaussian-blur;92;90;110;79;123;130;108;90;91;89 05:05:45 INFO - PROCESS | 2525 | |6;imaging-darkroom;98;98;97;97;99;97;112;111;97;96 05:05:45 INFO - PROCESS | 2525 | |7;imaging-desaturate;89;141;94;144;141;98;91;90;93;91 05:05:45 INFO - PROCESS | 2525 | |8;json-parse-financial;59;64;63;63;64;65;64;124;64;63 05:05:45 INFO - PROCESS | 2525 | |9;json-stringify-tinderbox;63;69;66;77;72;96;64;81;97;60 05:05:45 INFO - PROCESS | 2525 | |10;stanford-crypto-aes;90;86;101;107;76;97;112;80;85;85 05:05:45 INFO - PROCESS | 2525 | |11;stanford-crypto-ccm;141;136;132;150;155;153;157;145;234;167 05:05:45 INFO - PROCESS | 2525 | |12;stanford-crypto-pbkdf2;172;173;169;164;169;170;169;165;171;167 05:05:45 INFO - PROCESS | 2525 | |13;stanford-crypto-sha256-iterative;70;68;67;67;64;66;70;66;68;67 05:05:45 INFO - PROCESS | 2525 | __end_tp_report 05:05:45 INFO - PROCESS | 2525 | __start_cc_report 05:05:45 INFO - PROCESS | 2525 | _x_x_mozilla_cycle_collect,1069 05:05:45 INFO - PROCESS | 2525 | __end_cc_report 05:05:45 INFO - PROCESS | 2525 | __startTimestamp1463054745006__endTimestamp 05:05:45 INFO - PROCESS | 2525 | 05:05:45 INFO - PROCESS | 2525 | ------- Summary: start ------- 05:05:45 INFO - PROCESS | 2525 | Number of tests: 14 05:05:45 INFO - PROCESS | 2525 | 05:05:45 INFO - PROCESS | 2525 | [#0] ai-astar Cycles:10 Average:115.30 Median:115.50 stddev:3.13 (2.7%) stddev-sans-first:2.82 05:05:45 INFO - PROCESS | 2525 | Values: 120.0 118.0 117.0 115.0 114.0 108.0 115.0 115.0 115.0 116.0 05:05:45 INFO - PROCESS | 2525 | 05:05:45 INFO - PROCESS | 2525 | [#1] audio-beat-detection Cycles:10 Average:154.60 Median:148.50 stddev:28.93 (19.5%) stddev-sans-first:30.15 05:05:45 INFO - PROCESS | 2525 | Values: 170.0 116.0 149.0 207.0 139.0 140.0 133.0 148.0 199.0 145.0 05:05:45 INFO - PROCESS | 2525 | 05:05:45 INFO - PROCESS | 2525 | [#2] audio-dft Cycles:10 Average:214.60 Median:203.00 stddev:28.65 (14.1%) stddev-sans-first:29.67 05:05:45 INFO - PROCESS | 2525 | Values: 197.0 215.0 275.0 260.0 195.0 204.0 197.0 202.0 202.0 199.0 05:05:45 INFO - PROCESS | 2525 | 05:05:45 INFO - PROCESS | 2525 | [#3] audio-fft Cycles:10 Average:83.60 Median:61.50 stddev:41.34 (67.2%) stddev-sans-first:43.55 05:05:45 INFO - PROCESS | 2525 | Values: 70.0 82.0 78.0 177.0 58.0 59.0 50.0 61.0 139.0 62.0 05:05:45 INFO - PROCESS | 2525 | 05:05:45 INFO - PROCESS | 2525 | [#4] audio-oscillator Cycles:10 Average:106.20 Median:81.50 stddev:24.97 (30.6%) stddev-sans-first:24.61 05:05:45 INFO - PROCESS | 2525 | Values: 80.0 87.0 156.0 94.0 112.0 119.0 121.0 83.0 83.0 127.0 05:05:45 INFO - PROCESS | 2525 | 05:05:45 INFO - PROCESS | 2525 | [#5] imaging-gaussian-blur Cycles:10 Average:100.20 Median:89.50 stddev:16.67 (18.6%) stddev-sans-first:17.41 05:05:45 INFO - PROCESS | 2525 | Values: 92.0 90.0 110.0 79.0 123.0 130.0 108.0 90.0 91.0 89.0 05:05:45 INFO - PROCESS | 2525 | 05:05:45 INFO - PROCESS | 2525 | [#6] imaging-darkroom Cycles:10 Average:100.20 Median:97.00 stddev:6.01 (6.2%) stddev-sans-first:6.33 05:05:45 INFO - PROCESS | 2525 | Values: 98.0 98.0 97.0 97.0 99.0 97.0 112.0 111.0 97.0 96.0 05:05:45 INFO - PROCESS | 2525 | 05:05:45 INFO - PROCESS | 2525 | [#7] imaging-desaturate Cycles:10 Average:107.20 Median:91.00 stddev:24.16 (26.5%) stddev-sans-first:24.71 05:05:45 INFO - PROCESS | 2525 | Values: 89.0 141.0 94.0 144.0 141.0 98.0 91.0 90.0 93.0 91.0 05:05:45 INFO - PROCESS | 2525 | 05:05:45 INFO - PROCESS | 2525 | [#8] json-parse-financial Cycles:10 Average:69.30 Median:64.00 stddev:19.29 (30.1%) stddev-sans-first:20.09 05:05:45 INFO - PROCESS | 2525 | Values: 59.0 64.0 63.0 63.0 64.0 65.0 64.0 124.0 64.0 63.0 05:05:45 INFO - PROCESS | 2525 | 05:05:45 INFO - PROCESS | 2525 | [#9] json-stringify-tinderbox Cycles:10 Average:74.50 Median:74.50 stddev:13.24 (17.8%) stddev-sans-first:13.38 05:05:45 INFO - PROCESS | 2525 | Values: 63.0 69.0 66.0 77.0 72.0 96.0 64.0 81.0 97.0 60.0 05:05:45 INFO - PROCESS | 2525 | 05:05:45 INFO - PROCESS | 2525 | [#10] stanford-crypto-aes Cycles:10 Average:91.90 Median:85.00 stddev:11.87 (14.0%) stddev-sans-first:12.57 05:05:45 INFO - PROCESS | 2525 | Values: 90.0 86.0 101.0 107.0 76.0 97.0 112.0 80.0 85.0 85.0 05:05:45 INFO - PROCESS | 2525 | 05:05:45 INFO - PROCESS | 2525 | [#11] stanford-crypto-ccm Cycles:10 Average:157.00 Median:154.00 stddev:28.99 (18.8%) stddev-sans-first:30.17 05:05:45 INFO - PROCESS | 2525 | Values: 141.0 136.0 132.0 150.0 155.0 153.0 157.0 145.0 234.0 167.0 05:05:45 INFO - PROCESS | 2525 | 05:05:45 INFO - PROCESS | 2525 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.90 Median:169.50 stddev:2.88 (1.7%) stddev-sans-first:2.83 05:05:45 INFO - PROCESS | 2525 | Values: 172.0 173.0 169.0 164.0 169.0 170.0 169.0 165.0 171.0 167.0 05:05:45 INFO - PROCESS | 2525 | 05:05:45 INFO - PROCESS | 2525 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.50 stddev:1.83 (2.7%) stddev-sans-first:1.66 05:05:45 INFO - PROCESS | 2525 | Values: 70.0 68.0 67.0 67.0 64.0 66.0 70.0 66.0 68.0 67.0 05:05:45 INFO - PROCESS | 2525 | -------- Summary: end -------- 05:05:45 INFO - PROCESS | 2525 | 05:05:45 INFO - TEST-INFO | 2525: exit 0 05:05:45 INFO - TEST-OK | kraken | took 61785ms 05:05:45 INFO - SUITE-END | took 1174s 05:05:46 INFO - Completed test suite (00:19:34) 05:05:46 INFO - javascript benchmark 05:05:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7008.404207135974, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3356.412627790137, 3357.160709822546, 3378.3412234614243, 3396.184915620252, 3399.2691571312193, 5579.581531385147, 5608.551315894729, 5636.7745290188395, 5667.4615911488345, 5718.313802343714, 5559.638623489471, 5582.246396736439, 5599.776008959637, 5648.4633959773755, 5711.200431939532, 4202.411662367271, 5082.695038297705, 5136.563392111675, 5216.26972223889, 5268.367795864501, 449.1310961477049, 5111.64218504703, 5155.432902380735, 5195.168772996321, 5198.090334191501, 4686.320483529886, 5119.283300337956, 5145.562627176676, 5152.175651895716, 5157.510036546522, 5177.792888284464, 5190.351206099238, 5197.0, 5201.9075994041295, 5222.060029194743, 4716.221823399169, 5047.419546752134, 5102.897942041139, 5131.3329267195395, 5196.480351964811, 5485.149801780731, 5602.1596760485845, 5604.327480702301, 5618.803341883015, 5667.263255776723, 5123.538881500683, 5915.142304365843, 6113.510919126459, 6143.201383820119, 6165.630062196283, 6096.768593606644, 6136.641621527207, 6153.353897840707, 6261.655608941506, 6264.718087686065, 5712.714364281853, 5712.80005199816, 5717.399673034354, 5724.112762521816, 5733.770649174028, 6096.073160812928, 6160.8767824643255, 6310.337414571404, 6319.9052014218905, 6319.905201421982, 4834.492378300298, 5959.404059594006, 5960.940390596039, 5978.940210597925, 5988.6406815590335, 6147.262328520606, 6154.815355539341, 6175.660338681416, 6176.35148309421, 6212.72042758077, 5961.076033214858, 5980.641161530322, 6045.818625441244, 6101.176341193882, 6134.263888333428, 6052.334243233241, 6058.515318774487, 6067.1202675611785, 6086.117512960596, 6086.939130608637, 4422.668299877522, 4427.693830320063, 4811.663183577116, 4826.61387089032, 4865.537773911473, 4269.402283680287, 4284.1645879053285, 4299.699021068495, 4314.201872653569, 4339.7613131278085, 5544.473275038865, 5645.491905728422, 5666.206731057655, 5683.8010669626365, 5704.942950570524, 4694.342792009122, 6152.507799376039, 6155.169052177988, 6188.319284878659, 6222.097795819672, 5586.888262234733, 5658.519025882846, 5659.518940890071, 5680.715964201773, 5688.431156884361, 5270.657407268592, 5282.102042652758, 5290.1800220965815, 5293.788248470018, 5305.44292849253, 6301.936980630227, 6306.810795676137, 6309.179806625109, 6315.778947736899, 6329.208848893888, 6074.514038876968, 6125.264968203844, 6150.661713605795, 6159.29168145669, 6184.969075154775, 6279.686015699288, 6289.842753931005, 6305.684715764285, 6306.085617585332, 6311.147995020613, 5137.460566640449, 5912.67480288571, 6061.302950160788, 6064.151018857276, 6087.9391206087375, 5224.772178538116, 5890.293164820249, 5900.937831190427, 6115.266168059861, 6124.38756124384, 5921.259842519685, 5953.523718102628, 5959.0, 5966.343702192668, 5979.431953964367, 5799.188113664006, 5842.474177323891, 5937.762489500372, 5954.374790647093, 5963.7912673056235, 5674.2623458950075, 5721.399253078367, 5741.942580574141, 5742.511886489695, 5751.971240143773, 4919.655624106278, 5902.468777810018, 5914.378990206139, 5917.142014407801, 5936.079907614327, 5735.770569177186, 5754.798582049608, 5779.7110144493445, 5802.47777700009, 5813.273340832396], "value": 5524.492592783331, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [481.8867566121963, 529.8569386265708, 540.0333403208257, 558.0847410247197, 570.9286339207599, 38662.61337386629, 41420.47925926296, 41459.79270103649, 41469.37795933063, 41557.16885662293, 42368.0, 42941.570584294146, 43096.78451607745, 43109.568904310945, 43140.78429607856, 9621.567029483655, 9824.90175098247, 9828.312018158731, 9833.0, 9853.950730246339, 8321.375896807733, 9565.186959108483, 9652.0, 9685.709428717115, 9705.660301889435, 8201.58992050398, 9713.22294216461, 9720.173785228264, 9722.611095556169, 9732.56203470842, 9626.663066792664, 9651.131398174162, 9677.612895484172, 9687.660931867422, 9701.563429645683, 8582.914170858274, 9146.222571081466, 9583.712488625353, 9598.616055357777, 9601.567929443121, 39151.0, 39499.60500394988, 39549.80225098885, 39596.40605390922, 39618.0, 3206.3907857506997, 3360.1431634932937, 3366.3099064691924, 3417.094469965461, 3618.095476130967, 28120.437591248265, 29665.11004669863, 29665.258368540744, 29725.959591414412, 29747.0, 2633.789296856247, 2642.167717169105, 2812.9015484457946, 2830.7735381169455, 2833.39082097348, 471885.0, 477131.0, 477342.0, 477681.0, 478602.60698696633, 62820.68589657023, 63233.68383158055, 63775.72448551003, 63929.0, 64014.679926601, 66871.99692004624, 67465.66267168632, 67788.0, 67921.3207867925, 68094.3190568088, 14220.00459967813, 16410.671786564202, 16871.493855184362, 16899.493015209562, 16927.407540736014, 12992.805107923388, 13036.608901732963, 13066.82398584132, 13112.213267203999, 13120.212787232796, 17173.828261717474, 17710.02594857295, 17927.551811204565, 17937.37219197322, 17949.641007179784, 6875.4155896749335, 6969.58182509051, 6970.198427180939, 7013.228544860061, 7034.331738484836, 931.3154831199063, 937.7749340158313, 1025.1593693171676, 1068.3056013591229, 1073.3988966178965, 28572.85713571419, 29819.105426837228, 29878.25304367408, 30316.696833031827, 30547.847260763552, 822.995885020583, 991.8313886639289, 1007.6422869881234, 1018.0379541333517, 1135.2961164078324, 12365.505379784889, 12929.965602751758, 13609.047366684237, 15116.09303441775, 15439.459618913284, 634442.0, 641827.0, 648526.0, 649046.0, 650335.0, 61192.0, 61523.23145458465, 61526.76946461046, 61583.692081540205, 61861.0, 1336.4988129451456, 1390.0269811132248, 1446.3563714147103, 1480.8297045839652, 1481.1705444950646, 22448.77551224467, 26550.601740973514, 28734.712652873204, 29252.0, 29519.55720664149, 51685.48314516957, 52188.0, 52573.737131315625, 52618.158364457064, 52651.0, 585089.0, 588819.0, 589092.0, 589880.0, 591389.0, 39066.0, 39671.2065758689, 39709.40435893406, 39900.0, 39937.600623993385, 45727.77136114298, 46206.76896615495, 46630.0, 47100.7644961773, 47371.52628473809, 37437.62562374341, 37982.62017379901, 38042.80978595089, 38482.80758596301, 38998.80500597479, 37359.06602334963, 37628.623713762514, 37731.43402848904, 37848.05379865415, 38115.61884381121, 37090.629093709795, 37242.441363380116, 37518.2496350066, 38293.61706383012, 38834.02914927036, 32623.34753304968, 33038.5044224332, 33799.0, 33819.3236135271, 33891.15272118217, 19509.60980780348, 21449.946810097408, 23507.942142603966, 25613.3596660085, 26396.4720705581], "value": 19983.1185381021, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.9103206262558, 393.6968534228644, 394.1820722001846, 395.0794648469067, 416.444047196992, 15269.847301526981, 20271.898640506795, 20919.790802091975, 21541.784582154174, 21576.784232157675, 17254.741178882294, 20193.99030048499, 20620.793792062075, 21295.57408851822, 21554.0, 5561.165825126233, 6032.457078862902, 6051.848703782397, 6053.0, 6091.329953705111, 5548.667079975195, 5893.469587737103, 5914.0, 6025.578209525335, 6028.1861948636815, 5692.943070569303, 5935.584509084366, 5965.448983264361, 5966.105084237356, 5989.9101513477335, 6066.514678825705, 6097.7804439111915, 6120.602160859575, 6133.6626485543275, 6144.754209831602, 5667.319921609392, 5870.765169393219, 5871.442212989802, 5879.500242479395, 5921.289445266552, 13626.591202263948, 13776.24230667313, 13845.723085538233, 13919.930400348036, 22034.0, 1785.866060045489, 1901.5531350132708, 1985.384530795458, 1993.4219076467807, 2176.4994051368276, 10008.59965601375, 10025.0, 10064.849027264598, 10146.949265253626, 10165.949170254175, 1742.8605711543046, 1767.0281345260184, 1767.7701898753207, 1775.7158854583333, 1810.4478134169074, 112356.0, 118002.40998795036, 133525.0, 135607.32196339054, 136719.0, 9444.386114902509, 10331.01855323743, 11232.719182020515, 11919.463624136935, 11977.401129943468, 15261.160636164896, 15329.616759580877, 15339.079655220723, 15346.616334591501, 15364.462243821636, 8570.142985701379, 8634.22291993711, 8686.218240358397, 8719.476831390137, 8765.824683506295, 8486.024107227748, 8522.57387130641, 8606.784830379165, 8615.052344242129, 8627.525486098324, 4580.977095114503, 4646.256598944153, 4666.1536462757695, 4694.507076756891, 4729.5743383095, 1943.3489780923549, 1994.9601007979759, 1997.2909617085725, 1999.780024197337, 2028.4523178741656, 901.4230892228848, 918.5223683684446, 923.5290002098843, 989.8267803134567, 1032.5301987595626, 18404.723929141073, 20256.088476018616, 21208.25771098004, 21359.359219223446, 21458.678119828215, 980.8871979722423, 983.2969426859829, 1030.4384110659707, 1042.2912419554775, 1052.2581579986092, 9107.806691449745, 10099.192064634812, 11212.383318917375, 11213.359247947299, 11214.990650841464, 255918.72040639678, 256441.0, 258590.0, 259017.0, 259110.0, 105214.0, 135685.0, 141476.0, 143156.0, 144514.5548544501, 1017.5471914997755, 1020.9642662506925, 1021.2953062386929, 1023.0025724918145, 1035.570238351076, 18736.53158671044, 18779.530511737317, 18832.0, 19072.0, 19079.713804293224, 35685.46471802977, 45413.09173816571, 54909.90180196454, 55147.44852551423, 55789.0, 64455.67772161297, 65549.0, 77677.61161194158, 83114.58442707748, 86547.0, 6399.7440102395385, 6741.797746067626, 7107.82230444243, 8014.358851292, 8692.0, 11699.649010529698, 12220.87779122233, 12960.287184204959, 13334.199948002763, 13352.332383380986, 10381.065704086366, 10795.56817727282, 10957.726056848323, 11326.773464530499, 11468.0, 8853.557322133996, 10169.0, 10694.037536621741, 10962.177836662442, 11397.601083962021, 10072.395656260647, 10804.513796879002, 11234.0, 11670.0, 11817.527298907948, 5574.832755017356, 7391.926080739124, 7450.435408564045, 7742.961285193538, 7820.608969551386, 9565.473898935625, 10687.984641459048, 10713.89286107129, 10796.082333001783, 10974.176936729618], "value": 9177.751061547737, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1608.9517314480565, 1625.0249850089945, 1816.191794651379, 1864.5431869192041, 1910.5032691500205, 8344.582770861447, 8830.293576513879, 8912.688055918044, 9404.153626173644, 9407.153356197941, 8168.50988940664, 8522.14778522149, 9020.503872287021, 9159.358844880844, 9173.266138708905, 2446.3150317911013, 2479.962800557984, 2480.9751902481016, 2483.217786397288, 2489.614109812982, 2433.1605596069417, 2498.287987923442, 2504.7620476054744, 2504.949901001979, 2507.6865391826022, 2437.073911913479, 2449.8530088194766, 2451.142100264911, 2457.34129462612, 2477.3311205974464, 2252.0991603358625, 2290.6449500327476, 2426.660267562534, 2435.6224785158242, 2443.6701045358914, 2406.0856874387796, 2416.6375043743406, 2424.696912885889, 2437.427204606916, 2438.2319569335605, 8487.726840973903, 8942.0, 9232.446053236827, 9298.674546390845, 9313.720588382359, 4956.909479914413, 5521.3926468088475, 5594.216809646653, 5674.035413979634, 5716.314042314907, 3631.182983828644, 3651.6713495785507, 3657.104009517685, 3711.628837116267, 3721.274351501458, 790.7864876483375, 982.3172894838091, 1009.0968583118068, 1032.267090365841, 1063.1123012284675, 12219.877801221963, 12428.62714118578, 12430.751384972249, 12456.688582785413, 12456.94116000141, 4927.137750893579, 4941.925871111936, 4967.602591792648, 4987.152184128634, 5014.7994080236385, 10508.527116279633, 10511.211659125596, 10511.57953681844, 10517.42154181512, 10560.630377936735, 5493.285872836505, 5571.164325351229, 5604.943950560442, 5605.355384130877, 5617.438256174431, 4263.936040959388, 4496.977515112403, 4918.926216106761, 5035.874103147451, 5070.3155074064525, 4085.8569950051606, 4116.997956345938, 4182.605970461131, 4199.4330765346285, 4215.641670458045, 1351.6283022168786, 1367.7401293754156, 1374.8556401577691, 1375.7454870848926, 1378.400395827818, 845.9636944742764, 854.4873076154257, 906.850369688994, 909.5997760985146, 918.2011649864661, 5820.650760954356, 5861.736221870026, 5981.641101533922, 5991.0, 5995.280566332068, 916.7433118726767, 930.9301802364714, 944.6976967370513, 969.3214749675255, 1011.6054738651932, 3857.9421308680394, 3916.941245881314, 3995.001249687578, 4012.1373904610064, 4059.6752259819145, 11115.166362522843, 11556.76886462266, 11570.363630000262, 11623.12826537996, 11628.53485860573, 9904.504774761233, 11380.94309528447, 11433.0, 11574.247673901338, 11579.826302605468, 8408.663653453916, 8589.65641374338, 8618.353623478264, 8646.048934617187, 8681.6527338907, 4948.703077815343, 5111.696517388191, 5798.82603521895, 5927.940720592739, 5971.223740913653, 10153.730783652043, 10375.221858360503, 10595.788084238426, 10644.627438039632, 10709.303895246783, 7970.920290797249, 10466.476676166008, 10522.526486307981, 10698.679039628823, 10841.07850832688, 6716.429103526255, 7109.928900710926, 7249.202587715453, 7260.201377848327, 7368.410527157707, 7401.33387995083, 7931.611967905709, 8801.427907186013, 8894.955525222333, 8911.0, 7364.631768411451, 7754.457187996786, 7832.765017049498, 7836.686532538634, 7859.724909628364, 7549.131849837339, 7595.430342724208, 7657.88513172314, 7784.727534536264, 7822.06135263772, 7237.819054523679, 7267.273272672691, 7335.77992660221, 7347.05303094691, 7359.963200183965, 3885.579720182949, 4247.554006829362, 4376.6717496187275, 4385.561443855589, 4503.0, 4922.0, 4986.102501549756, 5005.749712514433, 5015.097282489187, 5051.974740126276], "value": 4696.7511781435105, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [276.5437028902311, 301.52058227418416, 321.8503395920897, 322.253982031597, 325.8094015001224, 4842.66101372904, 5049.924251136236, 5482.847362766411, 5577.63745356552, 5579.776808927643, 5107.157319042353, 5380.166074258486, 5410.675359478434, 5410.729463526827, 5474.72626368682, 4110.280700877342, 4157.833686652545, 4164.750114993095, 4166.749995000295, 4170.499540055198, 3736.532933383327, 3741.681957033655, 3919.1377896862646, 4102.548719640837, 4179.602937720927, 3818.0, 3981.3430783920617, 4028.879133625996, 4048.412980117881, 4062.1672557125867, 3847.7306588538813, 4025.2553277643437, 4047.4535937648484, 4085.142120154771, 4155.231282212785, 2975.8065725727756, 3542.36237477254, 3550.609432962372, 3557.6975957043683, 3594.245208506217, 4787.832425865113, 5165.354330708661, 5177.867390228895, 5197.8960420791755, 5225.589090945461, 4120.1553681495225, 4287.0, 4429.534898835608, 4597.287420449836, 4644.6052085572765, 4384.934225986581, 5020.874478138039, 5694.668618857973, 5736.6558006519745, 5754.683492407916, 3179.761517886168, 3616.385214513539, 3626.854925802991, 3704.666580007812, 3885.8834234972996, 5847.415258474161, 5917.260342457193, 5942.3760505146765, 5956.702164891738, 5990.760369585211, 3909.4722212500947, 3925.9607403925597, 3941.684665226775, 3943.8422463101156, 3970.6029397060065, 3697.242065376591, 3919.7452165609143, 3983.063979964706, 4008.2985477541897, 4041.656459201001, 4618.822200338892, 4628.652851036119, 4648.047150334173, 4667.719936803803, 4682.391289132391, 4886.315915771795, 4985.376827896513, 5405.675659460445, 5734.0, 5778.88442231153, 1241.472374240944, 1266.6326765237977, 1361.5098564516766, 1364.1542243808901, 1365.556194236877, 755.6372940988356, 760.3688938181407, 762.5729591428818, 807.6255807924682, 839.5508403004363, 4130.905310092828, 4150.086980864205, 4206.8737937861915, 4322.3516472528745, 4365.519792822787, 3873.2640798248244, 4301.956980430218, 4672.719636821734, 4897.804087836518, 5068.518490743373, 3945.980270098631, 4301.913961720748, 4449.377087207794, 4479.350494178326, 4485.407680273521, 2377.833551651368, 2398.0407836865393, 2478.3556275368533, 2604.5311843867908, 2648.7086420494115, 4565.634749220055, 4637.698549594265, 5027.3464449621315, 5097.719625420564, 5119.59043276537, 4223.619874211336, 4452.977735111303, 4806.678163505064, 4954.851354459372, 4976.954839483749, 4637.234856248749, 4797.496262892416, 4906.681065730716, 5144.148106681667, 5181.922271165936, 4129.793510324532, 4748.952510474851, 4892.8532144035735, 4936.160852655129, 4994.875128121681, 4068.7355321905166, 4115.650169735486, 4175.937360939528, 4594.104149690912, 4808.3989501312335, 4457.68796184264, 4503.0, 4547.613452856544, 4770.522947705202, 5255.9211611825085, 2996.970030299669, 3029.6818834022993, 3434.914127146841, 3440.4323286657423, 3493.9867438442566, 4475.888102797456, 4700.694454860423, 4885.65800393969, 4892.951070489249, 5089.694618322913, 4930.8767280818265, 5170.224466330081, 5181.3005244291535, 5525.475079867406, 5693.601447898607, 4676.392069031004, 5193.92209116856, 5624.6625202487985, 5683.857903552444, 5725.595249995095, 4771.475137734916, 5030.572401345927, 5381.67709937405, 5510.641808282448, 5526.751296191601, 4768.690035147704, 5466.851961088216, 5555.7499912504845, 5725.484706376446, 5775.7400916957995, 5567.0, 5567.526760225426, 5572.804951826666, 5606.85982850432, 5678.858028549319], "value": 3863.7998377179756, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [585.3882692586247, 623.3766233766233, 704.6371118873777, 718.4647437658941, 725.8986725003732, 13887.652808679788, 13930.746232839043, 14863.777043344346, 15197.088174709497, 16042.197890105506, 14000.579982600513, 14005.649858753537, 14064.296785160752, 14135.363908624111, 14144.222067786268, 892.5091199840078, 920.2959735802117, 920.3005715656116, 923.7736754495158, 926.1988380051241, 905.714699869539, 910.4764760262875, 912.1380295620642, 912.8128733609627, 914.0174312613933, 955.1356022799378, 957.7126861941424, 957.8754761880981, 961.8076384723048, 962.4465932089042, 938.0244545672492, 955.2358113509199, 955.4267439536292, 956.1967946924548, 957.5786653872274, 695.3012222716188, 702.8910518869584, 709.0959027240258, 710.3393843725333, 710.7974227345234, 12460.81308780369, 13178.275194864278, 13244.072914895962, 13285.601431957057, 13317.26755028473, 11116.666500005012, 11190.832137517944, 11219.439028048564, 11298.887011129866, 11397.601083962021, 9151.17639412456, 9315.39449935752, 9388.671396501159, 9579.760505987337, 9660.13058824702, 3178.904632861018, 3202.455582550972, 3343.164208947763, 3429.416999110132, 3515.929681406383, 81861.0, 82426.0, 85253.57373213094, 86063.13936860613, 86236.99999999937, 8040.758777236692, 8152.714654987047, 8162.328787621803, 8285.0, 8306.958465207636, 18747.718784218247, 18880.52798680044, 18927.90536047311, 18973.146208420654, 18999.145038473303, 6092.939070609325, 6610.0745895574655, 7306.378957788541, 7465.402767778564, 7471.551706897603, 3629.618890016563, 3894.162755007687, 3990.2418540477215, 4022.1754540319166, 4033.415154802537, 3437.2609888874013, 3556.324298383299, 3564.8752293669595, 3568.6431356864105, 3570.8571657133944, 632.2918331468784, 634.514596333805, 638.3616383616384, 638.9776357827453, 645.5617628801987, 521.9034478621467, 556.6409665765558, 566.3458705195505, 568.0740393159124, 588.4439204951366, 7508.62456877154, 7530.171681115073, 7566.621668916532, 7669.076417117412, 7836.373090152774, 473.67789902865695, 520.1391696741898, 546.7484956919861, 585.3970410477215, 588.6968211371147, 3731.794751288651, 3751.5685696144747, 3751.5873253942045, 3758.4174452959833, 3776.5090538229856, 87698.12301877027, 88550.1144988542, 89089.0, 89730.55134724415, 90755.54622226978, 72209.63895180596, 76943.0, 77159.45681086459, 79480.0, 80870.99999999882, 70372.24069398305, 70442.0, 70584.64707676429, 70737.2926270741, 70772.64613676899, 5469.835904922858, 7298.927010730037, 7299.963500182677, 7325.194228634739, 7426.108866936002, 33390.33219335648, 43822.0, 44074.33888491739, 44101.55898440975, 44501.777491112334, 47992.0, 61431.6928415373, 62006.20972056178, 62845.0, 62920.68539657273, 9397.201237894582, 9412.717618471457, 9424.858627120735, 9670.516474176122, 9783.119519243302, 10249.0, 10355.534000969823, 10408.739781505523, 10771.246012779031, 11086.556537738401, 9694.75763105928, 9918.900810992087, 10126.13927816144, 10176.135028522658, 10500.422476763852, 9545.952270238604, 9947.602095916372, 10026.548805303633, 10040.447775372133, 10092.0, 9295.907040929504, 9515.857262140935, 9839.360441571274, 10005.849912251177, 10277.023682750127, 7478.962605186939, 8166.550839703873, 8341.082480927214, 8408.285295749685, 8558.27254683334, 6917.28724463381, 7552.867069939333, 8403.159684031549, 8468.0, 8594.570271486276], "value": 6444.1053922628, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1610.8000000000004, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 118.0, 117.0, 115.0, 114.0, 108.0, 115.0, 115.0, 115.0, 116.0], "value": 115.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 116.0, 149.0, 207.0, 139.0, 140.0, 133.0, 148.0, 199.0, 145.0], "value": 154.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 215.0, 275.0, 260.0, 195.0, 204.0, 197.0, 202.0, 202.0, 199.0], "value": 214.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 82.0, 78.0, 177.0, 58.0, 59.0, 50.0, 61.0, 139.0, 62.0], "value": 83.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 87.0, 156.0, 94.0, 112.0, 119.0, 121.0, 83.0, 83.0, 127.0], "value": 106.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 110.0, 79.0, 123.0, 130.0, 108.0, 90.0, 91.0, 89.0], "value": 100.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 98.0, 97.0, 97.0, 99.0, 97.0, 112.0, 111.0, 97.0, 96.0], "value": 100.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 141.0, 94.0, 144.0, 141.0, 98.0, 91.0, 90.0, 93.0, 91.0], "value": 107.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 64.0, 63.0, 63.0, 64.0, 65.0, 64.0, 124.0, 64.0, 63.0], "value": 69.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 69.0, 66.0, 77.0, 72.0, 96.0, 64.0, 81.0, 97.0, 60.0], "value": 74.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 101.0, 107.0, 76.0, 97.0, 112.0, 80.0, 85.0, 85.0], "value": 91.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 136.0, 132.0, 150.0, 155.0, 153.0, 157.0, 145.0, 234.0, 167.0], "value": 157.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 173.0, 169.0, 164.0, 169.0, 170.0, 169.0, 165.0, 171.0, 167.0], "value": 168.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 67.0, 67.0, 64.0, 66.0, 70.0, 66.0, 68.0, 67.0], "value": 67.3, "unit": "score"}]}]} 05:05:46 INFO - Return code: 0 05:05:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:05:46 ERROR - # TBPL SUCCESS # 05:05:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:05:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:05:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:05:46 INFO - 2016-05-12 05:05:46.320 screenresolution[2530:78727] starting screenresolution argv=screenresolution get 05:05:46 INFO - 2016-05-12 05:05:46.323 screenresolution[2530:78727] Display 0: 1024x768x32@60 05:05:46 INFO - 2016-05-12 05:05:46.329 screenresolution[2531:78730] starting screenresolution argv=screenresolution list 05:05:46 INFO - Available Modes on Display 0 05:05:46 INFO - 1024x768x32@60 800x600x32@75 800x600x32@60 05:05:46 INFO - 640x480x32@75 640x480x32@60 Graphics/Displays: 05:05:46 INFO - Intel Iris: 05:05:46 INFO - Chipset Model: Intel Iris 05:05:46 INFO - Type: GPU 05:05:46 INFO - Bus: Built-In 05:05:46 INFO - VRAM (Dynamic, Max): 1536 MB 05:05:46 INFO - Vendor: Intel (0x8086) 05:05:46 INFO - Device ID: 0x0a2e 05:05:46 INFO - Revision ID: 0x0009 05:05:46 INFO - Displays: 05:05:46 INFO - Display: 05:05:46 INFO - Resolution: 1024 x 768 @ 60 Hz 05:05:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:05:46 INFO - Display Serial Number: 1024x768 60 05:05:46 INFO - Main Display: Yes 05:05:46 INFO - Mirror: Off 05:05:46 INFO - Online: Yes 05:05:46 INFO - Rotation: Supported 05:05:46 INFO - Return code: 0 05:05:46 INFO - Running post-action listener: _resource_record_post_action 05:05:46 INFO - ##### 05:05:46 INFO - ##### Finished run-tests step (success) 05:05:46 INFO - ##### 05:05:46 INFO - Running post-run listener: _resource_record_post_run 05:05:47 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 0; Write bytes: 697630208; Read time: 0; Write time: 1940 05:05:47 INFO - TinderboxPrint: CPU usage
54.7% 05:05:47 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 05:05:47 INFO - TinderboxPrint: I/O write bytes / time
697,630,208 / 1,940 05:05:47 INFO - TinderboxPrint: CPU idle
2,191.5 (45.3%) 05:05:47 INFO - TinderboxPrint: CPU system
978.8 (20.2%) 05:05:47 INFO - TinderboxPrint: CPU user
1,671.4 (34.5%) 05:05:47 INFO - TinderboxPrint: Swap in / out
393,482,240 / 0 05:05:47 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 394895872; Write bytes: 397834240; Read time: 29825; Write time: 797 05:05:47 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 0; Write bytes: 298948096; Read time: 0; Write time: 1139 05:05:47 INFO - Running post-run listener: _upload_blobber_files 05:05:47 INFO - Blob upload gear active. 05:05:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:05:47 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 05:05:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:05:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:05:48 INFO - (blobuploader) - INFO - Open directory for files ... 05:05:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:05:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:05:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:05:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:05:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:05:48 INFO - (blobuploader) - INFO - Done attempting. 05:05:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:05:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:05:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:05:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:05:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:05:50 INFO - (blobuploader) - INFO - Done attempting. 05:05:50 INFO - (blobuploader) - INFO - Iteration through files over. 05:05:50 INFO - Return code: 0 05:05:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:05:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:05:50 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6187d692ebb2689f17ebeff23c907d76c752f95ec06206bba529bd71d6830565dd0ddc81f4ba6ed4cfad4db48d103215f1e9b8daa4ca11ac403b7ef81b8eb4d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86593316e21890a59b5aaf9181ec0192d1236853b0f4aa9a844e25485310c89fbf1a1b661737e2e480c4b8843a0e5261863c4daaea9f7dcc05b3dc87b480d250"} 05:05:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:05:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:05:50 INFO - Contents: 05:05:50 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6187d692ebb2689f17ebeff23c907d76c752f95ec06206bba529bd71d6830565dd0ddc81f4ba6ed4cfad4db48d103215f1e9b8daa4ca11ac403b7ef81b8eb4d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86593316e21890a59b5aaf9181ec0192d1236853b0f4aa9a844e25485310c89fbf1a1b661737e2e480c4b8843a0e5261863c4daaea9f7dcc05b3dc87b480d250"} 05:05:50 INFO - Running post-run listener: copy_logs_to_upload_dir 05:05:50 INFO - Copying logs to upload dir... 05:05:50 INFO - mkdir: /builds/slave/test/build/upload/logs 05:05:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.426098 ========= master_lag: 0.20 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-05-12 05:05:50.720176) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 05:05:50.723931) ========= 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.P948Ohnh78/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.z1tjb06gAi/Listeners TMPDIR=/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6187d692ebb2689f17ebeff23c907d76c752f95ec06206bba529bd71d6830565dd0ddc81f4ba6ed4cfad4db48d103215f1e9b8daa4ca11ac403b7ef81b8eb4d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86593316e21890a59b5aaf9181ec0192d1236853b0f4aa9a844e25485310c89fbf1a1b661737e2e480c4b8843a0e5261863c4daaea9f7dcc05b3dc87b480d250"} build_url:https://queue.taskcluster.net/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011284 build_url: 'https://queue.taskcluster.net/v1/task/bNJ2WIjAS5ieTsNxgSuGgQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a6187d692ebb2689f17ebeff23c907d76c752f95ec06206bba529bd71d6830565dd0ddc81f4ba6ed4cfad4db48d103215f1e9b8daa4ca11ac403b7ef81b8eb4d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86593316e21890a59b5aaf9181ec0192d1236853b0f4aa9a844e25485310c89fbf1a1b661737e2e480c4b8843a0e5261863c4daaea9f7dcc05b3dc87b480d250"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 05:05:50.751915) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:05:50.752432) ========= 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.P948Ohnh78/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.z1tjb06gAi/Listeners TMPDIR=/var/folders/xl/2fl64x7x0nl14d20xydz31pc00000w/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.005097 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 05:05:50.801375) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 05:05:50.801689) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 05:05:50.802005) ========= ========= Total master_lag: 0.32 =========