builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0137 starttime: 1462584991.07 results: success (0) buildid: 20160506163539 builduid: 4735616e08b340879777bbb5a7dcc9bc revision: 738ec20418cc728910419aa60f98ae62b429facf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 18:36:31.071576) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 18:36:31.072053) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 18:36:31.072386) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 18:36:31.123517) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 18:36:31.123834) ========= 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.iVcZeIH6G1/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.ZvP3Kwt9lV/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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-06 18:36:31-- 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% 60.3M=0s 2016-05-06 18:36:31 (60.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.142249 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 18:36:31.283587) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 18:36:31.283906) ========= 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.iVcZeIH6G1/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.ZvP3Kwt9lV/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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.026336 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 18:36:31.332091) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 18:36:31.332496) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 738ec20418cc728910419aa60f98ae62b429facf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 738ec20418cc728910419aa60f98ae62b429facf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.iVcZeIH6G1/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.ZvP3Kwt9lV/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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-06 18:36:31,402 truncating revision to first 12 chars 2016-05-06 18:36:31,402 Setting DEBUG logging. 2016-05-06 18:36:31,402 attempt 1/10 2016-05-06 18:36:31,402 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/738ec20418cc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-06 18:36:32,064 unpacking tar archive at: fx-team-738ec20418cc/testing/mozharness/ program finished with exit code 0 elapsedTime=0.904628 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 18:36:32.254626) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 18:36:32.255073) ========= script_repo_revision: 738ec20418cc728910419aa60f98ae62b429facf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 18:36:32.255563) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 18:36:32.255869) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 18:36:32.269327) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-06 18:36:32.269715) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.iVcZeIH6G1/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.ZvP3Kwt9lV/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 18:36:32 INFO - MultiFileLogger online at 20160506 18:36:32 in /builds/slave/test 18:36:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 18:36:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:36:32 INFO - {'append_to_log': False, 18:36:32 INFO - 'base_work_dir': '/builds/slave/test', 18:36:32 INFO - 'blob_upload_branch': 'Fx-Team', 18:36:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:36:32 INFO - 'branch': 'Fx-Team', 18:36:32 INFO - 'buildbot_json_path': 'buildprops.json', 18:36:32 INFO - 'config_files': ('talos/mac_config.py',), 18:36:32 INFO - 'default_actions': ('clobber', 18:36:32 INFO - 'read-buildbot-config', 18:36:32 INFO - 'download-and-extract', 18:36:32 INFO - 'populate-webroot', 18:36:32 INFO - 'create-virtualenv', 18:36:32 INFO - 'install', 18:36:32 INFO - 'run-tests'), 18:36:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:36:32 INFO - 'download_minidump_stackwalk': True, 18:36:32 INFO - 'download_symbols': 'ondemand', 18:36:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:36:32 INFO - 'tooltool.py': '/tools/tooltool.py', 18:36:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:36:32 INFO - '/tools/misc-python/virtualenv.py')}, 18:36:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:36:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:36:32 INFO - 'installer_path': 'installer.exe', 18:36:32 INFO - 'log_level': 'info', 18:36:32 INFO - 'log_name': 'talos', 18:36:32 INFO - 'log_to_console': True, 18:36:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:36:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:36:32 INFO - 'opt_config_files': (), 18:36:32 INFO - 'pip_index': False, 18:36:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:36:32 INFO - 'cmd': ('bash', 18:36:32 INFO - '-c', 18:36:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:36:32 INFO - 'enabled': True, 18:36:32 INFO - 'halt_on_failure': False, 18:36:32 INFO - 'name': 'check_screen_resolution'},), 18:36:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:36:32 INFO - 'cmd': ('bash', 18:36:32 INFO - '-c', 18:36:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:36:32 INFO - 'enabled': True, 18:36:32 INFO - 'halt_on_failure': False, 18:36:32 INFO - 'name': 'check_screen_resolution'},), 18:36:32 INFO - 'run_cmd_checks_enabled': True, 18:36:32 INFO - 'sps_profile': False, 18:36:32 INFO - 'sps_profile_interval': 0, 18:36:32 INFO - 'suite': 'dromaeojs-e10s', 18:36:32 INFO - 'system_bits': '32', 18:36:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:36:32 INFO - 'title': 't-yosemite-r7-0137', 18:36:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:36:32 INFO - 'use_talos_json': True, 18:36:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:36:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:36:32 INFO - 'work_dir': 'build'} 18:36:32 INFO - ##### 18:36:32 INFO - ##### Running clobber step. 18:36:32 INFO - ##### 18:36:32 INFO - Running pre-action listener: _resource_record_pre_action 18:36:32 INFO - Running main action method: clobber 18:36:32 INFO - rmtree: /builds/slave/test/build 18:36:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:36:34 INFO - Running post-action listener: _resource_record_post_action 18:36:34 INFO - ##### 18:36:34 INFO - ##### Finished clobber step (success) 18:36:34 INFO - ##### 18:36:34 INFO - ##### 18:36:34 INFO - ##### Running read-buildbot-config step. 18:36:34 INFO - ##### 18:36:34 INFO - Running pre-action listener: _resource_record_pre_action 18:36:34 INFO - Running main action method: read_buildbot_config 18:36:34 INFO - Using buildbot properties: 18:36:34 INFO - { 18:36:34 INFO - "project": "", 18:36:34 INFO - "product": "firefox", 18:36:34 INFO - "script_repo_revision": "production", 18:36:34 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 18:36:34 INFO - "repository": "", 18:36:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 18:36:34 INFO - "buildid": "20160506163539", 18:36:34 INFO - "pgo_build": "False", 18:36:34 INFO - "basedir": "/builds/slave/test", 18:36:34 INFO - "buildnumber": 0, 18:36:34 INFO - "slavename": "t-yosemite-r7-0137", 18:36:34 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 18:36:34 INFO - "platform": "yosemite_r7", 18:36:34 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 18:36:34 INFO - "branch": "fx-team", 18:36:34 INFO - "repo_path": "integration/fx-team", 18:36:34 INFO - "revision": "738ec20418cc728910419aa60f98ae62b429facf", 18:36:34 INFO - "stage_platform": "macosx64", 18:36:34 INFO - "builduid": "4735616e08b340879777bbb5a7dcc9bc", 18:36:34 INFO - "slavebuilddir": "test" 18:36:34 INFO - } 18:36:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/CoK-sF93RuOSwnGJ7CiRhA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 18:36:34 INFO - Running post-action listener: _resource_record_post_action 18:36:34 INFO - ##### 18:36:34 INFO - ##### Finished read-buildbot-config step (success) 18:36:34 INFO - ##### 18:36:34 INFO - ##### 18:36:34 INFO - ##### Running download-and-extract step. 18:36:34 INFO - ##### 18:36:34 INFO - Running pre-action listener: _resource_record_pre_action 18:36:34 INFO - Running main action method: download_and_extract 18:36:34 INFO - mkdir: /builds/slave/test/build/tests 18:36:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:36:34 INFO - https://queue.taskcluster.net/v1/task/CoK-sF93RuOSwnGJ7CiRhA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:36:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CoK-sF93RuOSwnGJ7CiRhA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:36:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CoK-sF93RuOSwnGJ7CiRhA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 18:36:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CoK-sF93RuOSwnGJ7CiRhA/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 18:36:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CoK-sF93RuOSwnGJ7CiRhA/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 18:36:35 INFO - Downloaded 1199 bytes. 18:36:35 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 18:36:35 INFO - Using the following test package requirements: 18:36:35 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:36:35 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:36:35 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 18:36:35 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:36:35 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 18:36:35 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:36:35 INFO - u'jsshell-mac.zip'], 18:36:35 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:36:35 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 18:36:35 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 18:36:35 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:36:35 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 18:36:35 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:36:35 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 18:36:35 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:36:35 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 18:36:35 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 18:36:35 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 18:36:35 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:36: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')]} 18:36:35 INFO - https://queue.taskcluster.net/v1/task/CoK-sF93RuOSwnGJ7CiRhA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:36:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CoK-sF93RuOSwnGJ7CiRhA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:36:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CoK-sF93RuOSwnGJ7CiRhA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:36:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CoK-sF93RuOSwnGJ7CiRhA/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 18:36:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CoK-sF93RuOSwnGJ7CiRhA/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 18:36:37 INFO - Downloaded 18144996 bytes. 18:36:37 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'] 18:36:37 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 18:36:40 INFO - Return code: 0 18:36:40 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 18:36:40 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')]} 18:36:40 INFO - https://queue.taskcluster.net/v1/task/CoK-sF93RuOSwnGJ7CiRhA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:36:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CoK-sF93RuOSwnGJ7CiRhA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:36:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CoK-sF93RuOSwnGJ7CiRhA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 18:36:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CoK-sF93RuOSwnGJ7CiRhA/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 18:36:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CoK-sF93RuOSwnGJ7CiRhA/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 18:36:42 INFO - Downloaded 18144996 bytes. 18:36:42 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'] 18:36:42 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 18:36:45 INFO - Return code: 0 18:36:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:36:45 INFO - https://queue.taskcluster.net/v1/task/CoK-sF93RuOSwnGJ7CiRhA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:36:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CoK-sF93RuOSwnGJ7CiRhA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:36:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CoK-sF93RuOSwnGJ7CiRhA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 18:36:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CoK-sF93RuOSwnGJ7CiRhA/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 18:36:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CoK-sF93RuOSwnGJ7CiRhA/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 18:36:46 INFO - Downloaded 10840152 bytes. 18:36:46 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'] 18:36:46 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 18:36:46 INFO - Return code: 0 18:36: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')]} 18:36:46 INFO - https://queue.taskcluster.net/v1/task/CoK-sF93RuOSwnGJ7CiRhA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 18:36:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CoK-sF93RuOSwnGJ7CiRhA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:36:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CoK-sF93RuOSwnGJ7CiRhA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:36:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CoK-sF93RuOSwnGJ7CiRhA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 18:36:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CoK-sF93RuOSwnGJ7CiRhA/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 18:36:49 INFO - Downloaded 110442881 bytes. 18:36:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CoK-sF93RuOSwnGJ7CiRhA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:36:49 INFO - mkdir: /builds/slave/test/properties 18:36:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:36:49 INFO - Writing to file /builds/slave/test/properties/build_url 18:36:49 INFO - Contents: 18:36:49 INFO - build_url:https://queue.taskcluster.net/v1/task/CoK-sF93RuOSwnGJ7CiRhA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 18:36:51 INFO - Running post-action listener: _resource_record_post_action 18:36:51 INFO - Running post-action listener: set_extra_try_arguments 18:36:51 INFO - ##### 18:36:51 INFO - ##### Finished download-and-extract step (success) 18:36:51 INFO - ##### 18:36:51 INFO - ##### 18:36:51 INFO - ##### Running populate-webroot step. 18:36:51 INFO - ##### 18:36:51 INFO - Running pre-action listener: _resource_record_pre_action 18:36:51 INFO - Running main action method: populate_webroot 18:36:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:36:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:36:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:36:51 INFO - '--tppagecycles', 18:36:51 INFO - '7'], 18:36:51 INFO - 'tests': ['tsvgm']}}, 18:36:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:36:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:36:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:36:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:36:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:36:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:36:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:36:51 INFO - 'talos_options': ['--disable-e10s'], 18:36:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:36:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:36:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:36:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:36:51 INFO - 'talos_options': ['--disable-e10s'], 18:36:51 INFO - 'tests': ['damp', 'tps']}, 18:36:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:36:51 INFO - 'tests': ['damp', 'tps']}, 18:36:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:36:51 INFO - 'tests': ['dromaeo_dom']}, 18:36:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:36:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:36:51 INFO - 'tests': ['a11yr', 18:36:51 INFO - 'ts_paint', 18:36:51 INFO - 'tpaint', 18:36:51 INFO - 'sessionrestore', 18:36:51 INFO - 'sessionrestore_no_auto_restore', 18:36:51 INFO - 'tabpaint']}, 18:36:51 INFO - 'other-e10s': {'tests': ['a11yr', 18:36:51 INFO - 'ts_paint', 18:36:51 INFO - 'tpaint', 18:36:51 INFO - 'sessionrestore', 18:36:51 INFO - 'sessionrestore_no_auto_restore', 18:36:51 INFO - 'tabpaint']}, 18:36:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:36:51 INFO - 'ts_paint', 18:36:51 INFO - 'tpaint', 18:36:51 INFO - 'sessionrestore', 18:36:51 INFO - 'sessionrestore_no_auto_restore', 18:36:51 INFO - 'tabpaint']}, 18:36:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:36:51 INFO - 'ts_paint', 18:36:51 INFO - 'tpaint', 18:36:51 INFO - 'sessionrestore', 18:36:51 INFO - 'sessionrestore_no_auto_restore', 18:36:51 INFO - 'tabpaint']}, 18:36:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:36:51 INFO - 'tests': ['a11yr', 18:36:51 INFO - 'ts_paint', 18:36:51 INFO - 'tpaint', 18:36:51 INFO - 'sessionrestore', 18:36:51 INFO - 'sessionrestore_no_auto_restore', 18:36:51 INFO - 'tabpaint']}, 18:36:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:36:51 INFO - 'tests': ['a11yr', 18:36:51 INFO - 'ts_paint', 18:36:51 INFO - 'tpaint', 18:36:51 INFO - 'sessionrestore', 18:36:51 INFO - 'sessionrestore_no_auto_restore', 18:36:51 INFO - 'tabpaint']}, 18:36:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:36:51 INFO - 'tests': ['tsvgx', 18:36:51 INFO - 'tsvgr_opacity', 18:36:51 INFO - 'tart', 18:36:51 INFO - 'tscrollx', 18:36:51 INFO - 'cart']}, 18:36:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:36:51 INFO - 'tsvgr_opacity', 18:36:51 INFO - 'tart', 18:36:51 INFO - 'tscrollx', 18:36:51 INFO - 'cart']}, 18:36:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:36:51 INFO - 'talos_options': ['--disable-e10s'], 18:36:51 INFO - 'tests': ['tp5o']}, 18:36:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:36:51 INFO - 'tests': ['tp5o']}, 18:36:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:36:51 INFO - 'talos_options': ['--disable-e10s', 18:36:51 INFO - '--xperf_path', 18:36:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:36:51 INFO - 'tests': ['tp5n']}, 18:36:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:36:51 INFO - 'talos_options': ['--xperf_path', 18:36:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:36:51 INFO - 'tests': ['tp5n']}}, 18:36:51 INFO - 'talos.zip': {'path': '', 18:36:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:36:51 INFO - Running post-action listener: _resource_record_post_action 18:36:51 INFO - ##### 18:36:51 INFO - ##### Finished populate-webroot step (success) 18:36:51 INFO - ##### 18:36:51 INFO - ##### 18:36:51 INFO - ##### Running create-virtualenv step. 18:36:51 INFO - ##### 18:36:51 INFO - Running pre-action listener: _resource_record_pre_action 18:36:51 INFO - Running main action method: create_virtualenv 18:36:51 INFO - Creating virtualenv /builds/slave/test/build/venv 18:36:51 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 18:36:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:36:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:36:51 INFO - Using real prefix '/tools/python27' 18:36:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:36:51 INFO - Installing distribute.............................................................................................................................................................................................done. 18:36:54 INFO - Installing pip.................done. 18:36:54 INFO - Return code: 0 18:36:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:36:54 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')]} 18:36:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:54 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')]} 18:36:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:54 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 0x103b93a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b99570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc468d79a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a37d30>, '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 0x10385bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103a2eb20>, '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.iVcZeIH6G1/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.ZvP3Kwt9lV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 18:36:54 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 18:36:54 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 18:36:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iVcZeIH6G1/Render', 18:36:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:36:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:36:54 INFO - 'HOME': '/Users/cltbld', 18:36:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:36:54 INFO - 'LOGNAME': 'cltbld', 18:36:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:54 INFO - 'MOZ_NO_REMOTE': '1', 18:36:54 INFO - 'NO_EM_RESTART': '1', 18:36:54 INFO - 'PAGER': '/bin/cat', 18:36:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:36:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:54 INFO - 'PWD': '/builds/slave/test', 18:36:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:36:54 INFO - 'SHELL': '/bin/bash', 18:36:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZvP3Kwt9lV/Listeners', 18:36:54 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 18:36:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:36:54 INFO - 'USER': 'cltbld', 18:36:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:36:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:36:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:54 INFO - 'XPC_FLAGS': '0x0', 18:36:54 INFO - 'XPC_SERVICE_NAME': '0', 18:36:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:36:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:55 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 18:36:55 INFO - Cleaning up... 18:36:55 INFO - Return code: 0 18:36:55 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:36: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')]} 18:36:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36: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')]} 18:36:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:55 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 0x103b93a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b99570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc468d79a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a37d30>, '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 0x10385bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103a2eb20>, '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.iVcZeIH6G1/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.ZvP3Kwt9lV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 18:36:55 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 18:36:55 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 18:36:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iVcZeIH6G1/Render', 18:36:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:36:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:36:55 INFO - 'HOME': '/Users/cltbld', 18:36:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:36:55 INFO - 'LOGNAME': 'cltbld', 18:36:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:55 INFO - 'MOZ_NO_REMOTE': '1', 18:36:55 INFO - 'NO_EM_RESTART': '1', 18:36:55 INFO - 'PAGER': '/bin/cat', 18:36:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:36:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:55 INFO - 'PWD': '/builds/slave/test', 18:36:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:36:55 INFO - 'SHELL': '/bin/bash', 18:36:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZvP3Kwt9lV/Listeners', 18:36:55 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 18:36:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:36:55 INFO - 'USER': 'cltbld', 18:36:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:36:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:36:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:55 INFO - 'XPC_FLAGS': '0x0', 18:36:55 INFO - 'XPC_SERVICE_NAME': '0', 18:36:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:36:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:55 INFO - Downloading/unpacking psutil>=0.7.1 18:36:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:36:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:36:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:36:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:36:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:36:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:36:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:36:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:36:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:36:58 INFO - Installing collected packages: psutil 18:36:58 INFO - Running setup.py install for psutil 18:36:58 INFO - building 'psutil._psutil_osx' extension 18:36:58 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 18:36:58 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 18:36:59 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 18:36:59 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 18:36:59 INFO - building 'psutil._psutil_posix' extension 18:36:59 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 18:36:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:36:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:36:59 INFO - ^ 18:36:59 INFO - 1 warning generated. 18:36:59 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 18:36:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:36:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:36:59 INFO - Successfully installed psutil 18:36:59 INFO - Cleaning up... 18:36:59 INFO - Return code: 0 18:36:59 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:36:59 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')]} 18:36:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:36:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:59 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')]} 18:36:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:36:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:36:59 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 0x103b93a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b99570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc468d79a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a37d30>, '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 0x10385bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103a2eb20>, '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.iVcZeIH6G1/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.ZvP3Kwt9lV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 18:36:59 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 18:36:59 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 18:36:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iVcZeIH6G1/Render', 18:36:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:36:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:36:59 INFO - 'HOME': '/Users/cltbld', 18:36:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:36:59 INFO - 'LOGNAME': 'cltbld', 18:36:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:36:59 INFO - 'MOZ_NO_REMOTE': '1', 18:36:59 INFO - 'NO_EM_RESTART': '1', 18:36:59 INFO - 'PAGER': '/bin/cat', 18:36:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:36:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:36:59 INFO - 'PWD': '/builds/slave/test', 18:36:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:36:59 INFO - 'SHELL': '/bin/bash', 18:36:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZvP3Kwt9lV/Listeners', 18:36:59 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 18:36:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:36:59 INFO - 'USER': 'cltbld', 18:36:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:36:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:36:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:36:59 INFO - 'XPC_FLAGS': '0x0', 18:36:59 INFO - 'XPC_SERVICE_NAME': '0', 18:36:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:36:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:36:59 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:36:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:36:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:36:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:36:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:36:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:36:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:37:02 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:37:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:37:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:37:02 INFO - Installing collected packages: mozsystemmonitor 18:37:02 INFO - Running setup.py install for mozsystemmonitor 18:37:02 INFO - Successfully installed mozsystemmonitor 18:37:02 INFO - Cleaning up... 18:37:02 INFO - Return code: 0 18:37:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:37:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:37:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:37:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:37:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:37:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:02 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 0x103b93a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b99570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc468d79a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a37d30>, '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 0x10385bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103a2eb20>, '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.iVcZeIH6G1/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.ZvP3Kwt9lV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 18:37:02 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 18:37:02 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 18:37:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iVcZeIH6G1/Render', 18:37:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:37:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:37:02 INFO - 'HOME': '/Users/cltbld', 18:37:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:37:02 INFO - 'LOGNAME': 'cltbld', 18:37:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:37:02 INFO - 'MOZ_NO_REMOTE': '1', 18:37:02 INFO - 'NO_EM_RESTART': '1', 18:37:02 INFO - 'PAGER': '/bin/cat', 18:37:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:37:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:37:02 INFO - 'PWD': '/builds/slave/test', 18:37:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:37:02 INFO - 'SHELL': '/bin/bash', 18:37:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZvP3Kwt9lV/Listeners', 18:37:02 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 18:37:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:37:02 INFO - 'USER': 'cltbld', 18:37:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:37:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:37:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:37:02 INFO - 'XPC_FLAGS': '0x0', 18:37:02 INFO - 'XPC_SERVICE_NAME': '0', 18:37:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:37:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:37:02 INFO - Downloading/unpacking blobuploader==1.2.4 18:37:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:37:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:37:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:37:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:37:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:37:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:37:05 INFO - Downloading blobuploader-1.2.4.tar.gz 18:37:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:37:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:37: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 18:37: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 18:37: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 18:37: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 18:37: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 18:37: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 18:37:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:37:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:37: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 18:37: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 18:37: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 18:37: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 18:37: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 18:37: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 18:37:06 INFO - Downloading docopt-0.6.1.tar.gz 18:37:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:37:06 INFO - Installing collected packages: blobuploader, requests, docopt 18:37:06 INFO - Running setup.py install for blobuploader 18:37:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:37:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:37:06 INFO - Running setup.py install for requests 18:37:07 INFO - Running setup.py install for docopt 18:37:07 INFO - Successfully installed blobuploader requests docopt 18:37:07 INFO - Cleaning up... 18:37:07 INFO - Return code: 0 18:37:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:37:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:37:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:37:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:37:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:37:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:07 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 0x103b93a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b99570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc468d79a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a37d30>, '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 0x10385bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103a2eb20>, '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.iVcZeIH6G1/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.ZvP3Kwt9lV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 18:37:07 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 18:37:07 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 18:37:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iVcZeIH6G1/Render', 18:37:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:37:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:37:07 INFO - 'HOME': '/Users/cltbld', 18:37:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:37:07 INFO - 'LOGNAME': 'cltbld', 18:37:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:37:07 INFO - 'MOZ_NO_REMOTE': '1', 18:37:07 INFO - 'NO_EM_RESTART': '1', 18:37:07 INFO - 'PAGER': '/bin/cat', 18:37:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:37:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:37:07 INFO - 'PWD': '/builds/slave/test', 18:37:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:37:07 INFO - 'SHELL': '/bin/bash', 18:37:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZvP3Kwt9lV/Listeners', 18:37:07 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 18:37:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:37:07 INFO - 'USER': 'cltbld', 18:37:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:37:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:37:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:37:07 INFO - 'XPC_FLAGS': '0x0', 18:37:07 INFO - 'XPC_SERVICE_NAME': '0', 18:37:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:37:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:37:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:37:07 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-ogRqpk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:37:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:37:07 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-SQy3Fj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:37:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:37:07 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-2CrPlN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:37:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:37:07 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-1LF7S1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:37:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:37:08 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-JZdhN2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:37:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:37:08 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-4rUKoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:37:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:37:08 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-oQxctq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:37:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:37:08 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-NWeyP3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:37:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:37:08 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-9OLttP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:37:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:37:08 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-AZCQUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:37:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:37:08 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-Qom167-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:37:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:37:08 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-DoCoau-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:37:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:37:08 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-vz3oyV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:37:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:37:08 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-h2UCTs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:37:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:37:09 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-7nHRhT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:37:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:37:09 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-cgkpIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:37:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:37:09 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-oUz5ME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:37:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:37:09 INFO - Running setup.py install for manifestparser 18:37:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:37:09 INFO - Running setup.py install for mozcrash 18:37:09 INFO - Running setup.py install for mozdebug 18:37:09 INFO - Running setup.py install for mozdevice 18:37:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:37:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:37:09 INFO - Running setup.py install for mozfile 18:37:10 INFO - Running setup.py install for mozhttpd 18:37:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:37:10 INFO - Running setup.py install for mozinfo 18:37:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:37:10 INFO - Running setup.py install for mozInstall 18:37:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:37:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:37:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:37:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:37:10 INFO - Running setup.py install for mozleak 18:37:10 INFO - Running setup.py install for mozlog 18:37:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:37:10 INFO - Running setup.py install for moznetwork 18:37:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:37:11 INFO - Running setup.py install for mozprocess 18:37:11 INFO - Running setup.py install for mozprofile 18:37:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:37:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:37:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:37:11 INFO - Running setup.py install for mozrunner 18:37:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:37:11 INFO - Running setup.py install for mozscreenshot 18:37:11 INFO - Running setup.py install for moztest 18:37:11 INFO - Running setup.py install for mozversion 18:37:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:37:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:37:11 INFO - Cleaning up... 18:37:11 INFO - Return code: 0 18:37:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:37:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:37:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:37:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37: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')]} 18:37:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:37:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:12 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 0x103b93a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b99570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc468d79a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a37d30>, '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 0x10385bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103a2eb20>, '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.iVcZeIH6G1/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.ZvP3Kwt9lV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 18:37:12 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 18:37:12 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 18:37:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iVcZeIH6G1/Render', 18:37:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:37:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:37:12 INFO - 'HOME': '/Users/cltbld', 18:37:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:37:12 INFO - 'LOGNAME': 'cltbld', 18:37:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:37:12 INFO - 'MOZ_NO_REMOTE': '1', 18:37:12 INFO - 'NO_EM_RESTART': '1', 18:37:12 INFO - 'PAGER': '/bin/cat', 18:37:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:37:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:37:12 INFO - 'PWD': '/builds/slave/test', 18:37:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:37:12 INFO - 'SHELL': '/bin/bash', 18:37:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZvP3Kwt9lV/Listeners', 18:37:12 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 18:37:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:37:12 INFO - 'USER': 'cltbld', 18:37:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:37:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:37:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:37:12 INFO - 'XPC_FLAGS': '0x0', 18:37:12 INFO - 'XPC_SERVICE_NAME': '0', 18:37:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:37:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:37:12 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-HoPEBb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:37:12 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)) 18:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:37:12 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-ubsjht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:37:12 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)) 18:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:37:12 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-u080L6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:37:12 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)) 18:37:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:37:12 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-tXD8pw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:37:13 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)) 18:37:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:37:13 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-mCmuqg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:37:13 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)) 18:37:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:37:13 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-PBFOSi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:37:13 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)) 18:37:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:37:13 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-VMPk_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:37:13 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)) 18:37:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:37:13 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-sHfBhM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:37:13 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)) 18:37:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:37:13 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-fgX5wx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:37:13 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)) 18:37:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:37:13 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-YDZ_Go-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:37:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:37:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:37:13 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-rGjmN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:37:13 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)) 18:37:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:37:13 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-Hx2txC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:37:13 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)) 18:37:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:37:13 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-zSE3u0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:37:13 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)) 18:37:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:37:13 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-iiCF4G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:37:14 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)) 18:37:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:37:14 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-CV4QWm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:37:14 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)) 18:37:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:37:14 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-Nqoekx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:37:14 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)) 18:37:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:37:14 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-nbci3j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:37:14 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)) 18:37:14 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)) 18:37:14 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)) 18:37:14 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)) 18:37:14 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)) 18:37:14 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)) 18:37:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:37:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:37:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:37:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:37:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:37:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:37:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:37:17 INFO - Downloading blessings-1.6.tar.gz 18:37:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:37:17 INFO - Installing collected packages: blessings 18:37:17 INFO - Running setup.py install for blessings 18:37:17 INFO - Successfully installed blessings 18:37:17 INFO - Cleaning up... 18:37:17 INFO - Return code: 0 18:37:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:37:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:37:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:37:17 INFO - Reading from file tmpfile_stdout 18:37:17 INFO - Current package versions: 18:37:17 INFO - blessings == 1.6 18:37:17 INFO - blobuploader == 1.2.4 18:37:17 INFO - docopt == 0.6.1 18:37:17 INFO - manifestparser == 1.1 18:37:17 INFO - mozInstall == 1.12 18:37:17 INFO - mozcrash == 0.17 18:37:17 INFO - mozdebug == 0.1 18:37:17 INFO - mozdevice == 0.48 18:37:17 INFO - mozfile == 1.2 18:37:17 INFO - mozhttpd == 0.7 18:37:17 INFO - mozinfo == 0.9 18:37:17 INFO - mozleak == 0.1 18:37:17 INFO - mozlog == 3.1 18:37:17 INFO - moznetwork == 0.27 18:37:17 INFO - mozprocess == 0.22 18:37:17 INFO - mozprofile == 0.28 18:37:17 INFO - mozrunner == 6.11 18:37:17 INFO - mozscreenshot == 0.1 18:37:17 INFO - mozsystemmonitor == 0.0 18:37:17 INFO - moztest == 0.7 18:37:17 INFO - mozversion == 1.4 18:37:17 INFO - psutil == 3.1.1 18:37:17 INFO - requests == 1.2.3 18:37:17 INFO - wsgiref == 0.1.2 18:37:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:37: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')]} 18:37:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:37:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37: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')]} 18:37:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:37:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:17 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 0x103b93a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b99570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc468d79a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a37d30>, '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 0x10385bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103a2eb20>, '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.iVcZeIH6G1/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.ZvP3Kwt9lV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 18:37:17 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 18:37:17 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 18:37:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iVcZeIH6G1/Render', 18:37:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:37:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:37:17 INFO - 'HOME': '/Users/cltbld', 18:37:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:37:17 INFO - 'LOGNAME': 'cltbld', 18:37:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:37:17 INFO - 'MOZ_NO_REMOTE': '1', 18:37:17 INFO - 'NO_EM_RESTART': '1', 18:37:17 INFO - 'PAGER': '/bin/cat', 18:37:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:37:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:37:17 INFO - 'PWD': '/builds/slave/test', 18:37:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:37:17 INFO - 'SHELL': '/bin/bash', 18:37:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZvP3Kwt9lV/Listeners', 18:37:17 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 18:37:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:37:17 INFO - 'USER': 'cltbld', 18:37:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:37:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:37:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:37:17 INFO - 'XPC_FLAGS': '0x0', 18:37:17 INFO - 'XPC_SERVICE_NAME': '0', 18:37:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:37:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:37:18 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)) 18:37:18 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)) 18:37:18 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)) 18:37:18 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)) 18:37:18 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)) 18:37:18 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)) 18:37:18 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)) 18:37:18 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)) 18:37:18 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)) 18:37:18 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)) 18:37:18 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)) 18:37:18 INFO - Cleaning up... 18:37:18 INFO - Return code: 0 18:37:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:37:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:37:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:37:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:37:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:37:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:18 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 0x103b93a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b99570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc468d79a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a37d30>, '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 0x10385bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103a2eb20>, '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.iVcZeIH6G1/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.ZvP3Kwt9lV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 18:37:18 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 18:37:18 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 18:37:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iVcZeIH6G1/Render', 18:37:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:37:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:37:18 INFO - 'HOME': '/Users/cltbld', 18:37:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:37:18 INFO - 'LOGNAME': 'cltbld', 18:37:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:37:18 INFO - 'MOZ_NO_REMOTE': '1', 18:37:18 INFO - 'NO_EM_RESTART': '1', 18:37:18 INFO - 'PAGER': '/bin/cat', 18:37:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:37:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:37:18 INFO - 'PWD': '/builds/slave/test', 18:37:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:37:18 INFO - 'SHELL': '/bin/bash', 18:37:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZvP3Kwt9lV/Listeners', 18:37:18 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 18:37:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:37:18 INFO - 'USER': 'cltbld', 18:37:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:37:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:37:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:37:18 INFO - 'XPC_FLAGS': '0x0', 18:37:18 INFO - 'XPC_SERVICE_NAME': '0', 18:37:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:37:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:37:18 INFO - Downloading/unpacking jsonschema 18:37:18 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 18:37:18 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 18:37:18 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 18:37:18 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 18:37:18 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 18:37:18 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 18:37:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:37:20 INFO - Downloading/unpacking functools32 (from jsonschema) 18:37:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:37:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:37:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:37:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:37:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:37:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:37:21 INFO - Downloading functools32-3.2.3-2.tar.gz 18:37:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:37:21 INFO - warning: no files found matching '*.txt' 18:37:21 INFO - no previously-included directories found matching 'build' 18:37:21 INFO - no previously-included directories found matching 'dist' 18:37:21 INFO - no previously-included directories found matching '.git*' 18:37:21 INFO - Installing collected packages: jsonschema, functools32 18:37:21 INFO - Running setup.py install for functools32 18:37:21 INFO - warning: no files found matching '*.txt' 18:37:21 INFO - no previously-included directories found matching 'build' 18:37:21 INFO - no previously-included directories found matching 'dist' 18:37:21 INFO - no previously-included directories found matching '.git*' 18:37:21 INFO - Successfully installed jsonschema functools32 18:37:21 INFO - Cleaning up... 18:37:21 INFO - Return code: 0 18:37:21 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:37:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:37:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:37:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:37:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:37:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:37:21 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 0x103b93a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b99570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc468d79a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a37d30>, '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 0x10385bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103a2eb20>, '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.iVcZeIH6G1/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.ZvP3Kwt9lV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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 18:37:21 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 18:37:21 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 18:37:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iVcZeIH6G1/Render', 18:37:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:37:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:37:21 INFO - 'HOME': '/Users/cltbld', 18:37:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:37:21 INFO - 'LOGNAME': 'cltbld', 18:37:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:37:21 INFO - 'MOZ_NO_REMOTE': '1', 18:37:21 INFO - 'NO_EM_RESTART': '1', 18:37:21 INFO - 'PAGER': '/bin/cat', 18:37:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:37:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:37:21 INFO - 'PWD': '/builds/slave/test', 18:37:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:37:21 INFO - 'SHELL': '/bin/bash', 18:37:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZvP3Kwt9lV/Listeners', 18:37:21 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 18:37:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:37:21 INFO - 'USER': 'cltbld', 18:37:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:37:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:37:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:37:21 INFO - 'XPC_FLAGS': '0x0', 18:37:21 INFO - 'XPC_SERVICE_NAME': '0', 18:37:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:37:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:37:21 INFO - Downloading/unpacking flake8 18:37:21 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 18:37:21 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 18:37:21 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 18:37:21 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 18:37:21 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 18:37:21 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 18:37:24 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:37:24 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:37: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 18:37: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 18:37: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 18:37: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 18:37: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 18:37: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 18:37:24 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:37:24 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:37: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 18:37: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 18:37: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 18:37: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 18:37: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 18:37: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 18:37:25 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:37:25 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 18:37:25 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 18:37:25 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 18:37:25 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 18:37:25 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 18:37:25 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 18:37:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:37:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:37:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:37:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:37:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:37:25 INFO - no previously-included directories found matching 'docs/_build' 18:37:25 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:37:25 INFO - Running setup.py install for pep8 18:37:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:37:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:37:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:37:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:37:25 INFO - no previously-included directories found matching 'docs/_build' 18:37:25 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:37:25 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:37:25 INFO - Cleaning up... 18:37:25 INFO - Return code: 0 18:37:25 INFO - Running post-action listener: _resource_record_post_action 18:37:25 INFO - Running post-action listener: _start_resource_monitoring 18:37:25 INFO - Starting resource monitoring. 18:37:25 INFO - ##### 18:37:25 INFO - ##### Finished create-virtualenv step (success) 18:37:25 INFO - ##### 18:37:25 INFO - ##### 18:37:25 INFO - ##### Running install step. 18:37:25 INFO - ##### 18:37:25 INFO - Running pre-action listener: _resource_record_pre_action 18:37:25 INFO - Running main action method: install 18:37:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:37:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:37:26 INFO - Reading from file tmpfile_stdout 18:37:26 INFO - Detecting whether we're running mozinstall >=1.0... 18:37:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:37:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:37:26 INFO - Reading from file tmpfile_stdout 18:37:26 INFO - Output received: 18:37:26 INFO - Usage: mozinstall [options] installer 18:37:26 INFO - Options: 18:37:26 INFO - -h, --help show this help message and exit 18:37:26 INFO - -d DEST, --destination=DEST 18:37:26 INFO - Directory to install application into. [default: 18:37:26 INFO - "/builds/slave/test"] 18:37:26 INFO - --app=APP Application being installed. [default: firefox] 18:37:26 INFO - mkdir: /builds/slave/test/build/application 18:37:26 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'] 18:37:26 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 18:38:00 INFO - Reading from file tmpfile_stdout 18:38:00 INFO - Output received: 18:38:00 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:38:00 INFO - Running post-action listener: _resource_record_post_action 18:38:00 INFO - ##### 18:38:00 INFO - ##### Finished install step (success) 18:38:00 INFO - ##### 18:38:00 INFO - ##### 18:38:00 INFO - ##### Running run-tests step. 18:38:00 INFO - ##### 18:38:00 INFO - Running pre-action listener: _resource_record_pre_action 18:38:00 INFO - Running main action method: run_tests 18:38:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:38:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:38:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:38:00 INFO - 2016-05-06 18:38:00.036 screenresolution[1635:7644] starting screenresolution argv=screenresolution get 18:38:00 INFO - 2016-05-06 18:38:00.039 screenresolution[1635:7644] Display 0: 1600x1200x32@60 18:38:00 INFO - 2016-05-06 18:38:00.044 screenresolution[1636:7646] starting screenresolution argv=screenresolution list 18:38:00 INFO - Available Modes on Display 0 18:38:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:38:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:38:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:38:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:38:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:38:00 INFO - Intel Iris: 18:38:00 INFO - Chipset Model: Intel Iris 18:38:00 INFO - Type: GPU 18:38:00 INFO - Bus: Built-In 18:38:00 INFO - VRAM (Dynamic, Max): 1536 MB 18:38:00 INFO - Vendor: Intel (0x8086) 18:38:00 INFO - Device ID: 0x0a2e 18:38:00 INFO - Revision ID: 0x0009 18:38:00 INFO - Displays: 18:38:00 INFO - Display: 18:38:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:38:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:38:00 INFO - Display Serial Number: 1600x1200 60 18:38:00 INFO - Main Display: Yes 18:38:00 INFO - Mirror: Off 18:38:00 INFO - Online: Yes 18:38:00 INFO - Rotation: Supported 18:38:00 INFO - Return code: 0 18:38:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:38:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:38:00 INFO - Python 2.7.3 18:38:00 INFO - Return code: 0 18:38:00 INFO - grabbing minidump binary from tooltool 18:38:00 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')]} 18:38:00 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a37d30>, '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 0x10385bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103a2eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:38:00 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 18:38:00 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 18:38:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:38:00 INFO - Return code: 0 18:38:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:38:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:38:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:38:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:38:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:38:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:38:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:38:01 INFO - Return code: 0 18:38:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CoK-sF93RuOSwnGJ7CiRhA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0137', '--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 18:38:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CoK-sF93RuOSwnGJ7CiRhA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0137 --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 18:38:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iVcZeIH6G1/Render', 18:38:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:38:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:38:01 INFO - 'HOME': '/Users/cltbld', 18:38:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:38:01 INFO - 'LOGNAME': 'cltbld', 18:38:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:38:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:38:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:38:01 INFO - 'MOZ_NO_REMOTE': '1', 18:38:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:38:01 INFO - 'NO_EM_RESTART': '1', 18:38:01 INFO - 'PAGER': '/bin/cat', 18:38:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:38:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:38:01 INFO - 'PWD': '/builds/slave/test', 18:38:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:38:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:38:01 INFO - 'SHELL': '/bin/bash', 18:38:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZvP3Kwt9lV/Listeners', 18:38:01 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 18:38:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:38:01 INFO - 'USER': 'cltbld', 18:38:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:38:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:38:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:38:01 INFO - 'XPC_FLAGS': '0x0', 18:38:01 INFO - 'XPC_SERVICE_NAME': '0', 18:38:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:38:01 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CoK-sF93RuOSwnGJ7CiRhA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0137', '--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 18:38:01 INFO - mozversion application_buildid: 20160506163539 18:38:01 INFO - mozversion application_changeset: 738ec20418cc728910419aa60f98ae62b429facf 18:38:01 INFO - mozversion application_display_name: Nightly 18:38:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:38:01 INFO - mozversion application_name: Firefox 18:38:01 INFO - mozversion application_remotingname: firefox 18:38:01 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:38:01 INFO - mozversion application_vendor: Mozilla 18:38:01 INFO - mozversion application_version: 49.0a1 18:38:01 INFO - mozversion platform_buildid: 20160506163539 18:38:01 INFO - mozversion platform_changeset: 738ec20418cc728910419aa60f98ae62b429facf 18:38:01 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:38:01 INFO - mozversion platform_version: 49.0a1 18:38:01 INFO - using testdate: 1462585081 18:38:01 INFO - actual date: 1462585081 18:38:01 INFO - starting webserver on 'localhost:49278' 18:38:01 INFO - SUITE-START | Running 2 tests 18:38:01 INFO - TEST-START | dromaeo_css 18:38:01 INFO - operating with platform_type : mac_ 18:38:01 INFO - Initialising browser for dromaeo_css test... 18:38:01 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmp8Eqbmo/profile http://localhost:49278/getInfo.html) 18:38:04 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 18:38:04 INFO - PROCESS | 1654 | colorDepth:24 18:38:04 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 18:38:04 INFO - PROCESS | 1654 | __metrics 18:38:04 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 18:38:05 INFO - TEST-INFO | 1654: exit 0 18:38:05 INFO - Browser initialized. 18:38:05 INFO - Running cycle 1/1 for dromaeo_css test... 18:38:05 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmp8Eqbmo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:38:17 INFO - PROCESS | 1657 | RSS: Main: 250134528 18:38:17 INFO - PROCESS | 1657 | 18:39:07 INFO - PROCESS | 1657 | 1462585147093 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] 18:41:18 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 18:41:19 INFO - PROCESS | 1657 | RSS: Main: 262475776 18:41:19 INFO - PROCESS | 1657 | 18:44:21 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 18:44:22 INFO - PROCESS | 1657 | RSS: Main: 273760256 18:44:22 INFO - PROCESS | 1657 | 18:47:24 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 18:47:24 INFO - PROCESS | 1657 | RSS: Main: 275628032 18:47:24 INFO - PROCESS | 1657 | 18:50:26 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 18:50:27 INFO - PROCESS | 1657 | RSS: Main: 276987904 18:50:27 INFO - PROCESS | 1657 | 18:53:29 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 18:53:30 INFO - PROCESS | 1657 | RSS: Main: 280416256 18:53:30 INFO - PROCESS | 1657 | 18:56:33 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 18:56:33 INFO - PROCESS | 1657 | RSS: Main: 281034752 18:56:33 INFO - PROCESS | 1657 | 18:56:33 INFO - PROCESS | 1657 | __start_tp_report 18:56:33 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 18:56:33 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 18:56:33 INFO - PROCESS | 1657 | |i|pagename|runs| 18:56:33 INFO - PROCESS | 1657 | |0;dojo.html;3048.4055609156194;3141.356022015487;3482.4428091505365;3518.9472157917626;3536.5048893154963;5764.6252993555445;5826.300843898733;5839.7664093436215;5850;5853.5902486825935;5757.568182386327;5857.326407463143;5905.763769449228;5912.113183022549;5949.732262048207;5235.586391674236;5266.605004624649;5273.235380869791;5386.73066346683;5416.241726158341;5413.052715774744;5431.972840135793;5434.8913021739545;5482.0406428874985;5496.615236933396;5256.448072952342;5307.124324486455;5344.492273234056;5360.437154098802;5397.62216644835;5337.439568845254;5351.973240133813;5357.571394288448;5363.088274993261;5372.785088596452;5195.870103247449;5314.282571852789;5316.255724198615;5346.572274218053;5405.7567409466665;4505.941103840628;5205.037068142406;5464.453554644543;5612.747426365823;5736.627119237278;6230.657313847737;6284.591501552431;6284.874302513924;6292.905606415861;6307.148534947769;6193.81418557444;6204.348543402923;6287.49700023997;6295.150154729099;6316.242050953915;5943.673097979609;5999.220101386792;6001.159837622737;6034.517238620943;6079.544034197453;6322.332719337864;6381.298057213703;6400.615963042233;6412.070249813844;6431.067495213261;6127.662978536134;6173.722182501709;6179.227596550431;6261.467775239155;6267.874642507216;6266.342034086447;6281.937180628227;6304.621722696653;6306.274778400542;6327.905081423783;4525.932111018337;5209.921851172236;6136.3965394158;6264.404881536338;6273.686315684289;6159.23009623797;6176.197094377702;6263.906041409383;6275.3724627537795;6278.277998030285;4601.757525468105;4812.807487700453;4981.252812078144;5032.647714659939;5084.41529224144;4426.601605855489;4504.527024662429;4521.0279789845345;4522.570355816258;4526.162659907862;5876.529877609781;6045.637261764308;6093.75624974996;6105.32841387447;6111.297200821962;5771.365149833515;5916.889134141677;6277.451414416266;6337.746490140343;6347.904781428282;4707.6233901287815;6036.0644100164545;6102.72537735803;6108.450239478469;6130.632162070201;5412.56699464042;5481.123020316731;5498.752556134984;5522.447755224526;5538.86152846174;5283.735813209401;6030.7889223876955;6523.902141467882;6568.967155164193;6569.671516424255;6234.347897807175;6268.43584077417;6279.654618995816;6286.4342209201395;6291.1192433058495;6288.717007734572;6400.135981642418;6449;6479.611223326616;6528.804135875931;6228.688565571612;6245.219347581552;6258.937410625836;6263.029230469285;6281.905771413524;6204.410580994799;6299.275583307978;6310.842228944313;6324.083007963727;6330.9553923603935;5923.555733319932;6159.29168145669;6179.382061793965;6179.876402472015;6210.4100110489435;6196.969015154896;6200.81397558074;6247.5001999840815;6256.530760192913;6263.718132684131;5987.401259873977;6074.271087469532;6161.537884658579;6173.722182501709;6183.227096612924;6108.342071131425;6147.784827531015;6154.169187159855;6171.876562468815;6218.844528886814;6127.662978536223;6152.538559608137;6157.753689852356;6167.753289868535;6170.7223174958135 18:56:33 INFO - PROCESS | 1657 | |1;ext.html;476.4663576793993;537.938137114232;551.9558435325174;577.6303165973776;586.3696526234299;37743.05642358939;41580;41613.375799363035;41716.79141604295;41802.581974180284;42448.36327455083;42981.35527967083;42998;43064;43224.567754322445;9756.36583622064;9928.6524971626;9945.701628951143;9960.60157593699;10018.699439016842;9771.267154963407;9846.359986600848;9855.852162217572;9886.209103271756;9971.900280997206;9872.308938374317;9888.752781180492;9918.851217231748;9969.152622027137;10035.247356448259;9817.214622830192;9856.457894815781;9857.457839818808;9868.90131098687;9884.209263258957;9630;9736;9750.658726944523;9753.268504862093;9753.658621948269;39597.80201098976;39830.60169398327;39837.601623983675;39868.20263594741;39919.401208981886;2768;3053.129857990484;3112.7042930921525;3164.430402527544;3414.0270023043313;25462.490750184894;25808.354791130183;25870;26308;26381;2053.363457328233;2523.835950663201;2596.9482359644376;2730.9726902730918;2794.7563334316237;360515;370237;413613;424525;425527.87236063933;35374.823125884206;36316.636833631856;65093;66207.66896165488;66222;30344.84827575848;32134.839325803223;36353.63646363556;37168.07079822972;37376.813115934245;15024.173670448012;15118.866085043666;15353.232338383035;15542.844571554366;15572.766408503881;12891.419886105148;12939.02957278189;12939.964802815752;13002.479900804052;13041;8309.750707478785;8334.374921880883;8363.58182090893;8386.0356059054;8413.663453461793;3452.085197422685;3454.6199918008997;3456.0991454699815;3467.965320346764;3498.2129020970588;818.9353840007966;952.3619175152664;975.1370037516849;980.1178938954997;999.3554157568328;27235.591466128022;31154.532682009787;31970.680293197234;32442;32691.50962735561;945.0407836046419;1022.9386236825815;1036.212478516318;1070.571771291474;1105.5467258424007;12395.62813115608;15553;15652.92173539125;15706.842931570767;15926.203689815462;611833;614149;624532;626419;626775;61252;61422.69288653618;61715.765684686055;61901.380986189564;62006;1427.5289154578963;1441.4594527052354;1466.3987765016504;1468.8531146885439;1472.138798802691;29443.705562944953;29555.261118472212;29665.851670741507;30062.398752025274;30313.545296821005;53221;53611;53858.46141538535;54186.45813541814;54341.18488222601;605330;607393.9630301998;607537;609831;611962;27118.72881271162;27362.72637273602;28572.285692857844;28589.857050714614;28675.856620716764;46291.3056304149;46541.30188047114;46918.76540617275;47108.7644561775;47669.28496072492;38334.61665383422;38668.80665596654;38977.6102238974;39343.606563933994;39857.80071099626;37907.62092379041;37945.81027094847;37964;38507;38892.80553597214;32105;35787.28425431529;37264.06839828918;37571.62428375681;38627;32703.18242043968;33255.667443325256;33729;33990.830045849616;34006.48990265099;22771.88614056919;24736.381590460383;25260.989560417376;25364.873175634002;25879.61180582255 18:56:33 INFO - PROCESS | 1657 | |2;jquery.html;385.9729818912675;391.29566779796374;393.7440663568681;396.2807504379549;414.61440860000187;14539.854601453982;19603.313884014064;20533.691994620054;20868.07846901433;20917.686234706474;10764.623238186665;11262.493187806569;11269.718257043578;20750.96245187738;21189.258375956844;4298.376735373369;4969.776360063788;5095.082885080684;5140.588752899759;5157.896842063175;5493.313335833021;5959.88080238395;6069.939300607004;6127.264728232618;6134.478569321613;5355.973220133894;6035.788747393842;6065.8180254592435;6080.422359875804;6092.268927728656;4934.80260789568;5279.472052794709;5280.49835265653;5309.442508536606;5353.437889021655;4310.3534469829465;4674.135284972257;5137.152369858969;5163.561097306733;5193.272941788153;9946;12178.086643501772;12183.634490965285;12559.434825432878;12610.81083783744;1852.7684039495064;1981.455099847539;2052.48687828043;2161.135545781684;2207.061998650583;8867.980182278992;9049.411788233743;9129.234479803106;9256.814863702688;9383.812323753555;1355.5797702712093;1575.1100628145061;1596.5609457399191;1642.5154579399107;1706.1554530507478;94443.52778236133;108968;109276.17533386294;115628.42185789101;115947.2249395438;9307.860382094274;9692.612295508241;10082.74793130163;11358.602448914413;11969.341686207168;15311.770323445156;15321.846781532266;15389;15397.461088861835;15434.536963891102;7433.888491672629;8879.911200888038;8880.8667869982;8935.687250946186;8976.666066822672;7082.5396349237135;8617.784555386166;8624.396292259482;8744.213020828029;8817.867731984026;4319.524852266248;4445.844395446144;4635.235186194308;4784.808607655655;4788.904221915543;1772.9113544322731;1799.7660304160374;1943.9028048597513;1979.8119178678003;1993.9501512462014;892;911.7811725186041;918.7197904639015;969.9224062075017;994.2046362909643;15395.999260048062;19493.317733879245;20163;20440.693389599168;20563.485912852;801.3669201330883;897.9461232326081;944.8393773058459;990.3909095905966;993.6124911284605;10699.572017119384;10848.78302433947;10867.83698244527;10894.18293627981;10903.236773425942;597125;603958;605798;614784;618599.9070004711;112272;128473.3576332131;128613.35693321473;137030;138047;1024.2164743970868;1027.9588816447258;1033.2199189611795;1054.625607909181;1055.9102476289447;17638;18290.26838926428;18315.54211144732;18450.538736531158;18494.16776245099;47592;48492.51507485021;51056.978860423325;52917.7354113227;53165.7341713289;56883.14675279791;63131.684341577995;75359.62320188365;75860.72417827474;76390;6101.908471373022;7496.887546686913;7707.250328334773;7758.92241077582;7778.766637000899;7060.293970602898;10608.734781630521;11272.323660580392;12127.272363658209;12694.87305126937;9288;10605.522751476048;10693.304935179189;10947.671569852917;11080.778384432428;8279.668813247403;10570.735731606772;10742.409167495864;10938.781224375309;10951.288166269167;10959.50682219318;11088.279261847954;11161.162912781412;11258.549658013588;11305.773884522428;7622.390208783173;7644.770656880302;7719.536827790351;7772.339351155215;8341.45780524264;7874.05511338643;8416.621252043795;8916.777080573038;10609.628662996758;11170.888291116984 18:56:33 INFO - PROCESS | 1657 | |3;mootools.html;1624.9593760155994;1653.03297570921;1885.9339923102686;1933.0817861515773;1970.5861769028504;8112.148224436438;8569.91430085699;8650.524221167849;9112.316576256782;9127.863082053766;8738.213560779528;8826.382153249275;8981;9001.234895033913;9015.594298256578;2023.2513969831184;2419.3709635494765;2454.582720937436;2456.4841383309526;2460.5817011108156;2396.101461951768;2411.011485291031;2427.5630386530415;2431.5866302728577;2444.535538247736;2395.8203134764876;2409.927702168929;2416.5770990076753;2424.5514579802707;2436.658867758515;2366.715994080704;2392.940176495593;2403.7836594706473;2411.6503107049557;2414.7464516225896;2355.316958082154;2367.443650742074;2397.8681172535503;2401.4716762312437;2404;7731.574763388011;8880.822383552357;8938.195562399414;8943.016268210491;9020.097990200993;5179.766910489037;5355.223492593591;5365.248865158881;5369.704666243355;5372.059889519319;3369.2419205678525;3409.403354412968;3471.357798807229;3534.964650353489;3546.8758593449106;834.983300333996;978.4178413843752;1000.46475135802;1003.0220534978411;1011.8532812742185;9042.819143617091;11623.186376953616;11721.179517433782;11728.296302221894;11732;4510.233260345749;4515.932261016087;4571.062932098912;4596.5403459654435;4598.356230127785;8760.299176065899;8767.298616110696;8768.605412756442;8819.735407937773;8865.867011994826;5190.766415511311;5274.182501712241;5305.363356397257;5309.920351194735;5346.625736198429;4441.955580444219;4759;4866.975665121652;4906.1414252505665;4917.745974776409;3302.207470207181;3892.8442862285756;3915.4322623219473;3997.0806714455257;4040.373742069984;1232.9445174967148;1251.5306759965013;1252.680566455548;1255.190402190582;1261.4764872577778;691.9965400172966;859.7893516088599;871.960761765722;876.8246350729879;894.3605322194662;5357.678539287655;5496.917546236809;5683.090705487102;5713.771449141987;5912;923.2198792020732;1002.2583288366702;1015.8476228565627;1017.2828156149897;1017.4963393120453;2863.6277283952954;3590.6947909427467;3593.982030089885;3617.7286703497343;3635.236600313911;9146.451212927246;10952.56189752401;10984.176186786022;11004.559817607365;11033.448327583588;10611.257211995086;10912.23614347004;10955.50700218492;11008.614698485515;11138;8239.752807415787;8376.581170941428;8424.915750842536;8524.06235314115;8558.972923249125;5367.838964831061;5545.0018996581;5578.721063946868;5634.549236061187;5657.9151312731165;9928.801423971625;10202.28583999113;10219.948900255451;10240.64157754475;10257.794844102928;10018.749531261778;10320.48397580133;10391.480425978822;10405.9479702601;10459.372437653765;6393.840153996001;6642.070110184674;6796.76211332601;6822.658867056528;6893;8487.023992240971;8552.700655477029;8602.053774084721;8602.78493037679;8629.266512346521;6364.268109167505;7498.062742157231;7559.3196612305155;7746.49647772893;7754.922450775645;7411.8147046324275;7447.962760186165;7498.475106742476;7514.624268786648;7575.7348492804695;6647.434968027772;6716.194056713226;6747.662616869039;6765.8985115224295;6831.28271531482;4537.727736335831;4676.7895444705755;4712.340272361804;4763.428388593253;4885.853424397274;4747.501512341154;4793.328933949319;4799.928001080057;4849.515048495122;4968.354113965162 18:56:33 INFO - PROCESS | 1657 | |4;prototype.html;291.34447493140436;296.3347285344402;296.6173636009547;299.4340696084399;303.1844338728818;4617.492075871652;5249.265102885599;5521.4478552144765;5551.195076713874;5574.498295153436;4517.667288149995;4700.976495117519;4948.529889660485;5400.432954539776;5458.781648734046;3383.5432216650684;3454.6199918008997;3909.921801563967;3982.203559288154;4114.835406583733;3875.670568001709;4043;4075.755454672714;4085.142120154771;4115.855945041924;3053.7404320632772;3426.9485957710654;3741.5697194822537;3824.9043773905737;4063.9187216255805;3356.748243881694;3609.6029436761937;3925.7644541327327;4093.97953010236;4119.279126152926;3387.9661203387896;3487.877924272638;3504.702100321476;3512.8946131616094;3534.5935217450065;4461.977690111561;4734.0353753192385;4908.938245936715;5180.792768289264;5191.2472691459525;3942.448057271984;4685.906281874343;4690.343351930733;4719.9852031813325;4722.858314250544;4998.675086119427;5701.486866182064;5703.916255911364;5751.683657398841;5792.420757924216;3318.1041118898;3653.1415117447377;3756.398976163801;3775.62243775623;3786.9550442760874;5272.525472707436;5447.863803404907;5815.563832712563;6076.817695469143;6117.510599152057;3581.513671826215;3692.132348898007;3826.789526576065;3842.0971071798103;3846.9038274043373;3413.6104726691415;3766.6986641068647;3846.2307538492414;3916.236333914916;3920.098377373163;3962.8018599070506;3965.7422267552515;4400.119976004812;4630.078517415235;4633.953660463352;5473.781048758005;5567.610267281332;5806.6901865936015;5808.883822323614;5909.261342332208;1054.6625079974335;1078.5200585739428;1139.868915074777;1237.6385975426958;1277.8786015328528;740.7999041552719;801.8516574433748;820.7783898347532;835.9759294863865;854.7734850264685;4781.928271075936;4879.829205977774;5002.974985125124;5232.502912223333;5284.207368894697;4716.882077948078;5089.389273287229;5620.4660557247;5978.222831032025;5999.940000599938;5025.924611130836;5354.491323324278;5385.811496597678;5417.864553386197;5432.348118225759;2703.9184326269256;2925.195571217932;2954.9113526594238;3026.4098500792134;3087.6449208340878;5088.745562721923;5209.1610072510575;5444.8094316698725;5850.9414905851245;6041.879162416815;5021.84934451967;5402.595325215394;5727.140928860621;5900.633531186891;5967.015442451947;5048.966485050403;5933.465988061009;5987.413335466105;6024.27708674953;6047.606905551125;5373.361124856956;5453.918191227214;5731.598788084794;5804;5826.76692932278;4833.806647734052;5650.886982260414;5960.552958528215;6020.969895150496;6057.485113765203;5410.513053825174;6000.0999850022845;6284.905726414199;6340.239171299474;6340.841478963062;2942.7792915530995;3455.8444869981413;3609.9458508121875;3655.597884232679;3928.312545304503;5117.44127936032;5495.313085864267;5743.1385292206505;5922.496587790086;5971.58198926071;5055.974720126376;5778.8844223114465;5796.9420305798085;5853.853653658693;5865.53647468073;4415.646748260067;5365.839024829105;5384.919226211531;5398.271233383443;5759.568032397503;5046.4196617388;5626;5637.295338082739;5705.857353566194;5725.7137143141845;4583.72497650142;5195.19474481456;5486.917696234639;5517.2551705519245;5791.884162316645;5653.586603349163;5664.631798933056;5669;5679.375268720355;5706.372299047184 18:56:33 INFO - PROCESS | 1657 | |5;yui.html;654.9836254093648;713.2083387439941;731.5354749733917;735.1509007096805;738.8522295540893;14413.855861441383;14692.338844751985;14727.705445891102;14757.926210368947;14762.261886905651;13727;13735.656608584766;14007.089539179971;14032.92983535081;14098.154110753336;705.5519744961935;888.0586578227068;897.6454300551278;920.4017388697367;921.9904204895629;903;909.8453262945283;910.2672348759246;915.6612053540166;917.9908200918005;949.5110018340561;966.2656381150341;968.6222373274429;969.9757506062334;971.7862070344513;934.4580143516744;937.9906200937971;948.8292107420627;963.0946909904667;964.7105868239535;710.3109983316175;716.258672274199;717.4726575966661;718.8706032914073;718.9209186989427;14147.009709320351;14324.785128223086;14411.135331880016;14499.565013049625;14673.192974386404;12607.432665530074;12705.555305564261;12707.364631768374;12765.489380424771;12778.10553261272;9922.454265015422;9980.800383992351;10004.099631033241;10269;10385.532651030646;3079.6458407283226;3432.7253819694365;3474.0446377246208;3479.1128262293205;3487.075924879909;69509;81405.18594814035;82472.58763706202;83990.58004709998;85969.28061438799;7947.562884041317;9360.765980850534;9394.859077113848;9522.047795220422;9650.806983860284;19649.214031438867;20506.692399614018;20990.580188396452;21674.674879876813;21792.237271695416;7448.925510744932;7541.095068591804;7648.158702542716;7667;7769.922300777033;3957.2085582883537;3997.1605962747567;4020.778857162884;4024.678025757991;4034.293998550301;3356.3622911646708;3409.7613167078066;3426.9657303426648;3434.7252219822853;3438.6761096977343;599.8170557979859;600.069891667913;624.073251221935;632.0235236559527;637.2353176188592;508.9720065396365;555.6027440380147;557.8465921871518;595.2529575382866;608.2032537376051;7619.695212191561;7937.801554961172;8036.075851277178;8047.275745182962;8127.349812015024;566.7761234312424;568.9715514224272;585.2976428286074;592.9021711417568;594.4531031451075;3648.8175591220333;3843.8846834595006;3859.05453163971;3899.220155968761;3931.253061918226;81654;87570;88906.55546722225;89513.55243223872;89684.6547301791;76793;77977.61011194908;78872.60563697145;79131.60434197792;81104.78342824863;71942.64028679927;72717;73103.26896731071;73244;73806;7470.402367810453;7720.806979825549;7723.420743444151;7999.680012799423;8132.83734325322;35811;40880.79559602183;41229.793851030554;41613.58386416097;41956.321747129776;28845.855770721853;29039.709602903702;60075.0988735178;60841.69579152076;61042.69478652578;10642.787144256916;10727;10900;11151.330920144817;11178.776424471627;10642.84035739449;11876.762464750484;11882.76234475323;12104.031677465957;12200.572979945659;9289.953550232205;11376.94311528437;11487.827682584935;11742.510522406536;11777.941110294394;11057.447127643747;11440.48517816717;11563.132765042486;11811.800833516341;12042.096842736655;8695.260902823331;10119.79760404773;11054.78397376304;11604.245724027911;11744.882551174378;8529.848470456656;9353.204977576983;9422.811543769223;9508.714738557854;9639.228861691188;9031.407122385674;9467.905320946702;9774.75563110928;10011.899881001096;10066.770410217934 18:56:33 INFO - PROCESS | 1657 | __end_tp_report 18:56:33 INFO - PROCESS | 1657 | __start_cc_report 18:56:33 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,409 18:56:33 INFO - PROCESS | 1657 | __end_cc_report 18:56:33 INFO - PROCESS | 1657 | __startTimestamp1462586193347__endTimestamp 18:56:33 INFO - PROCESS | 1657 | 18:56:33 INFO - PROCESS | 1657 | ------- Summary: start ------- 18:56:33 INFO - PROCESS | 1657 | Number of tests: 6 18:56:33 INFO - PROCESS | 1657 | 18:56:33 INFO - PROCESS | 1657 | [#0] dojo.html Cycles:165 Average:5806.30 Median:6108.45 stddev:671.82 (11.0%) stddev-sans-first:638.09 18:56:33 INFO - PROCESS | 1657 | Values: 3048.4 3141.4 3482.4 3518.9 3536.5 5764.6 5826.3 5839.8 5850.0 5853.6 5757.6 5857.3 5905.8 5912.1 5949.7 5235.6 5266.6 5273.2 5386.7 5416.2 5413.1 5432.0 5434.9 5482.0 5496.6 5256.4 5307.1 5344.5 5360.4 5397.6 5337.4 5352.0 5357.6 5363.1 5372.8 5195.9 5314.3 5316.3 5346.6 5405.8 4505.9 5205.0 5464.5 5612.7 5736.6 6230.7 6284.6 6284.9 6292.9 6307.1 6193.8 6204.3 6287.5 6295.2 6316.2 5943.7 5999.2 6001.2 6034.5 6079.5 6322.3 6381.3 6400.6 6412.1 6431.1 6127.7 6173.7 6179.2 6261.5 6267.9 6266.3 6281.9 6304.6 6306.3 6327.9 4525.9 5209.9 6136.4 6264.4 6273.7 6159.2 6176.2 6263.9 6275.4 6278.3 4601.8 4812.8 4981.3 5032.6 5084.4 4426.6 4504.5 4521.0 4522.6 4526.2 5876.5 6045.6 6093.8 6105.3 6111.3 5771.4 5916.9 6277.5 6337.7 6347.9 4707.6 6036.1 6102.7 6108.5 6130.6 5412.6 5481.1 5498.8 5522.4 5538.9 5283.7 6030.8 6523.9 6569.0 6569.7 6234.3 6268.4 6279.7 6286.4 6291.1 6288.7 6400.1 6449.0 6479.6 6528.8 6228.7 6245.2 6258.9 6263.0 6281.9 6204.4 6299.3 6310.8 6324.1 6331.0 5923.6 6159.3 6179.4 6179.9 6210.4 6197.0 6200.8 6247.5 6256.5 6263.7 5987.4 6074.3 6161.5 6173.7 6183.2 6108.3 6147.8 6154.2 6171.9 6218.8 6127.7 6152.5 6157.8 6167.8 6170.7 18:56:33 INFO - PROCESS | 1657 | 18:56:33 INFO - PROCESS | 1657 | [#1] ext.html Cycles:180 Average:66764.65 Median:38647.90 stddev:147971.88 (382.9%) stddev-sans-first:148303.28 18:56:33 INFO - PROCESS | 1657 | Values: 476.5 537.9 552.0 577.6 586.4 37743.1 41580.0 41613.4 41716.8 41802.6 42448.4 42981.4 42998.0 43064.0 43224.6 9756.4 9928.7 9945.7 9960.6 10018.7 9771.3 9846.4 9855.9 9886.2 9971.9 9872.3 9888.8 9918.9 9969.2 10035.2 9817.2 9856.5 9857.5 9868.9 9884.2 9630.0 9736.0 9750.7 9753.3 9753.7 39597.8 39830.6 39837.6 39868.2 39919.4 2768.0 3053.1 3112.7 3164.4 3414.0 25462.5 25808.4 25870.0 26308.0 26381.0 2053.4 2523.8 2596.9 2731.0 2794.8 360515.0 370237.0 413613.0 424525.0 425527.9 35374.8 36316.6 65093.0 66207.7 66222.0 30344.8 32134.8 36353.6 37168.1 37376.8 15024.2 15118.9 15353.2 15542.8 15572.8 12891.4 12939.0 12940.0 13002.5 13041.0 8309.8 8334.4 8363.6 8386.0 8413.7 3452.1 3454.6 3456.1 3468.0 3498.2 818.9 952.4 975.1 980.1 999.4 27235.6 31154.5 31970.7 32442.0 32691.5 945.0 1022.9 1036.2 1070.6 1105.5 12395.6 15553.0 15652.9 15706.8 15926.2 611833.0 614149.0 624532.0 626419.0 626775.0 61252.0 61422.7 61715.8 61901.4 62006.0 1427.5 1441.5 1466.4 1468.9 1472.1 29443.7 29555.3 29665.9 30062.4 30313.5 53221.0 53611.0 53858.5 54186.5 54341.2 605330.0 607394.0 607537.0 609831.0 611962.0 27118.7 27362.7 28572.3 28589.9 28675.9 46291.3 46541.3 46918.8 47108.8 47669.3 38334.6 38668.8 38977.6 39343.6 39857.8 37907.6 37945.8 37964.0 38507.0 38892.8 32105.0 35787.3 37264.1 37571.6 38627.0 32703.2 33255.7 33729.0 33990.8 34006.5 22771.9 24736.4 25261.0 25364.9 25879.6 18:56:33 INFO - PROCESS | 1657 | 18:56:33 INFO - PROCESS | 1657 | [#2] jquery.html Cycles:180 Average:33893.14 Median:4304.37 stddev:101321.85 (2353.9%) stddev-sans-first:101574.84 18:56:33 INFO - PROCESS | 1657 | Values: 386.0 391.3 393.7 396.3 414.6 14539.9 19603.3 20533.7 20868.1 20917.7 10764.6 11262.5 11269.7 20751.0 21189.3 4298.4 4969.8 5095.1 5140.6 5157.9 5493.3 5959.9 6069.9 6127.3 6134.5 5356.0 6035.8 6065.8 6080.4 6092.3 4934.8 5279.5 5280.5 5309.4 5353.4 4310.4 4674.1 5137.2 5163.6 5193.3 9946.0 12178.1 12183.6 12559.4 12610.8 1852.8 1981.5 2052.5 2161.1 2207.1 8868.0 9049.4 9129.2 9256.8 9383.8 1355.6 1575.1 1596.6 1642.5 1706.2 94443.5 108968.0 109276.2 115628.4 115947.2 9307.9 9692.6 10082.7 11358.6 11969.3 15311.8 15321.8 15389.0 15397.5 15434.5 7433.9 8879.9 8880.9 8935.7 8976.7 7082.5 8617.8 8624.4 8744.2 8817.9 4319.5 4445.8 4635.2 4784.8 4788.9 1772.9 1799.8 1943.9 1979.8 1994.0 892.0 911.8 918.7 969.9 994.2 15396.0 19493.3 20163.0 20440.7 20563.5 801.4 897.9 944.8 990.4 993.6 10699.6 10848.8 10867.8 10894.2 10903.2 597125.0 603958.0 605798.0 614784.0 618599.9 112272.0 128473.4 128613.4 137030.0 138047.0 1024.2 1028.0 1033.2 1054.6 1055.9 17638.0 18290.3 18315.5 18450.5 18494.2 47592.0 48492.5 51057.0 52917.7 53165.7 56883.1 63131.7 75359.6 75860.7 76390.0 6101.9 7496.9 7707.3 7758.9 7778.8 7060.3 10608.7 11272.3 12127.3 12694.9 9288.0 10605.5 10693.3 10947.7 11080.8 8279.7 10570.7 10742.4 10938.8 10951.3 10959.5 11088.3 11161.2 11258.5 11305.8 7622.4 7644.8 7719.5 7772.3 8341.5 7874.1 8416.6 8916.8 10609.6 11170.9 18:56:33 INFO - PROCESS | 1657 | 18:56:33 INFO - PROCESS | 1657 | [#3] mootools.html Cycles:180 Average:5637.55 Median:4778.38 stddev:3182.69 (66.6%) stddev-sans-first:3177.34 18:56:33 INFO - PROCESS | 1657 | Values: 1625.0 1653.0 1885.9 1933.1 1970.6 8112.1 8569.9 8650.5 9112.3 9127.9 8738.2 8826.4 8981.0 9001.2 9015.6 2023.3 2419.4 2454.6 2456.5 2460.6 2396.1 2411.0 2427.6 2431.6 2444.5 2395.8 2409.9 2416.6 2424.6 2436.7 2366.7 2392.9 2403.8 2411.7 2414.7 2355.3 2367.4 2397.9 2401.5 2404.0 7731.6 8880.8 8938.2 8943.0 9020.1 5179.8 5355.2 5365.2 5369.7 5372.1 3369.2 3409.4 3471.4 3535.0 3546.9 835.0 978.4 1000.5 1003.0 1011.9 9042.8 11623.2 11721.2 11728.3 11732.0 4510.2 4515.9 4571.1 4596.5 4598.4 8760.3 8767.3 8768.6 8819.7 8865.9 5190.8 5274.2 5305.4 5309.9 5346.6 4442.0 4759.0 4867.0 4906.1 4917.7 3302.2 3892.8 3915.4 3997.1 4040.4 1232.9 1251.5 1252.7 1255.2 1261.5 692.0 859.8 872.0 876.8 894.4 5357.7 5496.9 5683.1 5713.8 5912.0 923.2 1002.3 1015.8 1017.3 1017.5 2863.6 3590.7 3594.0 3617.7 3635.2 9146.5 10952.6 10984.2 11004.6 11033.4 10611.3 10912.2 10955.5 11008.6 11138.0 8239.8 8376.6 8424.9 8524.1 8559.0 5367.8 5545.0 5578.7 5634.5 5657.9 9928.8 10202.3 10219.9 10240.6 10257.8 10018.7 10320.5 10391.5 10405.9 10459.4 6393.8 6642.1 6796.8 6822.7 6893.0 8487.0 8552.7 8602.1 8602.8 8629.3 6364.3 7498.1 7559.3 7746.5 7754.9 7411.8 7448.0 7498.5 7514.6 7575.7 6647.4 6716.2 6747.7 6765.9 6831.3 4537.7 4676.8 4712.3 4763.4 4885.9 4747.5 4793.3 4799.9 4849.5 4968.4 18:56:33 INFO - PROCESS | 1657 | 18:56:33 INFO - PROCESS | 1657 | [#4] prototype.html Cycles:180 Average:4530.38 Median:5089.07 stddev:1461.45 (28.7%) stddev-sans-first:1430.49 18:56:33 INFO - PROCESS | 1657 | Values: 291.3 296.3 296.6 299.4 303.2 4617.5 5249.3 5521.4 5551.2 5574.5 4517.7 4701.0 4948.5 5400.4 5458.8 3383.5 3454.6 3909.9 3982.2 4114.8 3875.7 4043.0 4075.8 4085.1 4115.9 3053.7 3426.9 3741.6 3824.9 4063.9 3356.7 3609.6 3925.8 4094.0 4119.3 3388.0 3487.9 3504.7 3512.9 3534.6 4462.0 4734.0 4908.9 5180.8 5191.2 3942.4 4685.9 4690.3 4720.0 4722.9 4998.7 5701.5 5703.9 5751.7 5792.4 3318.1 3653.1 3756.4 3775.6 3787.0 5272.5 5447.9 5815.6 6076.8 6117.5 3581.5 3692.1 3826.8 3842.1 3846.9 3413.6 3766.7 3846.2 3916.2 3920.1 3962.8 3965.7 4400.1 4630.1 4634.0 5473.8 5567.6 5806.7 5808.9 5909.3 1054.7 1078.5 1139.9 1237.6 1277.9 740.8 801.9 820.8 836.0 854.8 4781.9 4879.8 5003.0 5232.5 5284.2 4716.9 5089.4 5620.5 5978.2 5999.9 5025.9 5354.5 5385.8 5417.9 5432.3 2703.9 2925.2 2954.9 3026.4 3087.6 5088.7 5209.2 5444.8 5850.9 6041.9 5021.8 5402.6 5727.1 5900.6 5967.0 5049.0 5933.5 5987.4 6024.3 6047.6 5373.4 5453.9 5731.6 5804.0 5826.8 4833.8 5650.9 5960.6 6021.0 6057.5 5410.5 6000.1 6284.9 6340.2 6340.8 2942.8 3455.8 3609.9 3655.6 3928.3 5117.4 5495.3 5743.1 5922.5 5971.6 5056.0 5778.9 5796.9 5853.9 5865.5 4415.6 5365.8 5384.9 5398.3 5759.6 5046.4 5626.0 5637.3 5705.9 5725.7 4583.7 5195.2 5486.9 5517.3 5791.9 5653.6 5664.6 5669.0 5679.4 5706.4 18:56:33 INFO - PROCESS | 1657 | 18:56:33 INFO - PROCESS | 1657 | [#5] yui.html Cycles:180 Average:17332.37 Median:30337.58 stddev:24532.15 (80.9%) stddev-sans-first:24569.00 18:56:33 INFO - PROCESS | 1657 | Values: 655.0 713.2 731.5 735.2 738.9 14413.9 14692.3 14727.7 14757.9 14762.3 13727.0 13735.7 14007.1 14032.9 14098.2 705.6 888.1 897.6 920.4 922.0 903.0 909.8 910.3 915.7 918.0 949.5 966.3 968.6 970.0 971.8 934.5 938.0 948.8 963.1 964.7 710.3 716.3 717.5 718.9 718.9 14147.0 14324.8 14411.1 14499.6 14673.2 12607.4 12705.6 12707.4 12765.5 12778.1 9922.5 9980.8 10004.1 10269.0 10385.5 3079.6 3432.7 3474.0 3479.1 3487.1 69509.0 81405.2 82472.6 83990.6 85969.3 7947.6 9360.8 9394.9 9522.0 9650.8 19649.2 20506.7 20990.6 21674.7 21792.2 7448.9 7541.1 7648.2 7667.0 7769.9 3957.2 3997.2 4020.8 4024.7 4034.3 3356.4 3409.8 3427.0 3434.7 3438.7 599.8 600.1 624.1 632.0 637.2 509.0 555.6 557.8 595.3 608.2 7619.7 7937.8 8036.1 8047.3 8127.3 566.8 569.0 585.3 592.9 594.5 3648.8 3843.9 3859.1 3899.2 3931.3 81654.0 87570.0 88906.6 89513.6 89684.7 76793.0 77977.6 78872.6 79131.6 81104.8 71942.6 72717.0 73103.3 73244.0 73806.0 7470.4 7720.8 7723.4 7999.7 8132.8 35811.0 40880.8 41229.8 41613.6 41956.3 28845.9 29039.7 60075.1 60841.7 61042.7 10642.8 10727.0 10900.0 11151.3 11178.8 10642.8 11876.8 11882.8 12104.0 12200.6 9290.0 11376.9 11487.8 11742.5 11777.9 11057.4 11440.5 11563.1 11811.8 12042.1 8695.3 10119.8 11054.8 11604.2 11744.9 8529.8 9353.2 9422.8 9508.7 9639.2 9031.4 9467.9 9774.8 10011.9 10066.8 18:56:33 INFO - PROCESS | 1657 | -------- Summary: end -------- 18:56:33 INFO - PROCESS | 1657 | 18:56:33 INFO - TEST-INFO | 1657: exit 0 18:56:33 INFO - TEST-OK | dromaeo_css | took 1112234ms 18:56:33 INFO - TEST-START | kraken 18:56:33 INFO - operating with platform_type : mac_ 18:56:33 INFO - Initialising browser for kraken test... 18:56:33 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmpY6aakS/profile http://localhost:49278/getInfo.html) 18:56:37 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 18:56:37 INFO - PROCESS | 1668 | colorDepth:24 18:56:37 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 18:56:37 INFO - PROCESS | 1668 | __metrics 18:56:37 ERROR - PROCESS | 1668 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 18:56:37 INFO - TEST-INFO | 1668: exit 0 18:56:37 INFO - Browser initialized. 18:56:37 INFO - Running cycle 1/1 for kraken test... 18:56:37 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmpY6aakS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:56:49 INFO - PROCESS | 1671 | RSS: Main: 250183680 18:56:49 INFO - PROCESS | 1671 | 18:56:52 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 18:56:53 INFO - PROCESS | 1671 | RSS: Main: 262074368 18:56:53 INFO - PROCESS | 1671 | 18:56:55 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 18:56:56 INFO - PROCESS | 1671 | RSS: Main: 267345920 18:56:56 INFO - PROCESS | 1671 | 18:56:59 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 18:57:00 INFO - PROCESS | 1671 | RSS: Main: 267415552 18:57:00 INFO - PROCESS | 1671 | 18:57:02 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 18:57:03 INFO - PROCESS | 1671 | RSS: Main: 267567104 18:57:03 INFO - PROCESS | 1671 | 18:57:05 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:57:05 INFO - PROCESS | 1671 | RSS: Main: 268058624 18:57:05 INFO - PROCESS | 1671 | 18:57:08 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 18:57:09 INFO - PROCESS | 1671 | RSS: Main: 269000704 18:57:09 INFO - PROCESS | 1671 | 18:57:12 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 18:57:13 INFO - PROCESS | 1671 | RSS: Main: 269017088 18:57:13 INFO - PROCESS | 1671 | 18:57:16 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 18:57:16 INFO - PROCESS | 1671 | RSS: Main: 269090816 18:57:16 INFO - PROCESS | 1671 | 18:57:18 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:57:19 INFO - PROCESS | 1671 | RSS: Main: 269045760 18:57:19 INFO - PROCESS | 1671 | 18:57:21 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:57:21 INFO - PROCESS | 1671 | RSS: Main: 268378112 18:57:21 INFO - PROCESS | 1671 | 18:57:24 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:57:24 INFO - PROCESS | 1671 | RSS: Main: 268550144 18:57:24 INFO - PROCESS | 1671 | 18:57:27 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:57:28 INFO - PROCESS | 1671 | RSS: Main: 268578816 18:57:28 INFO - PROCESS | 1671 | 18:57:31 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:57:31 INFO - PROCESS | 1671 | RSS: Main: 268447744 18:57:31 INFO - PROCESS | 1671 | 18:57:33 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 18:57:33 INFO - PROCESS | 1671 | RSS: Main: 270229504 18:57:33 INFO - PROCESS | 1671 | 18:57:33 INFO - PROCESS | 1671 | __start_tp_report 18:57:33 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 18:57:33 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 18:57:33 INFO - PROCESS | 1671 | |i|pagename|runs| 18:57:33 INFO - PROCESS | 1671 | |0;ai-astar;113;115;106;115;116;114;107;115;122;162 18:57:33 INFO - PROCESS | 1671 | |1;audio-beat-detection;152;122;140;165;120;143;139;125;163;123 18:57:33 INFO - PROCESS | 1671 | |2;audio-dft;194;197;198;208;262;224;194;196;197;197 18:57:33 INFO - PROCESS | 1671 | |3;audio-fft;71;81;67;111;60;59;59;63;129;63 18:57:33 INFO - PROCESS | 1671 | |4;audio-oscillator;80;86;160;114;111;82;128;82;81;124 18:57:33 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;136;129;89;91;133;119;90;86;88;88 18:57:33 INFO - PROCESS | 1671 | |6;imaging-darkroom;92;111;109;150;145;96;127;105;152;105 18:57:33 INFO - PROCESS | 1671 | |7;imaging-desaturate;98;136;139;90;90;91;89;91;91;91 18:57:33 INFO - PROCESS | 1671 | |8;json-parse-financial;63;64;64;51;82;83;76;104;98;92 18:57:33 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;67;74;94;103;100;100;100;101;69;70 18:57:33 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;88;87;86;85;89;86;86;88;89;87 18:57:33 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;142;149;158;142;138;154;154;145;177;161 18:57:33 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;225;233;195;168;170;170;183;169;168;173 18:57:33 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;65;69;65;66;67;68;69;69;66;66 18:57:33 INFO - PROCESS | 1671 | __end_tp_report 18:57:33 INFO - PROCESS | 1671 | __start_cc_report 18:57:33 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1061 18:57:33 INFO - PROCESS | 1671 | __end_cc_report 18:57:33 INFO - PROCESS | 1671 | __startTimestamp1462586253831__endTimestamp 18:57:33 INFO - PROCESS | 1671 | 18:57:33 INFO - PROCESS | 1671 | ------- Summary: start ------- 18:57:33 INFO - PROCESS | 1671 | Number of tests: 14 18:57:33 INFO - PROCESS | 1671 | 18:57:33 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:118.50 Median:115.00 stddev:15.94 (13.9%) stddev-sans-first:16.78 18:57:33 INFO - PROCESS | 1671 | Values: 113.0 115.0 106.0 115.0 116.0 114.0 107.0 115.0 122.0 162.0 18:57:33 INFO - PROCESS | 1671 | 18:57:33 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:139.20 Median:141.50 stddev:16.80 (11.9%) stddev-sans-first:17.17 18:57:33 INFO - PROCESS | 1671 | Values: 152.0 122.0 140.0 165.0 120.0 143.0 139.0 125.0 163.0 123.0 18:57:33 INFO - PROCESS | 1671 | 18:57:33 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:206.70 Median:197.50 stddev:21.48 (10.9%) stddev-sans-first:22.29 18:57:33 INFO - PROCESS | 1671 | Values: 194.0 197.0 198.0 208.0 262.0 224.0 194.0 196.0 197.0 197.0 18:57:33 INFO - PROCESS | 1671 | 18:57:33 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:76.30 Median:63.00 stddev:24.35 (38.7%) stddev-sans-first:25.75 18:57:33 INFO - PROCESS | 1671 | Values: 71.0 81.0 67.0 111.0 60.0 59.0 59.0 63.0 129.0 63.0 18:57:33 INFO - PROCESS | 1671 | 18:57:33 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:104.80 Median:80.50 stddev:27.19 (33.8%) stddev-sans-first:27.31 18:57:33 INFO - PROCESS | 1671 | Values: 80.0 86.0 160.0 114.0 111.0 82.0 128.0 82.0 81.0 124.0 18:57:33 INFO - PROCESS | 1671 | 18:57:33 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:104.90 Median:88.00 stddev:21.43 (24.4%) stddev-sans-first:19.55 18:57:33 INFO - PROCESS | 1671 | Values: 136.0 129.0 89.0 91.0 133.0 119.0 90.0 86.0 88.0 88.0 18:57:33 INFO - PROCESS | 1671 | 18:57:33 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:119.20 Median:147.50 stddev:22.62 (15.3%) stddev-sans-first:21.74 18:57:33 INFO - PROCESS | 1671 | Values: 92.0 111.0 109.0 150.0 145.0 96.0 127.0 105.0 152.0 105.0 18:57:33 INFO - PROCESS | 1671 | 18:57:33 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:100.60 Median:91.00 stddev:19.61 (21.6%) stddev-sans-first:20.78 18:57:33 INFO - PROCESS | 1671 | Values: 98.0 136.0 139.0 90.0 90.0 91.0 89.0 91.0 91.0 91.0 18:57:33 INFO - PROCESS | 1671 | 18:57:33 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:77.70 Median:79.00 stddev:17.20 (21.8%) stddev-sans-first:17.40 18:57:33 INFO - PROCESS | 1671 | Values: 63.0 64.0 64.0 51.0 82.0 83.0 76.0 104.0 98.0 92.0 18:57:33 INFO - PROCESS | 1671 | 18:57:33 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:87.80 Median:68.00 stddev:15.58 (22.9%) stddev-sans-first:14.59 18:57:33 INFO - PROCESS | 1671 | Values: 67.0 74.0 94.0 103.0 100.0 100.0 100.0 101.0 69.0 70.0 18:57:33 INFO - PROCESS | 1671 | 18:57:33 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:87.10 Median:87.50 stddev:1.37 (1.6%) stddev-sans-first:1.41 18:57:33 INFO - PROCESS | 1671 | Values: 88.0 87.0 86.0 85.0 89.0 86.0 86.0 88.0 89.0 87.0 18:57:33 INFO - PROCESS | 1671 | 18:57:33 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:152.00 Median:154.00 stddev:11.57 (7.5%) stddev-sans-first:11.69 18:57:33 INFO - PROCESS | 1671 | Values: 142.0 149.0 158.0 142.0 138.0 154.0 154.0 145.0 177.0 161.0 18:57:33 INFO - PROCESS | 1671 | 18:57:33 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.40 Median:178.00 stddev:24.57 (13.8%) stddev-sans-first:21.48 18:57:33 INFO - PROCESS | 1671 | Values: 225.0 233.0 195.0 168.0 170.0 170.0 183.0 169.0 168.0 173.0 18:57:33 INFO - PROCESS | 1671 | 18:57:33 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.50 stddev:1.63 (2.4%) stddev-sans-first:1.56 18:57:33 INFO - PROCESS | 1671 | Values: 65.0 69.0 65.0 66.0 67.0 68.0 69.0 69.0 66.0 66.0 18:57:33 INFO - PROCESS | 1671 | -------- Summary: end -------- 18:57:33 INFO - PROCESS | 1671 | 18:57:34 INFO - TEST-INFO | 1671: exit 0 18:57:34 INFO - TEST-OK | kraken | took 60693ms 18:57:34 INFO - SUITE-END | took 1172s 18:57:34 INFO - Completed test suite (00:19:33) 18:57:34 INFO - javascript benchmark 18:57:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3048.4055609156194, 3141.356022015487, 3482.4428091505365, 3518.9472157917626, 3536.5048893154963, 5764.6252993555445, 5826.300843898733, 5839.7664093436215, 5850.0, 5853.5902486825935, 5757.568182386327, 5857.326407463143, 5905.763769449228, 5912.113183022549, 5949.732262048207, 5235.586391674236, 5266.605004624649, 5273.235380869791, 5386.73066346683, 5416.241726158341, 5413.052715774744, 5431.972840135793, 5434.8913021739545, 5482.0406428874985, 5496.615236933396, 5256.448072952342, 5307.124324486455, 5344.492273234056, 5360.437154098802, 5397.62216644835, 5337.439568845254, 5351.973240133813, 5357.571394288448, 5363.088274993261, 5372.785088596452, 5195.870103247449, 5314.282571852789, 5316.255724198615, 5346.572274218053, 5405.7567409466665, 4505.941103840628, 5205.037068142406, 5464.453554644543, 5612.747426365823, 5736.627119237278, 6230.657313847737, 6284.591501552431, 6284.874302513924, 6292.905606415861, 6307.148534947769, 6193.81418557444, 6204.348543402923, 6287.49700023997, 6295.150154729099, 6316.242050953915, 5943.673097979609, 5999.220101386792, 6001.159837622737, 6034.517238620943, 6079.544034197453, 6322.332719337864, 6381.298057213703, 6400.615963042233, 6412.070249813844, 6431.067495213261, 6127.662978536134, 6173.722182501709, 6179.227596550431, 6261.467775239155, 6267.874642507216, 6266.342034086447, 6281.937180628227, 6304.621722696653, 6306.274778400542, 6327.905081423783, 4525.932111018337, 5209.921851172236, 6136.3965394158, 6264.404881536338, 6273.686315684289, 6159.23009623797, 6176.197094377702, 6263.906041409383, 6275.3724627537795, 6278.277998030285, 4601.757525468105, 4812.807487700453, 4981.252812078144, 5032.647714659939, 5084.41529224144, 4426.601605855489, 4504.527024662429, 4521.0279789845345, 4522.570355816258, 4526.162659907862, 5876.529877609781, 6045.637261764308, 6093.75624974996, 6105.32841387447, 6111.297200821962, 5771.365149833515, 5916.889134141677, 6277.451414416266, 6337.746490140343, 6347.904781428282, 4707.6233901287815, 6036.0644100164545, 6102.72537735803, 6108.450239478469, 6130.632162070201, 5412.56699464042, 5481.123020316731, 5498.752556134984, 5522.447755224526, 5538.86152846174, 5283.735813209401, 6030.7889223876955, 6523.902141467882, 6568.967155164193, 6569.671516424255, 6234.347897807175, 6268.43584077417, 6279.654618995816, 6286.4342209201395, 6291.1192433058495, 6288.717007734572, 6400.135981642418, 6449.0, 6479.611223326616, 6528.804135875931, 6228.688565571612, 6245.219347581552, 6258.937410625836, 6263.029230469285, 6281.905771413524, 6204.410580994799, 6299.275583307978, 6310.842228944313, 6324.083007963727, 6330.9553923603935, 5923.555733319932, 6159.29168145669, 6179.382061793965, 6179.876402472015, 6210.4100110489435, 6196.969015154896, 6200.81397558074, 6247.5001999840815, 6256.530760192913, 6263.718132684131, 5987.401259873977, 6074.271087469532, 6161.537884658579, 6173.722182501709, 6183.227096612924, 6108.342071131425, 6147.784827531015, 6154.169187159855, 6171.876562468815, 6218.844528886814, 6127.662978536223, 6152.538559608137, 6157.753689852356, 6167.753289868535, 6170.7223174958135], "value": 5764.145202801892, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [476.4663576793993, 537.938137114232, 551.9558435325174, 577.6303165973776, 586.3696526234299, 37743.05642358939, 41580.0, 41613.375799363035, 41716.79141604295, 41802.581974180284, 42448.36327455083, 42981.35527967083, 42998.0, 43064.0, 43224.567754322445, 9756.36583622064, 9928.6524971626, 9945.701628951143, 9960.60157593699, 10018.699439016842, 9771.267154963407, 9846.359986600848, 9855.852162217572, 9886.209103271756, 9971.900280997206, 9872.308938374317, 9888.752781180492, 9918.851217231748, 9969.152622027137, 10035.247356448259, 9817.214622830192, 9856.457894815781, 9857.457839818808, 9868.90131098687, 9884.209263258957, 9630.0, 9736.0, 9750.658726944523, 9753.268504862093, 9753.658621948269, 39597.80201098976, 39830.60169398327, 39837.601623983675, 39868.20263594741, 39919.401208981886, 2768.0, 3053.129857990484, 3112.7042930921525, 3164.430402527544, 3414.0270023043313, 25462.490750184894, 25808.354791130183, 25870.0, 26308.0, 26381.0, 2053.363457328233, 2523.835950663201, 2596.9482359644376, 2730.9726902730918, 2794.7563334316237, 360515.0, 370237.0, 413613.0, 424525.0, 425527.87236063933, 35374.823125884206, 36316.636833631856, 65093.0, 66207.66896165488, 66222.0, 30344.84827575848, 32134.839325803223, 36353.63646363556, 37168.07079822972, 37376.813115934245, 15024.173670448012, 15118.866085043666, 15353.232338383035, 15542.844571554366, 15572.766408503881, 12891.419886105148, 12939.02957278189, 12939.964802815752, 13002.479900804052, 13041.0, 8309.750707478785, 8334.374921880883, 8363.58182090893, 8386.0356059054, 8413.663453461793, 3452.085197422685, 3454.6199918008997, 3456.0991454699815, 3467.965320346764, 3498.2129020970588, 818.9353840007966, 952.3619175152664, 975.1370037516849, 980.1178938954997, 999.3554157568328, 27235.591466128022, 31154.532682009787, 31970.680293197234, 32442.0, 32691.50962735561, 945.0407836046419, 1022.9386236825815, 1036.212478516318, 1070.571771291474, 1105.5467258424007, 12395.62813115608, 15553.0, 15652.92173539125, 15706.842931570767, 15926.203689815462, 611833.0, 614149.0, 624532.0, 626419.0, 626775.0, 61252.0, 61422.69288653618, 61715.765684686055, 61901.380986189564, 62006.0, 1427.5289154578963, 1441.4594527052354, 1466.3987765016504, 1468.8531146885439, 1472.138798802691, 29443.705562944953, 29555.261118472212, 29665.851670741507, 30062.398752025274, 30313.545296821005, 53221.0, 53611.0, 53858.46141538535, 54186.45813541814, 54341.18488222601, 605330.0, 607393.9630301998, 607537.0, 609831.0, 611962.0, 27118.72881271162, 27362.72637273602, 28572.285692857844, 28589.857050714614, 28675.856620716764, 46291.3056304149, 46541.30188047114, 46918.76540617275, 47108.7644561775, 47669.28496072492, 38334.61665383422, 38668.80665596654, 38977.6102238974, 39343.606563933994, 39857.80071099626, 37907.62092379041, 37945.81027094847, 37964.0, 38507.0, 38892.80553597214, 32105.0, 35787.28425431529, 37264.06839828918, 37571.62428375681, 38627.0, 32703.18242043968, 33255.667443325256, 33729.0, 33990.830045849616, 34006.48990265099, 22771.88614056919, 24736.381590460383, 25260.989560417376, 25364.873175634002, 25879.61180582255], "value": 18556.878881103872, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.9729818912675, 391.29566779796374, 393.7440663568681, 396.2807504379549, 414.61440860000187, 14539.854601453982, 19603.313884014064, 20533.691994620054, 20868.07846901433, 20917.686234706474, 10764.623238186665, 11262.493187806569, 11269.718257043578, 20750.96245187738, 21189.258375956844, 4298.376735373369, 4969.776360063788, 5095.082885080684, 5140.588752899759, 5157.896842063175, 5493.313335833021, 5959.88080238395, 6069.939300607004, 6127.264728232618, 6134.478569321613, 5355.973220133894, 6035.788747393842, 6065.8180254592435, 6080.422359875804, 6092.268927728656, 4934.80260789568, 5279.472052794709, 5280.49835265653, 5309.442508536606, 5353.437889021655, 4310.3534469829465, 4674.135284972257, 5137.152369858969, 5163.561097306733, 5193.272941788153, 9946.0, 12178.086643501772, 12183.634490965285, 12559.434825432878, 12610.81083783744, 1852.7684039495064, 1981.455099847539, 2052.48687828043, 2161.135545781684, 2207.061998650583, 8867.980182278992, 9049.411788233743, 9129.234479803106, 9256.814863702688, 9383.812323753555, 1355.5797702712093, 1575.1100628145061, 1596.5609457399191, 1642.5154579399107, 1706.1554530507478, 94443.52778236133, 108968.0, 109276.17533386294, 115628.42185789101, 115947.2249395438, 9307.860382094274, 9692.612295508241, 10082.74793130163, 11358.602448914413, 11969.341686207168, 15311.770323445156, 15321.846781532266, 15389.0, 15397.461088861835, 15434.536963891102, 7433.888491672629, 8879.911200888038, 8880.8667869982, 8935.687250946186, 8976.666066822672, 7082.5396349237135, 8617.784555386166, 8624.396292259482, 8744.213020828029, 8817.867731984026, 4319.524852266248, 4445.844395446144, 4635.235186194308, 4784.808607655655, 4788.904221915543, 1772.9113544322731, 1799.7660304160374, 1943.9028048597513, 1979.8119178678003, 1993.9501512462014, 892.0, 911.7811725186041, 918.7197904639015, 969.9224062075017, 994.2046362909643, 15395.999260048062, 19493.317733879245, 20163.0, 20440.693389599168, 20563.485912852, 801.3669201330883, 897.9461232326081, 944.8393773058459, 990.3909095905966, 993.6124911284605, 10699.572017119384, 10848.78302433947, 10867.83698244527, 10894.18293627981, 10903.236773425942, 597125.0, 603958.0, 605798.0, 614784.0, 618599.9070004711, 112272.0, 128473.3576332131, 128613.35693321473, 137030.0, 138047.0, 1024.2164743970868, 1027.9588816447258, 1033.2199189611795, 1054.625607909181, 1055.9102476289447, 17638.0, 18290.26838926428, 18315.54211144732, 18450.538736531158, 18494.16776245099, 47592.0, 48492.51507485021, 51056.978860423325, 52917.7354113227, 53165.7341713289, 56883.14675279791, 63131.684341577995, 75359.62320188365, 75860.72417827474, 76390.0, 6101.908471373022, 7496.887546686913, 7707.250328334773, 7758.92241077582, 7778.766637000899, 7060.293970602898, 10608.734781630521, 11272.323660580392, 12127.272363658209, 12694.87305126937, 9288.0, 10605.522751476048, 10693.304935179189, 10947.671569852917, 11080.778384432428, 8279.668813247403, 10570.735731606772, 10742.409167495864, 10938.781224375309, 10951.288166269167, 10959.50682219318, 11088.279261847954, 11161.162912781412, 11258.549658013588, 11305.773884522428, 7622.390208783173, 7644.770656880302, 7719.536827790351, 7772.339351155215, 8341.45780524264, 7874.05511338643, 8416.621252043795, 8916.777080573038, 10609.628662996758, 11170.888291116984], "value": 8869.633227693495, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1624.9593760155994, 1653.03297570921, 1885.9339923102686, 1933.0817861515773, 1970.5861769028504, 8112.148224436438, 8569.91430085699, 8650.524221167849, 9112.316576256782, 9127.863082053766, 8738.213560779528, 8826.382153249275, 8981.0, 9001.234895033913, 9015.594298256578, 2023.2513969831184, 2419.3709635494765, 2454.582720937436, 2456.4841383309526, 2460.5817011108156, 2396.101461951768, 2411.011485291031, 2427.5630386530415, 2431.5866302728577, 2444.535538247736, 2395.8203134764876, 2409.927702168929, 2416.5770990076753, 2424.5514579802707, 2436.658867758515, 2366.715994080704, 2392.940176495593, 2403.7836594706473, 2411.6503107049557, 2414.7464516225896, 2355.316958082154, 2367.443650742074, 2397.8681172535503, 2401.4716762312437, 2404.0, 7731.574763388011, 8880.822383552357, 8938.195562399414, 8943.016268210491, 9020.097990200993, 5179.766910489037, 5355.223492593591, 5365.248865158881, 5369.704666243355, 5372.059889519319, 3369.2419205678525, 3409.403354412968, 3471.357798807229, 3534.964650353489, 3546.8758593449106, 834.983300333996, 978.4178413843752, 1000.46475135802, 1003.0220534978411, 1011.8532812742185, 9042.819143617091, 11623.186376953616, 11721.179517433782, 11728.296302221894, 11732.0, 4510.233260345749, 4515.932261016087, 4571.062932098912, 4596.5403459654435, 4598.356230127785, 8760.299176065899, 8767.298616110696, 8768.605412756442, 8819.735407937773, 8865.867011994826, 5190.766415511311, 5274.182501712241, 5305.363356397257, 5309.920351194735, 5346.625736198429, 4441.955580444219, 4759.0, 4866.975665121652, 4906.1414252505665, 4917.745974776409, 3302.207470207181, 3892.8442862285756, 3915.4322623219473, 3997.0806714455257, 4040.373742069984, 1232.9445174967148, 1251.5306759965013, 1252.680566455548, 1255.190402190582, 1261.4764872577778, 691.9965400172966, 859.7893516088599, 871.960761765722, 876.8246350729879, 894.3605322194662, 5357.678539287655, 5496.917546236809, 5683.090705487102, 5713.771449141987, 5912.0, 923.2198792020732, 1002.2583288366702, 1015.8476228565627, 1017.2828156149897, 1017.4963393120453, 2863.6277283952954, 3590.6947909427467, 3593.982030089885, 3617.7286703497343, 3635.236600313911, 9146.451212927246, 10952.56189752401, 10984.176186786022, 11004.559817607365, 11033.448327583588, 10611.257211995086, 10912.23614347004, 10955.50700218492, 11008.614698485515, 11138.0, 8239.752807415787, 8376.581170941428, 8424.915750842536, 8524.06235314115, 8558.972923249125, 5367.838964831061, 5545.0018996581, 5578.721063946868, 5634.549236061187, 5657.9151312731165, 9928.801423971625, 10202.28583999113, 10219.948900255451, 10240.64157754475, 10257.794844102928, 10018.749531261778, 10320.48397580133, 10391.480425978822, 10405.9479702601, 10459.372437653765, 6393.840153996001, 6642.070110184674, 6796.76211332601, 6822.658867056528, 6893.0, 8487.023992240971, 8552.700655477029, 8602.053774084721, 8602.78493037679, 8629.266512346521, 6364.268109167505, 7498.062742157231, 7559.3196612305155, 7746.49647772893, 7754.922450775645, 7411.8147046324275, 7447.962760186165, 7498.475106742476, 7514.624268786648, 7575.7348492804695, 6647.434968027772, 6716.194056713226, 6747.662616869039, 6765.8985115224295, 6831.28271531482, 4537.727736335831, 4676.7895444705755, 4712.340272361804, 4763.428388593253, 4885.853424397274, 4747.501512341154, 4793.328933949319, 4799.928001080057, 4849.515048495122, 4968.354113965162], "value": 4532.957285375733, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.34447493140436, 296.3347285344402, 296.6173636009547, 299.4340696084399, 303.1844338728818, 4617.492075871652, 5249.265102885599, 5521.4478552144765, 5551.195076713874, 5574.498295153436, 4517.667288149995, 4700.976495117519, 4948.529889660485, 5400.432954539776, 5458.781648734046, 3383.5432216650684, 3454.6199918008997, 3909.921801563967, 3982.203559288154, 4114.835406583733, 3875.670568001709, 4043.0, 4075.755454672714, 4085.142120154771, 4115.855945041924, 3053.7404320632772, 3426.9485957710654, 3741.5697194822537, 3824.9043773905737, 4063.9187216255805, 3356.748243881694, 3609.6029436761937, 3925.7644541327327, 4093.97953010236, 4119.279126152926, 3387.9661203387896, 3487.877924272638, 3504.702100321476, 3512.8946131616094, 3534.5935217450065, 4461.977690111561, 4734.0353753192385, 4908.938245936715, 5180.792768289264, 5191.2472691459525, 3942.448057271984, 4685.906281874343, 4690.343351930733, 4719.9852031813325, 4722.858314250544, 4998.675086119427, 5701.486866182064, 5703.916255911364, 5751.683657398841, 5792.420757924216, 3318.1041118898, 3653.1415117447377, 3756.398976163801, 3775.62243775623, 3786.9550442760874, 5272.525472707436, 5447.863803404907, 5815.563832712563, 6076.817695469143, 6117.510599152057, 3581.513671826215, 3692.132348898007, 3826.789526576065, 3842.0971071798103, 3846.9038274043373, 3413.6104726691415, 3766.6986641068647, 3846.2307538492414, 3916.236333914916, 3920.098377373163, 3962.8018599070506, 3965.7422267552515, 4400.119976004812, 4630.078517415235, 4633.953660463352, 5473.781048758005, 5567.610267281332, 5806.6901865936015, 5808.883822323614, 5909.261342332208, 1054.6625079974335, 1078.5200585739428, 1139.868915074777, 1237.6385975426958, 1277.8786015328528, 740.7999041552719, 801.8516574433748, 820.7783898347532, 835.9759294863865, 854.7734850264685, 4781.928271075936, 4879.829205977774, 5002.974985125124, 5232.502912223333, 5284.207368894697, 4716.882077948078, 5089.389273287229, 5620.4660557247, 5978.222831032025, 5999.940000599938, 5025.924611130836, 5354.491323324278, 5385.811496597678, 5417.864553386197, 5432.348118225759, 2703.9184326269256, 2925.195571217932, 2954.9113526594238, 3026.4098500792134, 3087.6449208340878, 5088.745562721923, 5209.1610072510575, 5444.8094316698725, 5850.9414905851245, 6041.879162416815, 5021.84934451967, 5402.595325215394, 5727.140928860621, 5900.633531186891, 5967.015442451947, 5048.966485050403, 5933.465988061009, 5987.413335466105, 6024.27708674953, 6047.606905551125, 5373.361124856956, 5453.918191227214, 5731.598788084794, 5804.0, 5826.76692932278, 4833.806647734052, 5650.886982260414, 5960.552958528215, 6020.969895150496, 6057.485113765203, 5410.513053825174, 6000.0999850022845, 6284.905726414199, 6340.239171299474, 6340.841478963062, 2942.7792915530995, 3455.8444869981413, 3609.9458508121875, 3655.597884232679, 3928.312545304503, 5117.44127936032, 5495.313085864267, 5743.1385292206505, 5922.496587790086, 5971.58198926071, 5055.974720126376, 5778.8844223114465, 5796.9420305798085, 5853.853653658693, 5865.53647468073, 4415.646748260067, 5365.839024829105, 5384.919226211531, 5398.271233383443, 5759.568032397503, 5046.4196617388, 5626.0, 5637.295338082739, 5705.857353566194, 5725.7137143141845, 4583.72497650142, 5195.19474481456, 5486.917696234639, 5517.2551705519245, 5791.884162316645, 5653.586603349163, 5664.631798933056, 5669.0, 5679.375268720355, 5706.372299047184], "value": 4052.3661181744533, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [654.9836254093648, 713.2083387439941, 731.5354749733917, 735.1509007096805, 738.8522295540893, 14413.855861441383, 14692.338844751985, 14727.705445891102, 14757.926210368947, 14762.261886905651, 13727.0, 13735.656608584766, 14007.089539179971, 14032.92983535081, 14098.154110753336, 705.5519744961935, 888.0586578227068, 897.6454300551278, 920.4017388697367, 921.9904204895629, 903.0, 909.8453262945283, 910.2672348759246, 915.6612053540166, 917.9908200918005, 949.5110018340561, 966.2656381150341, 968.6222373274429, 969.9757506062334, 971.7862070344513, 934.4580143516744, 937.9906200937971, 948.8292107420627, 963.0946909904667, 964.7105868239535, 710.3109983316175, 716.258672274199, 717.4726575966661, 718.8706032914073, 718.9209186989427, 14147.009709320351, 14324.785128223086, 14411.135331880016, 14499.565013049625, 14673.192974386404, 12607.432665530074, 12705.555305564261, 12707.364631768374, 12765.489380424771, 12778.10553261272, 9922.454265015422, 9980.800383992351, 10004.099631033241, 10269.0, 10385.532651030646, 3079.6458407283226, 3432.7253819694365, 3474.0446377246208, 3479.1128262293205, 3487.075924879909, 69509.0, 81405.18594814035, 82472.58763706202, 83990.58004709998, 85969.28061438799, 7947.562884041317, 9360.765980850534, 9394.859077113848, 9522.047795220422, 9650.806983860284, 19649.214031438867, 20506.692399614018, 20990.580188396452, 21674.674879876813, 21792.237271695416, 7448.925510744932, 7541.095068591804, 7648.158702542716, 7667.0, 7769.922300777033, 3957.2085582883537, 3997.1605962747567, 4020.778857162884, 4024.678025757991, 4034.293998550301, 3356.3622911646708, 3409.7613167078066, 3426.9657303426648, 3434.7252219822853, 3438.6761096977343, 599.8170557979859, 600.069891667913, 624.073251221935, 632.0235236559527, 637.2353176188592, 508.9720065396365, 555.6027440380147, 557.8465921871518, 595.2529575382866, 608.2032537376051, 7619.695212191561, 7937.801554961172, 8036.075851277178, 8047.275745182962, 8127.349812015024, 566.7761234312424, 568.9715514224272, 585.2976428286074, 592.9021711417568, 594.4531031451075, 3648.8175591220333, 3843.8846834595006, 3859.05453163971, 3899.220155968761, 3931.253061918226, 81654.0, 87570.0, 88906.55546722225, 89513.55243223872, 89684.6547301791, 76793.0, 77977.61011194908, 78872.60563697145, 79131.60434197792, 81104.78342824863, 71942.64028679927, 72717.0, 73103.26896731071, 73244.0, 73806.0, 7470.402367810453, 7720.806979825549, 7723.420743444151, 7999.680012799423, 8132.83734325322, 35811.0, 40880.79559602183, 41229.793851030554, 41613.58386416097, 41956.321747129776, 28845.855770721853, 29039.709602903702, 60075.0988735178, 60841.69579152076, 61042.69478652578, 10642.787144256916, 10727.0, 10900.0, 11151.330920144817, 11178.776424471627, 10642.84035739449, 11876.762464750484, 11882.76234475323, 12104.031677465957, 12200.572979945659, 9289.953550232205, 11376.94311528437, 11487.827682584935, 11742.510522406536, 11777.941110294394, 11057.447127643747, 11440.48517816717, 11563.132765042486, 11811.800833516341, 12042.096842736655, 8695.260902823331, 10119.79760404773, 11054.78397376304, 11604.245724027911, 11744.882551174378, 8529.848470456656, 9353.204977576983, 9422.811543769223, 9508.714738557854, 9639.228861691188, 9031.407122385674, 9467.905320946702, 9774.75563110928, 10011.899881001096, 10066.770410217934], "value": 6709.616766480231, "unit": "score"}], "name": "dromaeo_css", "value": 6992.986944617742, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 115.0, 106.0, 115.0, 116.0, 114.0, 107.0, 115.0, 122.0, 162.0], "value": 118.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 122.0, 140.0, 165.0, 120.0, 143.0, 139.0, 125.0, 163.0, 123.0], "value": 139.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 197.0, 198.0, 208.0, 262.0, 224.0, 194.0, 196.0, 197.0, 197.0], "value": 206.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 81.0, 67.0, 111.0, 60.0, 59.0, 59.0, 63.0, 129.0, 63.0], "value": 76.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 86.0, 160.0, 114.0, 111.0, 82.0, 128.0, 82.0, 81.0, 124.0], "value": 104.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 129.0, 89.0, 91.0, 133.0, 119.0, 90.0, 86.0, 88.0, 88.0], "value": 104.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 111.0, 109.0, 150.0, 145.0, 96.0, 127.0, 105.0, 152.0, 105.0], "value": 119.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 136.0, 139.0, 90.0, 90.0, 91.0, 89.0, 91.0, 91.0, 91.0], "value": 100.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 64.0, 51.0, 82.0, 83.0, 76.0, 104.0, 98.0, 92.0], "value": 77.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 74.0, 94.0, 103.0, 100.0, 100.0, 100.0, 101.0, 69.0, 70.0], "value": 87.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 86.0, 85.0, 89.0, 86.0, 86.0, 88.0, 89.0, 87.0], "value": 87.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 149.0, 158.0, 142.0, 138.0, 154.0, 154.0, 145.0, 177.0, 161.0], "value": 152.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [225.0, 233.0, 195.0, 168.0, 170.0, 170.0, 183.0, 169.0, 168.0, 173.0], "value": 185.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 69.0, 65.0, 66.0, 67.0, 68.0, 69.0, 69.0, 66.0, 66.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1627.1999999999998, "alertThreshold": 2.0}]} 18:57:35 INFO - Return code: 0 18:57:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:57:35 ERROR - # TBPL SUCCESS # 18:57:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:57:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:57:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:57:35 INFO - 2016-05-06 18:57:35.172 screenresolution[1676:10668] starting screenresolution argv=screenresolution get 18:57:35 INFO - 2016-05-06 18:57:35.174 screenresolution[1676:10668] Display 0: 1600x1200x32@60 18:57:35 INFO - 2016-05-06 18:57:35.180 screenresolution[1677:10671] starting screenresolution argv=screenresolution list 18:57:35 INFO - Available Modes on Display 0 18:57:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:57:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:57:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:57:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:57:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:57:35 INFO - Intel Iris: 18:57:35 INFO - Chipset Model: Intel Iris 18:57:35 INFO - Type: GPU 18:57:35 INFO - Bus: Built-In 18:57:35 INFO - VRAM (Dynamic, Max): 1536 MB 18:57:35 INFO - Vendor: Intel (0x8086) 18:57:35 INFO - Device ID: 0x0a2e 18:57:35 INFO - Revision ID: 0x0009 18:57:35 INFO - Displays: 18:57:35 INFO - Display: 18:57:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:57:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:57:35 INFO - Display Serial Number: 1600x1200 60 18:57:35 INFO - Main Display: Yes 18:57:35 INFO - Mirror: Off 18:57:35 INFO - Online: Yes 18:57:35 INFO - Rotation: Supported 18:57:35 INFO - Return code: 0 18:57:35 INFO - Running post-action listener: _resource_record_post_action 18:57:35 INFO - ##### 18:57:35 INFO - ##### Finished run-tests step (success) 18:57:35 INFO - ##### 18:57:35 INFO - Running post-run listener: _resource_record_post_run 18:57:36 INFO - Total resource usage - Wall time: 1208s; CPU: 54.0%; Read bytes: 37783040; Write bytes: 695860224; Read time: 393; Write time: 2007 18:57:36 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 274432; Write bytes: 398939648; Read time: 7; Write time: 712 18:57:36 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 35964416; Write bytes: 296072704; Read time: 372; Write time: 1293 18:57:36 INFO - Running post-run listener: _upload_blobber_files 18:57:36 INFO - Blob upload gear active. 18:57:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:57:36 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 18:57:36 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:57:36 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:57:36 INFO - (blobuploader) - INFO - Open directory for files ... 18:57:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:57:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:57:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:57:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:57:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:57:38 INFO - (blobuploader) - INFO - Done attempting. 18:57:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:57:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:57:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:57:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:57:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:57:39 INFO - (blobuploader) - INFO - Done attempting. 18:57:39 INFO - (blobuploader) - INFO - Iteration through files over. 18:57:39 INFO - Return code: 0 18:57:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:57:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:57:39 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fbf11404e50ee7d4d3154a990196d5c170588f56603906d843d915cc2faf296cc1d2912edd29cf676f0211b48b2fa8741024fc86492949d44ca57040272d8942", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab067c6880aa27e3fecca3438bbd1629f198742a4f10d4bfb142206b06b03b739e2e9390e32a126240d2f0c889ded5f948bb62f1ead2376b31ca9fd928898b49"} 18:57:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:57:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:57:39 INFO - Contents: 18:57:39 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fbf11404e50ee7d4d3154a990196d5c170588f56603906d843d915cc2faf296cc1d2912edd29cf676f0211b48b2fa8741024fc86492949d44ca57040272d8942", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab067c6880aa27e3fecca3438bbd1629f198742a4f10d4bfb142206b06b03b739e2e9390e32a126240d2f0c889ded5f948bb62f1ead2376b31ca9fd928898b49"} 18:57:39 INFO - Running post-run listener: copy_logs_to_upload_dir 18:57:39 INFO - Copying logs to upload dir... 18:57:39 INFO - mkdir: /builds/slave/test/build/upload/logs 18:57:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.054729 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-06 18:57:39.363859) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 18:57:39.367906) ========= 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.iVcZeIH6G1/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.ZvP3Kwt9lV/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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/Fx-Team/sha512/fbf11404e50ee7d4d3154a990196d5c170588f56603906d843d915cc2faf296cc1d2912edd29cf676f0211b48b2fa8741024fc86492949d44ca57040272d8942", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab067c6880aa27e3fecca3438bbd1629f198742a4f10d4bfb142206b06b03b739e2e9390e32a126240d2f0c889ded5f948bb62f1ead2376b31ca9fd928898b49"} build_url:https://queue.taskcluster.net/v1/task/CoK-sF93RuOSwnGJ7CiRhA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011084 build_url: 'https://queue.taskcluster.net/v1/task/CoK-sF93RuOSwnGJ7CiRhA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fbf11404e50ee7d4d3154a990196d5c170588f56603906d843d915cc2faf296cc1d2912edd29cf676f0211b48b2fa8741024fc86492949d44ca57040272d8942", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab067c6880aa27e3fecca3438bbd1629f198742a4f10d4bfb142206b06b03b739e2e9390e32a126240d2f0c889ded5f948bb62f1ead2376b31ca9fd928898b49"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 18:57:39.395817) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 18:57:39.396177) ========= 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.iVcZeIH6G1/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.ZvP3Kwt9lV/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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.004875 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 18:57:39.445074) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 18:57:39.445393) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 18:57:39.445713) ========= ========= Total master_lag: 0.16 =========