builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0077 starttime: 1461083423.68 results: success (0) buildid: 20160419071334 builduid: 729a2cec09bb4437b3b6a57d523c0486 revision: e2f5cd553b44f73fa85c8427aed560f9a47f0ce9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-19 09:30:23.681044) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-19 09:30:23.681506) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-19 09:30:23.681826) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-19 09:30:23.702701) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 09:30:23.703082) ========= 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.14kr0p1lOk/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.HoVbgyESD3/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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-04-19 09:30:23-- 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% 13.9M=0.001s 2016-04-19 09:30:23 (13.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.140115 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 09:30:23.862490) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 09:30:23.862866) ========= 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.14kr0p1lOk/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.HoVbgyESD3/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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.026369 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 09:30:23.911514) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-19 09:30:23.911869) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev e2f5cd553b44f73fa85c8427aed560f9a47f0ce9 --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 e2f5cd553b44f73fa85c8427aed560f9a47f0ce9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.14kr0p1lOk/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.HoVbgyESD3/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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-04-19 09:30:23,987 truncating revision to first 12 chars 2016-04-19 09:30:23,987 Setting DEBUG logging. 2016-04-19 09:30:23,987 attempt 1/10 2016-04-19 09:30:23,987 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/e2f5cd553b44?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-19 09:30:25,632 unpacking tar archive at: fx-team-e2f5cd553b44/testing/mozharness/ program finished with exit code 0 elapsedTime=1.892901 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-19 09:30:25.821123) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-19 09:30:25.821440) ========= script_repo_revision: e2f5cd553b44f73fa85c8427aed560f9a47f0ce9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-19 09:30:25.821842) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-19 09:30:25.822118) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-19 09:30:25.835973) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-04-19 09:30:25.836240) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.14kr0p1lOk/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.HoVbgyESD3/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:30:26 INFO - MultiFileLogger online at 20160419 09:30:26 in /builds/slave/test 09:30:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:30:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:30:26 INFO - {'append_to_log': False, 09:30:26 INFO - 'base_work_dir': '/builds/slave/test', 09:30:26 INFO - 'blob_upload_branch': 'Fx-Team', 09:30:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:30:26 INFO - 'branch': 'Fx-Team', 09:30:26 INFO - 'buildbot_json_path': 'buildprops.json', 09:30:26 INFO - 'config_files': ('talos/mac_config.py',), 09:30:26 INFO - 'default_actions': ('clobber', 09:30:26 INFO - 'read-buildbot-config', 09:30:26 INFO - 'download-and-extract', 09:30:26 INFO - 'populate-webroot', 09:30:26 INFO - 'create-virtualenv', 09:30:26 INFO - 'install', 09:30:26 INFO - 'run-tests'), 09:30:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:30:26 INFO - 'download_minidump_stackwalk': True, 09:30:26 INFO - 'download_symbols': 'ondemand', 09:30:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:30:26 INFO - 'tooltool.py': '/tools/tooltool.py', 09:30:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:30:26 INFO - '/tools/misc-python/virtualenv.py')}, 09:30:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:30:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:30:26 INFO - 'installer_path': 'installer.exe', 09:30:26 INFO - 'log_level': 'info', 09:30:26 INFO - 'log_name': 'talos', 09:30:26 INFO - 'log_to_console': True, 09:30:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:30:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:30:26 INFO - 'opt_config_files': (), 09:30:26 INFO - 'pip_index': False, 09:30:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:30:26 INFO - 'cmd': ('bash', 09:30:26 INFO - '-c', 09:30:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:30:26 INFO - 'enabled': True, 09:30:26 INFO - 'halt_on_failure': False, 09:30:26 INFO - 'name': 'check_screen_resolution'},), 09:30:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:30:26 INFO - 'cmd': ('bash', 09:30:26 INFO - '-c', 09:30:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:30:26 INFO - 'enabled': True, 09:30:26 INFO - 'halt_on_failure': False, 09:30:26 INFO - 'name': 'check_screen_resolution'},), 09:30:26 INFO - 'run_cmd_checks_enabled': True, 09:30:26 INFO - 'sps_profile': False, 09:30:26 INFO - 'sps_profile_interval': 0, 09:30:26 INFO - 'suite': 'dromaeojs', 09:30:26 INFO - 'system_bits': '32', 09:30:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:30:26 INFO - 'title': 't-yosemite-r7-0077', 09:30:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:30:26 INFO - 'use_talos_json': True, 09:30:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:30:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:30:26 INFO - 'work_dir': 'build'} 09:30:26 INFO - ##### 09:30:26 INFO - ##### Running clobber step. 09:30:26 INFO - ##### 09:30:26 INFO - Running pre-action listener: _resource_record_pre_action 09:30:26 INFO - Running main action method: clobber 09:30:26 INFO - rmtree: /builds/slave/test/build 09:30:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:30:27 INFO - Running post-action listener: _resource_record_post_action 09:30:27 INFO - ##### 09:30:27 INFO - ##### Running read-buildbot-config step. 09:30:27 INFO - ##### 09:30:27 INFO - Running pre-action listener: _resource_record_pre_action 09:30:27 INFO - Running main action method: read_buildbot_config 09:30:27 INFO - Using buildbot properties: 09:30:27 INFO - { 09:30:27 INFO - "project": "", 09:30:27 INFO - "product": "firefox", 09:30:27 INFO - "script_repo_revision": "production", 09:30:27 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:30:27 INFO - "repository": "", 09:30:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:30:27 INFO - "buildid": "20160419071334", 09:30:27 INFO - "pgo_build": "False", 09:30:27 INFO - "basedir": "/builds/slave/test", 09:30:27 INFO - "buildnumber": 637, 09:30:27 INFO - "slavename": "t-yosemite-r7-0077", 09:30:27 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:30:27 INFO - "platform": "yosemite_r7", 09:30:27 INFO - "scheduler": "tests-fx-team-macosx64-talos", 09:30:27 INFO - "branch": "fx-team", 09:30:27 INFO - "repo_path": "integration/fx-team", 09:30:27 INFO - "revision": "e2f5cd553b44f73fa85c8427aed560f9a47f0ce9", 09:30:27 INFO - "stage_platform": "macosx64", 09:30:27 INFO - "builduid": "729a2cec09bb4437b3b6a57d523c0486", 09:30:27 INFO - "slavebuilddir": "test" 09:30:27 INFO - } 09:30:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 09:30:27 INFO - Running post-action listener: _resource_record_post_action 09:30:27 INFO - ##### 09:30:27 INFO - ##### Running download-and-extract step. 09:30:27 INFO - ##### 09:30:27 INFO - Running pre-action listener: _resource_record_pre_action 09:30:27 INFO - Running main action method: download_and_extract 09:30:27 INFO - mkdir: /builds/slave/test/build/tests 09:30:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:27 INFO - https://queue.taskcluster.net/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:30:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 09:30:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 09:30:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 09:30:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 09:30:29 INFO - Downloaded 1199 bytes. 09:30:29 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 09:30:29 INFO - Using the following test package requirements: 09:30:29 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 09:30:29 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:30:29 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 09:30:29 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:30:29 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 09:30:29 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:30:29 INFO - u'jsshell-mac.zip'], 09:30:29 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:30:29 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 09:30:29 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 09:30:29 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:30:29 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 09:30:29 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:30:29 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 09:30:29 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:30:29 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 09:30:29 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:30:29 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 09:30:29 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:30:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:29 INFO - https://queue.taskcluster.net/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:30:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:30:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:30:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:30:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:30:30 INFO - Downloaded 18039418 bytes. 09:30:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:30:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:30:33 INFO - Return code: 0 09:30:33 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:30:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:33 INFO - https://queue.taskcluster.net/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:30:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:30:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:30:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:30:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:30:34 INFO - Downloaded 18039418 bytes. 09:30:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:30:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:30:38 INFO - Return code: 0 09:30:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:38 INFO - https://queue.taskcluster.net/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:30:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 09:30:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 09:30:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 09:30:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:30:39 INFO - Downloaded 10845952 bytes. 09:30:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:30:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:30:39 INFO - Return code: 0 09:30:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:39 INFO - https://queue.taskcluster.net/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:30:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 09:30:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 09:30:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 09:30:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 09:30:43 INFO - Downloaded 109939065 bytes. 09:30:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 09:30:43 INFO - mkdir: /builds/slave/test/properties 09:30:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:30:43 INFO - Writing to file /builds/slave/test/properties/build_url 09:30:43 INFO - Contents: 09:30:43 INFO - build_url:https://queue.taskcluster.net/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 09:30:45 INFO - Running post-action listener: _resource_record_post_action 09:30:45 INFO - Running post-action listener: set_extra_try_arguments 09:30:45 INFO - ##### 09:30:45 INFO - ##### Running populate-webroot step. 09:30:45 INFO - ##### 09:30:45 INFO - Running pre-action listener: _resource_record_pre_action 09:30:45 INFO - Running main action method: populate_webroot 09:30:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:30:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:30:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:30:45 INFO - '--tppagecycles', 09:30:45 INFO - '7'], 09:30:45 INFO - 'tests': ['tsvgm']}}, 09:30:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:30:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:30:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:30:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:30:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:30:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:30:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:45 INFO - 'talos_options': ['--disable-e10s'], 09:30:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:30:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:30:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:45 INFO - 'talos_options': ['--disable-e10s'], 09:30:45 INFO - 'tests': ['damp', 'tps']}, 09:30:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:45 INFO - 'tests': ['damp', 'tps']}, 09:30:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:30:45 INFO - 'tests': ['dromaeo_dom']}, 09:30:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:30:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:30:45 INFO - 'tests': ['a11yr', 09:30:45 INFO - 'ts_paint', 09:30:45 INFO - 'tpaint', 09:30:45 INFO - 'sessionrestore', 09:30:45 INFO - 'sessionrestore_no_auto_restore', 09:30:45 INFO - 'tabpaint']}, 09:30:45 INFO - 'other-e10s': {'tests': ['a11yr', 09:30:45 INFO - 'ts_paint', 09:30:45 INFO - 'tpaint', 09:30:45 INFO - 'sessionrestore', 09:30:45 INFO - 'sessionrestore_no_auto_restore', 09:30:45 INFO - 'tabpaint']}, 09:30:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:30:45 INFO - 'ts_paint', 09:30:45 INFO - 'tpaint', 09:30:45 INFO - 'sessionrestore', 09:30:45 INFO - 'sessionrestore_no_auto_restore', 09:30:45 INFO - 'tabpaint']}, 09:30:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:30:45 INFO - 'ts_paint', 09:30:45 INFO - 'tpaint', 09:30:45 INFO - 'sessionrestore', 09:30:45 INFO - 'sessionrestore_no_auto_restore', 09:30:45 INFO - 'tabpaint']}, 09:30:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:30:45 INFO - 'tests': ['a11yr', 09:30:45 INFO - 'ts_paint', 09:30:45 INFO - 'tpaint', 09:30:45 INFO - 'sessionrestore', 09:30:45 INFO - 'sessionrestore_no_auto_restore', 09:30:45 INFO - 'tabpaint']}, 09:30:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:30:45 INFO - 'tests': ['a11yr', 09:30:45 INFO - 'ts_paint', 09:30:45 INFO - 'tpaint', 09:30:45 INFO - 'sessionrestore', 09:30:45 INFO - 'sessionrestore_no_auto_restore', 09:30:45 INFO - 'tabpaint']}, 09:30:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:30:45 INFO - 'tests': ['tsvgx', 09:30:45 INFO - 'tsvgr_opacity', 09:30:45 INFO - 'tart', 09:30:45 INFO - 'tscrollx', 09:30:45 INFO - 'cart']}, 09:30:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:30:45 INFO - 'tsvgr_opacity', 09:30:45 INFO - 'tart', 09:30:45 INFO - 'tscrollx', 09:30:45 INFO - 'cart']}, 09:30:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:45 INFO - 'talos_options': ['--disable-e10s'], 09:30:45 INFO - 'tests': ['tp5o']}, 09:30:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:45 INFO - 'tests': ['tp5o']}, 09:30:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:45 INFO - 'talos_options': ['--disable-e10s', 09:30:45 INFO - '--xperf_path', 09:30:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:30:45 INFO - 'tests': ['tp5n']}, 09:30:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:30:45 INFO - 'talos_options': ['--xperf_path', 09:30:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:30:45 INFO - 'tests': ['tp5n']}}, 09:30:45 INFO - 'talos.zip': {'path': '', 09:30:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:30:45 INFO - Running post-action listener: _resource_record_post_action 09:30:45 INFO - ##### 09:30:45 INFO - ##### Running create-virtualenv step. 09:30:45 INFO - ##### 09:30:45 INFO - Running pre-action listener: _resource_record_pre_action 09:30:45 INFO - Running main action method: create_virtualenv 09:30:45 INFO - Creating virtualenv /builds/slave/test/build/venv 09:30:45 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:30:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:30:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:30:45 INFO - Using real prefix '/tools/python27' 09:30:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:30:45 INFO - Installing distribute.............................................................................................................................................................................................done. 09:30:48 INFO - Installing pip.................done. 09:30:48 INFO - Return code: 0 09:30:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:30:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:48 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 0x106ef6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f06420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee4259d1f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d95e00>, '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 0x106bbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d90b20>, '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.14kr0p1lOk/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.HoVbgyESD3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:30:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:30:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:30:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.14kr0p1lOk/Render', 09:30:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:48 INFO - 'HOME': '/Users/cltbld', 09:30:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:48 INFO - 'LOGNAME': 'cltbld', 09:30:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:48 INFO - 'MOZ_NO_REMOTE': '1', 09:30:48 INFO - 'NO_EM_RESTART': '1', 09:30:48 INFO - 'PAGER': '/bin/cat', 09:30:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:48 INFO - 'PWD': '/builds/slave/test', 09:30:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:48 INFO - 'SHELL': '/bin/bash', 09:30:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HoVbgyESD3/Listeners', 09:30:48 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 09:30:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:48 INFO - 'USER': 'cltbld', 09:30:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:48 INFO - 'XPC_FLAGS': '0x0', 09:30:48 INFO - 'XPC_SERVICE_NAME': '0', 09:30:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:49 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:30:49 INFO - Cleaning up... 09:30:49 INFO - Return code: 0 09:30:49 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:30:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106ef6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f06420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee4259d1f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d95e00>, '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 0x106bbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d90b20>, '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.14kr0p1lOk/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.HoVbgyESD3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:30:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 09:30:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:30:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.14kr0p1lOk/Render', 09:30:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:49 INFO - 'HOME': '/Users/cltbld', 09:30:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:49 INFO - 'LOGNAME': 'cltbld', 09:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:49 INFO - 'MOZ_NO_REMOTE': '1', 09:30:49 INFO - 'NO_EM_RESTART': '1', 09:30:49 INFO - 'PAGER': '/bin/cat', 09:30:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:49 INFO - 'PWD': '/builds/slave/test', 09:30:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:49 INFO - 'SHELL': '/bin/bash', 09:30:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HoVbgyESD3/Listeners', 09:30:49 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 09:30:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:49 INFO - 'USER': 'cltbld', 09:30:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:49 INFO - 'XPC_FLAGS': '0x0', 09:30:49 INFO - 'XPC_SERVICE_NAME': '0', 09:30:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:49 INFO - Downloading/unpacking psutil>=0.7.1 09:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:30:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:30:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:30:51 INFO - Installing collected packages: psutil 09:30:51 INFO - Running setup.py install for psutil 09:30:51 INFO - building 'psutil._psutil_osx' extension 09:30:51 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:30:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:30:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:30:52 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:30:52 INFO - building 'psutil._psutil_posix' extension 09:30:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:30:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:30:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:30:52 INFO - ^ 09:30:52 INFO - 1 warning generated. 09:30:52 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:30:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:30:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:30:53 INFO - Successfully installed psutil 09:30:53 INFO - Cleaning up... 09:30:53 INFO - Return code: 0 09:30:53 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:30:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:53 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 0x106ef6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f06420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee4259d1f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d95e00>, '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 0x106bbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d90b20>, '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.14kr0p1lOk/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.HoVbgyESD3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:30:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 09:30:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:30:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.14kr0p1lOk/Render', 09:30:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:53 INFO - 'HOME': '/Users/cltbld', 09:30:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:53 INFO - 'LOGNAME': 'cltbld', 09:30:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:53 INFO - 'MOZ_NO_REMOTE': '1', 09:30:53 INFO - 'NO_EM_RESTART': '1', 09:30:53 INFO - 'PAGER': '/bin/cat', 09:30:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:53 INFO - 'PWD': '/builds/slave/test', 09:30:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:53 INFO - 'SHELL': '/bin/bash', 09:30:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HoVbgyESD3/Listeners', 09:30:53 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 09:30:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:53 INFO - 'USER': 'cltbld', 09:30:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:53 INFO - 'XPC_FLAGS': '0x0', 09:30:53 INFO - 'XPC_SERVICE_NAME': '0', 09:30:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:53 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:30:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:55 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:30:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:30:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:30:55 INFO - Installing collected packages: mozsystemmonitor 09:30:55 INFO - Running setup.py install for mozsystemmonitor 09:30:56 INFO - Successfully installed mozsystemmonitor 09:30:56 INFO - Cleaning up... 09:30:56 INFO - Return code: 0 09:30:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:30:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:30:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:30:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:30:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:30:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106ef6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f06420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee4259d1f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d95e00>, '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 0x106bbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d90b20>, '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.14kr0p1lOk/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.HoVbgyESD3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:30:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:30:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:30:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.14kr0p1lOk/Render', 09:30:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:30:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:30:56 INFO - 'HOME': '/Users/cltbld', 09:30:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:30:56 INFO - 'LOGNAME': 'cltbld', 09:30:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:30:56 INFO - 'MOZ_NO_REMOTE': '1', 09:30:56 INFO - 'NO_EM_RESTART': '1', 09:30:56 INFO - 'PAGER': '/bin/cat', 09:30:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:30:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:30:56 INFO - 'PWD': '/builds/slave/test', 09:30:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:30:56 INFO - 'SHELL': '/bin/bash', 09:30:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HoVbgyESD3/Listeners', 09:30:56 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 09:30:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:30:56 INFO - 'USER': 'cltbld', 09:30:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:30:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:30:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:30:56 INFO - 'XPC_FLAGS': '0x0', 09:30:56 INFO - 'XPC_SERVICE_NAME': '0', 09:30:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:30:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:30:56 INFO - Downloading/unpacking blobuploader==1.2.4 09:30:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:58 INFO - Downloading blobuploader-1.2.4.tar.gz 09:30:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:30:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:30:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:30:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:30:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:30:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:00 INFO - Downloading docopt-0.6.1.tar.gz 09:31:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:31:00 INFO - Installing collected packages: blobuploader, requests, docopt 09:31:00 INFO - Running setup.py install for blobuploader 09:31:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:31:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:31:00 INFO - Running setup.py install for requests 09:31:00 INFO - Running setup.py install for docopt 09:31:00 INFO - Successfully installed blobuploader requests docopt 09:31:00 INFO - Cleaning up... 09:31:01 INFO - Return code: 0 09:31:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:31:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:01 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 0x106ef6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f06420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee4259d1f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d95e00>, '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 0x106bbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d90b20>, '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.14kr0p1lOk/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.HoVbgyESD3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:31:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:31:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:31:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.14kr0p1lOk/Render', 09:31:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:01 INFO - 'HOME': '/Users/cltbld', 09:31:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:01 INFO - 'LOGNAME': 'cltbld', 09:31:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:01 INFO - 'MOZ_NO_REMOTE': '1', 09:31:01 INFO - 'NO_EM_RESTART': '1', 09:31:01 INFO - 'PAGER': '/bin/cat', 09:31:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:01 INFO - 'PWD': '/builds/slave/test', 09:31:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:01 INFO - 'SHELL': '/bin/bash', 09:31:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HoVbgyESD3/Listeners', 09:31:01 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 09:31:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:01 INFO - 'USER': 'cltbld', 09:31:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:01 INFO - 'XPC_FLAGS': '0x0', 09:31:01 INFO - 'XPC_SERVICE_NAME': '0', 09:31:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:31:01 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-FPiyto-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:31:01 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-ruVw5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:31:01 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-YGuWQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:31:01 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-RzFkkE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:31:01 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-5zUyYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:31:01 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-LgjNJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:31:01 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-tJfoJZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:31:02 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-WGPbAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:31:02 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-KrTljx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:31:02 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-Egfsei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:31:02 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-_CNvKv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:31:02 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-1a8nH_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:31:02 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-IEXLaZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:31:02 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-z_KWsG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:02 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-3Mwy7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:31:02 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-OojOSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:31:02 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-aP7JQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:31:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:31:03 INFO - Running setup.py install for manifestparser 09:31:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:31:03 INFO - Running setup.py install for mozcrash 09:31:03 INFO - Running setup.py install for mozdebug 09:31:03 INFO - Running setup.py install for mozdevice 09:31:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:31:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:31:03 INFO - Running setup.py install for mozfile 09:31:03 INFO - Running setup.py install for mozhttpd 09:31:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:31:03 INFO - Running setup.py install for mozinfo 09:31:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:31:04 INFO - Running setup.py install for mozInstall 09:31:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:31:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:31:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:31:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:31:04 INFO - Running setup.py install for mozleak 09:31:04 INFO - Running setup.py install for mozlog 09:31:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:31:04 INFO - Running setup.py install for moznetwork 09:31:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:31:04 INFO - Running setup.py install for mozprocess 09:31:04 INFO - Running setup.py install for mozprofile 09:31:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:31:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:31:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:31:05 INFO - Running setup.py install for mozrunner 09:31:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:31:05 INFO - Running setup.py install for mozscreenshot 09:31:05 INFO - Running setup.py install for moztest 09:31:05 INFO - Running setup.py install for mozversion 09:31:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:31:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:31:05 INFO - Cleaning up... 09:31:05 INFO - Return code: 0 09:31:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:31:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:05 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 0x106ef6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f06420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee4259d1f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d95e00>, '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 0x106bbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d90b20>, '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.14kr0p1lOk/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.HoVbgyESD3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:31:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:31:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:31:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.14kr0p1lOk/Render', 09:31:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:05 INFO - 'HOME': '/Users/cltbld', 09:31:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:05 INFO - 'LOGNAME': 'cltbld', 09:31:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:05 INFO - 'MOZ_NO_REMOTE': '1', 09:31:05 INFO - 'NO_EM_RESTART': '1', 09:31:05 INFO - 'PAGER': '/bin/cat', 09:31:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:05 INFO - 'PWD': '/builds/slave/test', 09:31:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:05 INFO - 'SHELL': '/bin/bash', 09:31:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HoVbgyESD3/Listeners', 09:31:05 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 09:31:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:05 INFO - 'USER': 'cltbld', 09:31:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:05 INFO - 'XPC_FLAGS': '0x0', 09:31:05 INFO - 'XPC_SERVICE_NAME': '0', 09:31:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:31:05 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-HphFgE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:31:06 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:31:06 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-Lw6Vjc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:31:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:31:06 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-h73M_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:31:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:31:06 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-hZ5zcE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:31:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:31:06 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-7hfPv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:31:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:31:06 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-k0PfRp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:31:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:31:06 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-I_EYl7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:31:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:31:06 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-txncyl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:31:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:31:06 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-fOaUhv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:31:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:31:06 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-BxJhbO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:31:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:31:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:31:06 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-yl3Z9V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:31:07 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-rbJflJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:31:07 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-84paoA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:31:07 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-An7R0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:07 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-SK5Uj0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:31:07 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-Kk2aMS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:31:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:31:07 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-wmVcbG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:31:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:10 INFO - Downloading blessings-1.6.tar.gz 09:31:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:31:10 INFO - Installing collected packages: blessings 09:31:10 INFO - Running setup.py install for blessings 09:31:10 INFO - Successfully installed blessings 09:31:10 INFO - Cleaning up... 09:31:10 INFO - Return code: 0 09:31:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:31:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:31:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:31:10 INFO - Reading from file tmpfile_stdout 09:31:10 INFO - Current package versions: 09:31:10 INFO - blessings == 1.6 09:31:10 INFO - blobuploader == 1.2.4 09:31:10 INFO - docopt == 0.6.1 09:31:10 INFO - manifestparser == 1.1 09:31:10 INFO - mozInstall == 1.12 09:31:10 INFO - mozcrash == 0.17 09:31:10 INFO - mozdebug == 0.1 09:31:10 INFO - mozdevice == 0.48 09:31:10 INFO - mozfile == 1.2 09:31:10 INFO - mozhttpd == 0.7 09:31:10 INFO - mozinfo == 0.9 09:31:10 INFO - mozleak == 0.1 09:31:10 INFO - mozlog == 3.1 09:31:10 INFO - moznetwork == 0.27 09:31:10 INFO - mozprocess == 0.22 09:31:10 INFO - mozprofile == 0.28 09:31:10 INFO - mozrunner == 6.11 09:31:10 INFO - mozscreenshot == 0.1 09:31:10 INFO - mozsystemmonitor == 0.0 09:31:10 INFO - moztest == 0.7 09:31:10 INFO - mozversion == 1.4 09:31:10 INFO - psutil == 3.1.1 09:31:10 INFO - requests == 1.2.3 09:31:10 INFO - wsgiref == 0.1.2 09:31:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:10 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 0x106ef6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f06420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee4259d1f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d95e00>, '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 0x106bbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d90b20>, '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.14kr0p1lOk/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.HoVbgyESD3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:31:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:31:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:31:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.14kr0p1lOk/Render', 09:31:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:10 INFO - 'HOME': '/Users/cltbld', 09:31:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:10 INFO - 'LOGNAME': 'cltbld', 09:31:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:10 INFO - 'MOZ_NO_REMOTE': '1', 09:31:10 INFO - 'NO_EM_RESTART': '1', 09:31:10 INFO - 'PAGER': '/bin/cat', 09:31:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:10 INFO - 'PWD': '/builds/slave/test', 09:31:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:10 INFO - 'SHELL': '/bin/bash', 09:31:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HoVbgyESD3/Listeners', 09:31:10 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 09:31:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:10 INFO - 'USER': 'cltbld', 09:31:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:10 INFO - 'XPC_FLAGS': '0x0', 09:31:10 INFO - 'XPC_SERVICE_NAME': '0', 09:31:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:31:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:31:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:31:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:31:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:31:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:31:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:31:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:31:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:31:11 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:31:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:31:11 INFO - Cleaning up... 09:31:11 INFO - Return code: 0 09:31:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:31: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')]} 09:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31: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')]} 09:31:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106ef6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f06420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee4259d1f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d95e00>, '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 0x106bbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d90b20>, '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.14kr0p1lOk/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.HoVbgyESD3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:31:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:31:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:31:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.14kr0p1lOk/Render', 09:31:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:11 INFO - 'HOME': '/Users/cltbld', 09:31:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:11 INFO - 'LOGNAME': 'cltbld', 09:31:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:11 INFO - 'MOZ_NO_REMOTE': '1', 09:31:11 INFO - 'NO_EM_RESTART': '1', 09:31:11 INFO - 'PAGER': '/bin/cat', 09:31:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:11 INFO - 'PWD': '/builds/slave/test', 09:31:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:11 INFO - 'SHELL': '/bin/bash', 09:31:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HoVbgyESD3/Listeners', 09:31:11 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 09:31:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:11 INFO - 'USER': 'cltbld', 09:31:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:11 INFO - 'XPC_FLAGS': '0x0', 09:31:11 INFO - 'XPC_SERVICE_NAME': '0', 09:31:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:11 INFO - Downloading/unpacking jsonschema 09:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:31:13 INFO - Downloading/unpacking functools32 (from jsonschema) 09:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:14 INFO - Downloading functools32-3.2.3-2.tar.gz 09:31:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:31:14 INFO - warning: no files found matching '*.txt' 09:31:14 INFO - no previously-included directories found matching 'build' 09:31:14 INFO - no previously-included directories found matching 'dist' 09:31:14 INFO - no previously-included directories found matching '.git*' 09:31:14 INFO - Installing collected packages: jsonschema, functools32 09:31:14 INFO - Running setup.py install for functools32 09:31:14 INFO - warning: no files found matching '*.txt' 09:31:14 INFO - no previously-included directories found matching 'build' 09:31:14 INFO - no previously-included directories found matching 'dist' 09:31:14 INFO - no previously-included directories found matching '.git*' 09:31:14 INFO - Successfully installed jsonschema functools32 09:31:14 INFO - Cleaning up... 09:31:14 INFO - Return code: 0 09:31:14 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:31:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:14 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 0x106ef6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f06420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee4259d1f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d95e00>, '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 0x106bbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d90b20>, '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.14kr0p1lOk/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.HoVbgyESD3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:31:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 09:31:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:31:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.14kr0p1lOk/Render', 09:31:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:14 INFO - 'HOME': '/Users/cltbld', 09:31:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:14 INFO - 'LOGNAME': 'cltbld', 09:31:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:14 INFO - 'MOZ_NO_REMOTE': '1', 09:31:14 INFO - 'NO_EM_RESTART': '1', 09:31:14 INFO - 'PAGER': '/bin/cat', 09:31:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:14 INFO - 'PWD': '/builds/slave/test', 09:31:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:14 INFO - 'SHELL': '/bin/bash', 09:31:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HoVbgyESD3/Listeners', 09:31:14 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 09:31:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:14 INFO - 'USER': 'cltbld', 09:31:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:14 INFO - 'XPC_FLAGS': '0x0', 09:31:14 INFO - 'XPC_SERVICE_NAME': '0', 09:31:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:14 INFO - Downloading/unpacking flake8 09:31:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:17 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:31:17 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:31:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:17 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:31:17 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:31:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:17 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:31:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:31:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:31:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:31:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:31:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:31:18 INFO - no previously-included directories found matching 'docs/_build' 09:31:18 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:31:18 INFO - Running setup.py install for pep8 09:31:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:31:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:31:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:31:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:31:18 INFO - no previously-included directories found matching 'docs/_build' 09:31:18 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:31:18 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:31:18 INFO - Cleaning up... 09:31:18 INFO - Return code: 0 09:31:18 INFO - Running post-action listener: _resource_record_post_action 09:31:18 INFO - Running post-action listener: _start_resource_monitoring 09:31:18 INFO - Starting resource monitoring. 09:31:18 INFO - ##### 09:31:18 INFO - ##### Running install step. 09:31:18 INFO - ##### 09:31:18 INFO - Running pre-action listener: _resource_record_pre_action 09:31:18 INFO - Running main action method: install 09:31:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:31:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:31:19 INFO - Reading from file tmpfile_stdout 09:31:19 INFO - Detecting whether we're running mozinstall >=1.0... 09:31:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:31:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:31:19 INFO - Reading from file tmpfile_stdout 09:31:19 INFO - Output received: 09:31:19 INFO - Usage: mozinstall [options] installer 09:31:19 INFO - Options: 09:31:19 INFO - -h, --help show this help message and exit 09:31:19 INFO - -d DEST, --destination=DEST 09:31:19 INFO - Directory to install application into. [default: 09:31:19 INFO - "/builds/slave/test"] 09:31:19 INFO - --app=APP Application being installed. [default: firefox] 09:31:19 INFO - mkdir: /builds/slave/test/build/application 09:31:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:31:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:31:52 INFO - Reading from file tmpfile_stdout 09:31:52 INFO - Output received: 09:31:52 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:31:52 INFO - Running post-action listener: _resource_record_post_action 09:31:52 INFO - ##### 09:31:52 INFO - ##### Running run-tests step. 09:31:52 INFO - ##### 09:31:52 INFO - Running pre-action listener: _resource_record_pre_action 09:31:52 INFO - Running main action method: run_tests 09:31:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:31:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:31:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:31:52 INFO - 2016-04-19 09:31:52.767 screenresolution[1641:7403] starting screenresolution argv=screenresolution get 09:31:52 INFO - 2016-04-19 09:31:52.770 screenresolution[1641:7403] Display 0: 1600x1200x32@60 09:31:52 INFO - 2016-04-19 09:31:52.776 screenresolution[1642:7406] starting screenresolution argv=screenresolution list 09:31:52 INFO - Available Modes on Display 0 09:31:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:31:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:31:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:31:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:31:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:31:53 INFO - Intel Iris: 09:31:53 INFO - Chipset Model: Intel Iris 09:31:53 INFO - Type: GPU 09:31:53 INFO - Bus: Built-In 09:31:53 INFO - VRAM (Dynamic, Max): 1536 MB 09:31:53 INFO - Vendor: Intel (0x8086) 09:31:53 INFO - Device ID: 0x0a2e 09:31:53 INFO - Revision ID: 0x0009 09:31:53 INFO - Displays: 09:31:53 INFO - Display: 09:31:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:31:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:31:53 INFO - Display Serial Number: 1600x1200 60 09:31:53 INFO - Main Display: Yes 09:31:53 INFO - Mirror: Off 09:31:53 INFO - Online: Yes 09:31:53 INFO - Rotation: Supported 09:31:53 INFO - Return code: 0 09:31:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:31:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:31:53 INFO - Python 2.7.3 09:31:53 INFO - Return code: 0 09:31:53 INFO - grabbing minidump binary from tooltool 09:31:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:31:53 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d95e00>, '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 0x106bbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d90b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:31:53 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:31:53 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:31:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:31:53 INFO - Return code: 0 09:31:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:31:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:31:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:31:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:31:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:31:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:31:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:31:54 INFO - Return code: 0 09:31:54 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', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0077', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:31:54 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 --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0077 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:31:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.14kr0p1lOk/Render', 09:31:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:31:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:31:54 INFO - 'HOME': '/Users/cltbld', 09:31:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:31:54 INFO - 'LOGNAME': 'cltbld', 09:31:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:31:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:31:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:54 INFO - 'MOZ_NO_REMOTE': '1', 09:31:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:31:54 INFO - 'NO_EM_RESTART': '1', 09:31:54 INFO - 'PAGER': '/bin/cat', 09:31:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:31:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:54 INFO - 'PWD': '/builds/slave/test', 09:31:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:31:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:31:54 INFO - 'SHELL': '/bin/bash', 09:31:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HoVbgyESD3/Listeners', 09:31:54 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 09:31:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:31:54 INFO - 'USER': 'cltbld', 09:31:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:31:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:31:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:54 INFO - 'XPC_FLAGS': '0x0', 09:31:54 INFO - 'XPC_SERVICE_NAME': '0', 09:31:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:31:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0077', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:31:54 INFO - mozversion application_buildid: 20160419071334 09:31:54 INFO - mozversion application_changeset: e2f5cd553b44f73fa85c8427aed560f9a47f0ce9 09:31:54 INFO - mozversion application_display_name: Nightly 09:31:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:31:54 INFO - mozversion application_name: Firefox 09:31:54 INFO - mozversion application_remotingname: firefox 09:31:54 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:31:54 INFO - mozversion application_vendor: Mozilla 09:31:54 INFO - mozversion application_version: 48.0a1 09:31:54 INFO - mozversion platform_buildid: 20160419071334 09:31:54 INFO - mozversion platform_changeset: e2f5cd553b44f73fa85c8427aed560f9a47f0ce9 09:31:54 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:31:54 INFO - mozversion platform_version: 48.0a1 09:31:54 INFO - using testdate: 1461083514 09:31:54 INFO - actual date: 1461083514 09:31:54 INFO - starting webserver on 'localhost:49276' 09:31:54 INFO - SUITE-START | Running 2 tests 09:31:54 INFO - TEST-START | dromaeo_css 09:31:54 INFO - operating with platform_type : mac_ 09:31:54 INFO - Initialising browser for dromaeo_css test... 09:31:54 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmp0Pg4fD/profile http://localhost:49276/getInfo.html) 09:31:56 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 09:31:56 INFO - PROCESS | 1660 | colorDepth:24 09:31:56 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 09:31:56 INFO - PROCESS | 1660 | __metrics 09:31:57 INFO - PROCESS | 1660 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:31:57 ERROR - PROCESS | 1660 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:31:57 INFO - TEST-INFO | 1660: exit 0 09:31:57 INFO - Browser initialized. 09:31:57 INFO - Running cycle 1/1 for dromaeo_css test... 09:31:57 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmp0Pg4fD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:32:09 INFO - PROCESS | 1662 | RSS: Main: 237379584 09:32:09 INFO - PROCESS | 1662 | 09:33:01 INFO - PROCESS | 1662 | 1461083581107 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:35:11 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 09:35:12 INFO - PROCESS | 1662 | RSS: Main: 367251456 09:35:12 INFO - PROCESS | 1662 | 09:38:16 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 09:38:16 INFO - PROCESS | 1662 | RSS: Main: 379002880 09:38:16 INFO - PROCESS | 1662 | 09:41:18 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 09:41:19 INFO - PROCESS | 1662 | RSS: Main: 376635392 09:41:19 INFO - PROCESS | 1662 | 09:44:21 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 09:44:22 INFO - PROCESS | 1662 | RSS: Main: 373248000 09:44:22 INFO - PROCESS | 1662 | 09:47:25 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 09:47:26 INFO - PROCESS | 1662 | RSS: Main: 340996096 09:47:26 INFO - PROCESS | 1662 | 09:50:29 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 09:50:29 INFO - PROCESS | 1662 | RSS: Main: 385527808 09:50:29 INFO - PROCESS | 1662 | 09:50:29 INFO - PROCESS | 1662 | __start_tp_report 09:50:29 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 09:50:29 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 09:50:29 INFO - PROCESS | 1662 | |i|pagename|runs| 09:50:29 INFO - PROCESS | 1662 | |0;dojo.html;3387.4072037393457;3415.7438192135583;3419.7606167568265;3453.274812289419;3479.6172421033666;5577.358603760569;5685.943140568593;5693.601447898638;5712.9999999999945;5719;5619.100943848985;5666.971665141678;5702.885942281152;5805.767769289223;5856.795012174564;5074.391073071237;5092.516210959953;5134.358205224347;5136.178211486163;5236.842894713146;4188.916221675565;5200.245964335169;5250.159974404096;5263.526282634562;5303.814366497173;5069.518395752398;5085.415177254626;5156.845294641129;5161.354830646169;5163.406208286039;5134.537891589737;5153.793848246066;5155.046316431472;5157.7936882524655;5160.354955630546;5101.566366858821;5155.072087024334;5158.174692049254;5171.922421163686;5186.92219616706;5532.917006244909;5573.996680597491;5614.3543492498475;5632;5687;6187.9480488317095;6193.225846769154;6221.222347206599;6223;6226.906596401058;6107.534191793982;6222.657753823538;6273.153124328249;6298;6300.716467758962;5769.163471296681;5868.559858010581;5871.735771890275;5875.735591898332;5877.882442351129;6295.150154729145;6304.905426418607;6321.525885558602;6323.588966717149;6326.43062124411;6014.368491308437;6078.544109191828;6084.148219249309;6103.267607887082;6116.602420842631;6097.312956045969;6156.907646385308;6257;6259.561830671809;6265.8120256392385;6162.969185154136;6183.690815459209;6190.690465476799;6197.504199664107;6214.161088253028;6084.756609735561;6240.188775459162;6244.500439964792;6246.3441338659695;6266.592671476339;4125.546189919107;4868.14807408702;4887.486813884562;4915.188993816052;4926.33494478248;4388.956110438855;4396.07682386696;4402.493713223021;4403.427554417905;4421.46942366918;5536.889262214733;5565.1095824667855;5646.632968857011;5650.74571644277;5698.8290351289525;6171.166892469549;6194.442500174916;6287.654179020106;6320.178376811077;6329.082283068929;5689.08974564076;5710.828675139753;5712.6286791358425;5712.914306285409;5726.742296596663;5415.404305526389;5415.675059496442;5416.133418652997;5426.484483974016;5431.728413579305;6195.473384762346;6256.843578910472;6335.619862808247;6337.302896681454;6343.9682801585695;6288.5598008138995;6312.368763123651;6313.40022697843;6317.273513545817;6336.904946425715;6194.690265486617;6264.185655864709;6307.306196318493;6326.936730632635;6341.778037768656;6090.330063692902;6115.453310713017;6119.807225076679;6128.136482154648;6145.201123853871;6066.545009124245;6243.875122497616;6264.561480696308;6278.968605156945;6297.275813281348;5906.173135760911;6017.729202185825;6089.391060893875;6098.237720284965;6111.297200821785;6091.086337049478;6094.634321940698;6102.328743838086;6104.853288833144;6144.78493252734;5942.1383899335215;5946.732397042057;5952.672603006876;5954;5959.463648271676;5866.3547009828035;6084;6108.847278818065;6119.624418755179;6122.265328160649;5735.426457354231;5904.793332233351;5935.258092738408;5959.940400595939;6021.307549631849 09:50:29 INFO - PROCESS | 1662 | |1;ext.html;496.913040217962;502.43977964569507;526.208056874404;526.9815556455525;580.4920694392405;29797.255068623293;29960.550591741143;30350.544741828864;30957.380852382994;39208.019799505026;41900;42507;42523;42524.57475425245;42528.14943701124;9488.857667134998;9706.466144362057;9746;9755.853662195037;9757.756056098584;8575.528345940971;11618.593349232779;11939.641810745692;11959;11960.401979901013;11609.18735688502;11681;11693.883061169407;11705.122115841346;11722.941385293061;11612.709682257926;11693.649190524298;11702.882971170264;11703.473343699554;11711.414429278544;10123.341982771097;11416.714582135513;11521.884781152165;11665.066794656492;11855.288682679067;39761.20477590461;40217;40224;40306;40325.19349612991;3152.7004934531183;3170.302533442639;3286.277019055804;3563.768355056913;4044.373122166069;28841.711582884112;30179.69820301791;31023.914163004185;31037.8448107758;31128.68871311303;2609.3476630842288;2689;2788.219298596396;2811.493931092423;2817.887284508617;268569;320979;321753;323732.3813380965;324729;60441.69779151076;61686.69156654188;62553.68723156355;62577.74844503085;62606;65061.67469162624;65222.347776522576;65287;65311;65480.67259663671;16655.91672041632;17190;17193.914030430016;17196.914015429844;17212.913935430242;11856.466459009192;12149.392530373447;13070.738585228431;13121.212727236396;13127.934360328138;15287.61780955485;17012.064336461364;17209;17225.913870430566;17232.827671723124;7249.456290778107;7308.744193953186;7704.844273358929;8078.041759537901;8164.713880113578;913.3195769151891;953.8557441608406;1004.8293737795533;1024.2727663358949;1111.483160330434;24811.5037699245;29858.988954800236;29909.850450748043;30085.69914300873;30132.355128141313;1071.6945670483874;1130.2370899642708;1162.4071723420948;1182.6570294614637;1223.3516236394546;13226.470941162439;14186.794613161703;14682.926585367004;15726.449574265074;16029.759553606706;635060;637585.8120709367;651147;651182;651543;60952.39047609467;62097.689511552155;62239;62286.37713622896;62331;1495.9850401496064;1524.5578782153052;1525.6643538421752;1539;1544.79145315381;30300.24249393783;30365.848170759004;30548;30874.53688194724;31147;43846.34230486481;53600.19599706086;53948.73025634847;54022.72988635032;54025.45974540361;577224;580613;581599;581808;584129;26806.329841754112;27238.863805680845;42402;44495.11009779851;44601.330980034676;44912.32631510465;45438.091238175715;45504;45507;45558;35709.64290357167;37388.439173411876;37635.870923872324;37789.95825498927;38744.80627596844;37140;37333;37510.43734343933;38223.23553528969;38442.42336364901;37238.44142337813;37549.62450375571;37943;38392.6160738389;38479.65321213744;30856.53715194229;31742.36515269728;32555;33261.833690831394;33492.83253583814;24182.75817241805;24213.394665133514;24417.26748197557;24849.254522364357;25216.049844983914 09:50:29 INFO - PROCESS | 1662 | |2;jquery.html;318.8421731243035;384.9826757795899;391.65534329789796;394.7808966023857;399.8320705303774;15135.108111486064;15886.523404297875;20166.495837604067;20786.272480463187;20848;21087.051082701277;21293.787062129373;21462.89268553659;21735.347939561798;21743.45641358967;5672.688002159869;5877.970610146965;5923.407659234085;5939.67331796751;6464.385883341075;6446.065320528521;6466.482681385502;6485.027245913104;6505.60966342021;6511.218653761555;6205.503559715212;6302.96372125539;6340.23917129945;6346.777862774804;6354.714037868284;6209.9068513972325;6316.115743795849;6317.463015643676;6324.71538780756;6326.43062124411;5443.700596467193;5573.108302671593;5600.1599760035915;5618.8314350569135;5633.436656334375;25373.238802835946;26466.941322347084;27129.45741085167;27135.05027324034;27284.727152728417;2180.760116387196;2193.4187440328324;2402.507485965373;2456.5086982603552;2860.7854410919263;14251.78622320666;14475.78286325706;14617.926910365486;14953.476628318065;15306.770398444032;1493.3503925792315;1674.6650669866076;1705.403108911871;1785.7142857142794;1879.7180422936533;113789;118375;118637.22044169344;118713.81286187113;118935;9438.575264113131;10080.445575493271;10177.643782467578;10177.796444071077;10311.74220644475;12507.187032842836;12513;12624.17942833713;12636.431360588796;12649.557265495663;8006.799830004296;8184.754457366289;8193.344532437392;8281.21328473794;8289.212524810133;8407.95796021016;8409.075001749803;8450.788730281793;8542.060373358925;8602.612882420306;3996.760194388346;4014.698897582693;4036.1927614476635;4040.716994897932;4083.5303940047274;1931.734713762488;1976.2193933396227;1995.2441851171168;2013.4161093283076;2020.8181263686338;905.6784841381346;938.2728385501155;950.6577632052456;1007.4005966449951;1013.5844303835393;19790.505237368892;20129.496762581053;20961.37115886526;21194.364169074954;21670.34988950334;924.523870206844;925.6945208081318;944.2729098594044;1009.6718566465928;1029.5418538750184;10555.525001374957;11008;11042.668719938414;11057.944710276397;11128.554857805759;565157;573487;574681;597365;603559;118953;125380.37309813393;125552;131342.34328828295;131465;1044.1646682653848;1070.989290107089;1079.794838980591;1090.6837017264904;1093.1691914145306;18433.354832580797;18704.251829926652;18788.624227515647;18936.52658683544;18970.43088707341;42832.5716742841;49063.999999998574;49411.75294123506;50122.99754004972;50266;68147.97778033433;76119;76157;76888;79189.85265957694;7662.6934922604705;7810.726624568113;8054.798630034296;8065.83868322642;8374.49753014821;9962.601495940371;11631.53473861036;12496.2505249267;12539.623811285675;12971.22172669605;10554.366737995744;10618.946905265424;10859.619913302997;10891.18316126278;11159.442027898735;9223.723288301362;9573.329866909527;10317.845232321371;10769.9461502692;10893.673189804318;10788.676339709822;10854.240203185702;11323.32060076365;11374.260673056226;11639.322955720401;8073.7577872663915;8112.269895709414;8219.753407397788;8354.373421993496;8457.915420845959;9350;10812.135029197803;10899.509522071685;10972;11005.504752286006 09:50:29 INFO - PROCESS | 1662 | |3;mootools.html;1377.5454100146953;1507.7964474795904;1582.9445969391074;1820.0990509697701;1971.9605607887852;7831.17772633873;8574.271186949109;8794.604242809073;8813.118688131184;8822.161894620001;8433.578321083953;8661.133886611336;8670;8675.436096653713;8676.609552570133;2136.166894910986;2364.7044119485063;2400.9639855402183;2430.8176886733477;2445.645381419693;2448.192096608115;2474.6906636670415;2480.1443501991785;2481.5036992601463;2481.6525686403916;2398.760123987605;2450.681411416522;2451.534208500379;2453.43570978675;2455.484348286862;2128.5742851429777;2355.163916809542;2416.8912398942;2426.187227278846;2430.8419952703193;2363.054778088778;2382.023370418138;2382.118616112032;2382.24959137871;2386.4988352446007;8422.705205317845;8440.873386899202;8466.872996905051;8467.195616416431;8469;5074.137396642543;5185.870353241199;5310.893782124374;5315.548178404802;5315.707636080014;3468.8785892493643;3484.9651503484893;3548.27260411615;3582.158192824684;3592.802395868226;1021.0555236406369;1026.0457774269926;1037.9896201037968;1039.9272050956436;1041.0578426524007;11668.416579171093;11706.297622142698;11721.882781172164;11722.413879306087;11799.233049851819;4625.838095666635;4669.883252918704;4816.54242846929;4838.903221935612;4867.221244600847;9830.803383932282;9956.900430995742;9965.900340996643;9974.152197063186;9978.201743860474;4585.174668559691;5313.57491400687;5345.1982202669915;5384.111621582395;5389.568834493231;4334.848280310174;4392.538783427758;4414.227510185705;4508.661850361236;4856.344393506832;3986.820593073319;3996.7801770902297;4042.777647229431;4081.571434999342;4116.670666346685;1323.5896871969528;1337.518493342396;1337.6054064051127;1351.9391627376792;1351.986480135186;801.5311043039887;843.3169133002287;877.7103555826562;891.790429249126;935.4761333653177;5499.395066542677;5510.283663123849;5738.856528586819;5775.5668324875805;5925;921.424109931293;965.6716716316486;971.4268581536927;971.8833739951251;984.640606178754;3736.3274610570356;3793.6016718244186;3869.4582758413844;3888.202918401721;3894.5131858517684;10360.585576577003;10818.567257309776;10844.024037836632;10849.837252441219;10875.184361172785;10322.432266225429;10514.894851051544;10685.465726713634;10845;10853.728656783485;8051.718189863327;8135.308498777552;8157.796055098552;8192.67229310821;8223.588820558949;4419.447569053868;5170.586353091819;5644.774209031592;5648.5763567731765;5899.941000589939;9465.905340946503;9746.415215087118;9759;9776.608935642495;9797.65708200238;9133.041030691682;9421.199198067967;9538.380005299634;9978.650747223812;10037.69886903394;6778.661066946732;7153.070100886852;7591.127020392725;7608.8478230436185;7610.238976102345;8312.87530687028;8341.332693384637;8409.915900840913;8419.95790021046;8420.989481262288;7192.96403518;7448.5158464699625;7457.925420745723;7539.849203016019;7563.243675632392;7187.389071928945;7242.529235599669;7292.270772922878;7394.186639469546;7455.291747283999;5788.768449262151;6851.3491218334175;6886.208086070166;6889.689963951534;6891.517593768589;4591.150637132141;4753.595944344769;4760.571548560508;4779.211430113992;4930.605551555938;4016.49793775778;4737.076270127292;4746.715197088186;4795.928061079157;4866.683665561727 09:50:29 INFO - PROCESS | 1662 | |4;prototype.html;309.6934035305047;315.75686721224656;322.3038237407201;328.0257634824572;328.84051235150963;5100.724818795301;5859.209006784083;5876.970615146924;5902.586818922677;5947.00775240295;5571.824060285639;5683.772649094031;5781.65842833652;5856.150858125569;5860.882782344351;4377.255866502686;4396.252637051709;4413.867583972486;4417.1607394595085;4451.844185453509;4226.577342265779;4387.758673272985;4394.62645675118;4431.446069241345;4447.154430911184;4207.347861081522;4399.73601583906;4402.691811573191;4426.5130835608215;4458.5318541553;4387.9122417551625;4411.1618792429335;4418.3372494125815;4430.623397011258;4433.312836510346;3182.6976437238427;3361.9831900840586;3812.5234345706785;3817.3319669058083;3824.770513769183;5419.349678038621;5694.943050569483;5711.400302968212;5715.228444160027;5728.856778580569;5069.366329208849;5073.568746656539;5088;5103.387593488768;5128.538431541179;5193.948060519384;5469.890602187934;6175.116589440219;6212.738312385988;6234.532410069263;3586.5337506124038;4061.228366610364;4259.125984566812;4289.957100428987;4325.935110973338;5170.353705786739;6103.901297766401;6197.81406557804;6252.624842509418;6579.9732123223375;3856.807159642007;4313;4349.478062632441;4362.716423432466;4369.077605853415;3670.33933891902;4021.678265738734;4175.684659332301;4229.836794881432;4447.866564003085;4581.5339091490405;4880.266596054022;5117.360329958755;5135.794568217304;5207;5438.293021907127;6242.469390101802;6251.718672659741;6263.217097862767;6277.8879833709;1204.3436327201696;1288.3558220889556;1449.1305216870005;1537.4772577323763;1546.072356586062;915.231205787142;1002.7944271424341;1006.5772375602264;1019.6889948565758;1024.6925922223304;4848.296996935495;5756.510696590753;5798.333191682926;5836.9124463133085;5846.386129456346;5871.444067322163;6231.75072997075;6393.904091438632;6435.774747883802;6450.903236451457;4472.955270447319;4632.698874573142;5638.731285460821;5774.335951365562;5785.71071446426;3216.809780381227;3366.865325386957;3383.120388698956;3401.1837159081642;3412.6416726243883;6294.905576416358;6526.869462610721;6652.401283884474;6658.967859981711;6682.966585167043;5485.835424937259;6554.541182117301;6563.7702680405955;6582.341765823475;6590.934090659128;6116.3577824328695;6604.45728394562;6608.570442921195;6639.601623902581;6685.899711504234;5794.942050579441;6103.481204097524;6478.708458119301;6490.740370385131;6510.088587597835;6298.370162983665;6332.113504109337;6402.871942561216;6405.711742971671;6440.774572889926;6446.227287495976;6735.831604209934;6744.69648865812;6748.628825414453;6803.115594972622;3961.306771315066;4099.139180771952;4116.897077573085;4160.62554370096;4184.225918205142;5677.687727175045;6399.232092148785;6437.356264373525;6460.289368169404;6490.415862572391;5057.671251368796;6026.306974697965;6066.787662431793;6233.844153896189;6240.469560087444;5923.911141332969;6300.243970723543;6301.369863013845;6330.056523716494;6331.9366806331345;6060.939390606214;6188.226471691039;6194.194754681863;6197.845053873689;6210.323212732627;6116.480099191618;6278.367624417593;6299.527535434952;6301.684915754102;6321.96839015802;6147.293061298008;6165.599236049822;6167.321594624498;6173.259208894961;6215.906761398673 09:50:29 INFO - PROCESS | 1662 | |5;yui.html;640.8368810608748;655.3642966322668;739.2533541123465;758.3516093739852;788.4796034617153;13581.185128892273;14252.57242282732;14737.557873263806;15176.772348414783;15211.543653690407;13849.515266965658;13893.652658683513;13943.581692549214;15125.470608528703;15718.37126514941;901.9188273055423;908.1508789282043;918.8254231695956;919.4253591505309;919.4850883505225;714.4570126703717;736.4476642518111;899.2356496977582;912.9497877616728;913.6528119314651;957.3729207369184;957.7366224288306;958.2717134977432;958.8657587937695;959.788846453779;702.7364738223166;950.3680052764903;950.9381890177151;952.2477242978039;952.7903861150536;705.5378726933867;714.0039644695672;714.1751277274756;715.3311653603897;716.0226291112646;15301.923490382476;15320.310586023656;15345.156016419092;15348.386064557404;15377.308021138964;12203.877961220363;12233.388330583524;12238;12542.435590398363;12580.182288151242;9867.753306167331;9999.950000250024;10033.648822291185;10386.27296089274;10465.581376744922;3382.4080785862375;3428.879989200366;3468.202313467891;3499.1602015516346;3698.6301369863068;84485;85644.57177714072;86714;88500;88886.66669999955;9253.306002049872;9389.530523473799;9455.763605909908;9524.14282714559;9689.757756056015;21478.57042859134;21483.925803709753;21496.247631333143;21518;21564.4608884776;7229.7469588564145;7391.8152046199275;7410.184879663233;7423.554586724814;7515.286047825448;4094.733842300241;4159.10579225468;4160.895977600524;4166.604172603597;4183.100633363842;2945.3520225550346;3633.9636603634153;3649.9270014600093;3657.5062366580705;3663.3405986922075;563.7716724726492;621.9191505104397;633.3919437340205;639.88162189995;647.9481641468672;494.99752501237265;499.9250112483157;557.6626141184524;604.431834075976;614.3089024847047;7516.962415187889;7835.6473958671995;7882.448228623941;8060.4760690556095;8227.71203007892;468.75624675168723;520.3600812317861;552.4972275229521;580.2630659062967;581.2356750872589;3632.724393923545;3661.35926212917;3673.210259794157;3722.6277372262557;3789.2800367930004;89250.55374723214;90379;90500.56996580226;92821.53589232145;92845.53577232205;61883.07175392373;75778.24221757823;76099;78151.60924195456;78228.21771782209;66877.99683004759;67431.66284168548;69744;70345;70489.64755176191;7047.823804404931;7123.501354905314;7347.709649052674;7497.10034795828;7562.432817538596;34810.47784283289;36559;41675.37486937759;43491.56508434875;44090.779546102065;59614;63900.68049659722;64238.357616425106;64251.90717737868;65190;8154.714584989734;8270.793230169294;9835.901640983784;9969.50152492387;10703.250772445854;10834.187435942178;10893;10905.945470272598;11289;11293.43532823372;10283.485825708534;10326.741831453974;10532.157427405637;10637.9468102659;10737.409442480739;10045.447500387256;10406.167506599606;10646.680599582025;10689.412082335546;11089.390083545482;10246.692599222035;10254.6410875619;10416.375017498975;10504.947475262881;10587.20595955291;8554.828903422022;8612.440191387539;8757.211850933447;8857.291416686778;9107.408018478778;8705.34709896773;8767;8880.777980550538;8908.420952638058;9044.638214471348 09:50:29 INFO - PROCESS | 1662 | __end_tp_report 09:50:29 INFO - PROCESS | 1662 | __start_cc_report 09:50:29 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,1325 09:50:29 INFO - PROCESS | 1662 | __end_cc_report 09:50:29 INFO - PROCESS | 1662 | __startTimestamp1461084629470__endTimestamp 09:50:29 INFO - PROCESS | 1662 | 09:50:29 INFO - PROCESS | 1662 | ------- Summary: start ------- 09:50:29 INFO - PROCESS | 1662 | Number of tests: 6 09:50:29 INFO - PROCESS | 1662 | 09:50:29 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5722.62 Median:5952.67 stddev:655.48 (11.0%) stddev-sans-first:631.37 09:50:29 INFO - PROCESS | 1662 | Values: 3387.4 3415.7 3419.8 3453.3 3479.6 5577.4 5685.9 5693.6 5713.0 5719.0 5619.1 5667.0 5702.9 5805.8 5856.8 5074.4 5092.5 5134.4 5136.2 5236.8 4188.9 5200.2 5250.2 5263.5 5303.8 5069.5 5085.4 5156.8 5161.4 5163.4 5134.5 5153.8 5155.0 5157.8 5160.4 5101.6 5155.1 5158.2 5171.9 5186.9 5532.9 5574.0 5614.4 5632.0 5687.0 6187.9 6193.2 6221.2 6223.0 6226.9 6107.5 6222.7 6273.2 6298.0 6300.7 5769.2 5868.6 5871.7 5875.7 5877.9 6295.2 6304.9 6321.5 6323.6 6326.4 6014.4 6078.5 6084.1 6103.3 6116.6 6097.3 6156.9 6257.0 6259.6 6265.8 6163.0 6183.7 6190.7 6197.5 6214.2 6084.8 6240.2 6244.5 6246.3 6266.6 4125.5 4868.1 4887.5 4915.2 4926.3 4389.0 4396.1 4402.5 4403.4 4421.5 5536.9 5565.1 5646.6 5650.7 5698.8 6171.2 6194.4 6287.7 6320.2 6329.1 5689.1 5710.8 5712.6 5712.9 5726.7 5415.4 5415.7 5416.1 5426.5 5431.7 6195.5 6256.8 6335.6 6337.3 6344.0 6288.6 6312.4 6313.4 6317.3 6336.9 6194.7 6264.2 6307.3 6326.9 6341.8 6090.3 6115.5 6119.8 6128.1 6145.2 6066.5 6243.9 6264.6 6279.0 6297.3 5906.2 6017.7 6089.4 6098.2 6111.3 6091.1 6094.6 6102.3 6104.9 6144.8 5942.1 5946.7 5952.7 5954.0 5959.5 5866.4 6084.0 6108.8 6119.6 6122.3 5735.4 5904.8 5935.3 5959.9 6021.3 09:50:29 INFO - PROCESS | 1662 | 09:50:29 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:65879.67 Median:324230.69 stddev:142656.90 (44.0%) stddev-sans-first:142972.63 09:50:29 INFO - PROCESS | 1662 | Values: 496.9 502.4 526.2 527.0 580.5 29797.3 29960.6 30350.5 30957.4 39208.0 41900.0 42507.0 42523.0 42524.6 42528.1 9488.9 9706.5 9746.0 9755.9 9757.8 8575.5 11618.6 11939.6 11959.0 11960.4 11609.2 11681.0 11693.9 11705.1 11722.9 11612.7 11693.6 11702.9 11703.5 11711.4 10123.3 11416.7 11521.9 11665.1 11855.3 39761.2 40217.0 40224.0 40306.0 40325.2 3152.7 3170.3 3286.3 3563.8 4044.4 28841.7 30179.7 31023.9 31037.8 31128.7 2609.3 2689.0 2788.2 2811.5 2817.9 268569.0 320979.0 321753.0 323732.4 324729.0 60441.7 61686.7 62553.7 62577.7 62606.0 65061.7 65222.3 65287.0 65311.0 65480.7 16655.9 17190.0 17193.9 17196.9 17212.9 11856.5 12149.4 13070.7 13121.2 13127.9 15287.6 17012.1 17209.0 17225.9 17232.8 7249.5 7308.7 7704.8 8078.0 8164.7 913.3 953.9 1004.8 1024.3 1111.5 24811.5 29859.0 29909.9 30085.7 30132.4 1071.7 1130.2 1162.4 1182.7 1223.4 13226.5 14186.8 14682.9 15726.4 16029.8 635060.0 637585.8 651147.0 651182.0 651543.0 60952.4 62097.7 62239.0 62286.4 62331.0 1496.0 1524.6 1525.7 1539.0 1544.8 30300.2 30365.8 30548.0 30874.5 31147.0 43846.3 53600.2 53948.7 54022.7 54025.5 577224.0 580613.0 581599.0 581808.0 584129.0 26806.3 27238.9 42402.0 44495.1 44601.3 44912.3 45438.1 45504.0 45507.0 45558.0 35709.6 37388.4 37635.9 37790.0 38744.8 37140.0 37333.0 37510.4 38223.2 38442.4 37238.4 37549.6 37943.0 38392.6 38479.7 30856.5 31742.4 32555.0 33261.8 33492.8 24182.8 24213.4 24417.3 24849.3 25216.0 09:50:29 INFO - PROCESS | 1662 | 09:50:29 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:34361.80 Median:2297.96 stddev:97359.42 (4236.8%) stddev-sans-first:97598.99 09:50:29 INFO - PROCESS | 1662 | Values: 318.8 385.0 391.7 394.8 399.8 15135.1 15886.5 20166.5 20786.3 20848.0 21087.1 21293.8 21462.9 21735.3 21743.5 5672.7 5878.0 5923.4 5939.7 6464.4 6446.1 6466.5 6485.0 6505.6 6511.2 6205.5 6303.0 6340.2 6346.8 6354.7 6209.9 6316.1 6317.5 6324.7 6326.4 5443.7 5573.1 5600.2 5618.8 5633.4 25373.2 26466.9 27129.5 27135.1 27284.7 2180.8 2193.4 2402.5 2456.5 2860.8 14251.8 14475.8 14617.9 14953.5 15306.8 1493.4 1674.7 1705.4 1785.7 1879.7 113789.0 118375.0 118637.2 118713.8 118935.0 9438.6 10080.4 10177.6 10177.8 10311.7 12507.2 12513.0 12624.2 12636.4 12649.6 8006.8 8184.8 8193.3 8281.2 8289.2 8408.0 8409.1 8450.8 8542.1 8602.6 3996.8 4014.7 4036.2 4040.7 4083.5 1931.7 1976.2 1995.2 2013.4 2020.8 905.7 938.3 950.7 1007.4 1013.6 19790.5 20129.5 20961.4 21194.4 21670.3 924.5 925.7 944.3 1009.7 1029.5 10555.5 11008.0 11042.7 11057.9 11128.6 565157.0 573487.0 574681.0 597365.0 603559.0 118953.0 125380.4 125552.0 131342.3 131465.0 1044.2 1071.0 1079.8 1090.7 1093.2 18433.4 18704.3 18788.6 18936.5 18970.4 42832.6 49064.0 49411.8 50123.0 50266.0 68148.0 76119.0 76157.0 76888.0 79189.9 7662.7 7810.7 8054.8 8065.8 8374.5 9962.6 11631.5 12496.3 12539.6 12971.2 10554.4 10618.9 10859.6 10891.2 11159.4 9223.7 9573.3 10317.8 10769.9 10893.7 10788.7 10854.2 11323.3 11374.3 11639.3 8073.8 8112.3 8219.8 8354.4 8457.9 9350.0 10812.1 10899.5 10972.0 11005.5 09:50:29 INFO - PROCESS | 1662 | 09:50:29 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5632.21 Median:4866.95 stddev:3157.30 (64.9%) stddev-sans-first:3149.96 09:50:29 INFO - PROCESS | 1662 | Values: 1377.5 1507.8 1582.9 1820.1 1972.0 7831.2 8574.3 8794.6 8813.1 8822.2 8433.6 8661.1 8670.0 8675.4 8676.6 2136.2 2364.7 2401.0 2430.8 2445.6 2448.2 2474.7 2480.1 2481.5 2481.7 2398.8 2450.7 2451.5 2453.4 2455.5 2128.6 2355.2 2416.9 2426.2 2430.8 2363.1 2382.0 2382.1 2382.2 2386.5 8422.7 8440.9 8466.9 8467.2 8469.0 5074.1 5185.9 5310.9 5315.5 5315.7 3468.9 3485.0 3548.3 3582.2 3592.8 1021.1 1026.0 1038.0 1039.9 1041.1 11668.4 11706.3 11721.9 11722.4 11799.2 4625.8 4669.9 4816.5 4838.9 4867.2 9830.8 9956.9 9965.9 9974.2 9978.2 4585.2 5313.6 5345.2 5384.1 5389.6 4334.8 4392.5 4414.2 4508.7 4856.3 3986.8 3996.8 4042.8 4081.6 4116.7 1323.6 1337.5 1337.6 1351.9 1352.0 801.5 843.3 877.7 891.8 935.5 5499.4 5510.3 5738.9 5775.6 5925.0 921.4 965.7 971.4 971.9 984.6 3736.3 3793.6 3869.5 3888.2 3894.5 10360.6 10818.6 10844.0 10849.8 10875.2 10322.4 10514.9 10685.5 10845.0 10853.7 8051.7 8135.3 8157.8 8192.7 8223.6 4419.4 5170.6 5644.8 5648.6 5899.9 9465.9 9746.4 9759.0 9776.6 9797.7 9133.0 9421.2 9538.4 9978.7 10037.7 6778.7 7153.1 7591.1 7608.8 7610.2 8312.9 8341.3 8409.9 8420.0 8421.0 7193.0 7448.5 7457.9 7539.8 7563.2 7187.4 7242.5 7292.3 7394.2 7455.3 5788.8 6851.3 6886.2 6889.7 6891.5 4591.2 4753.6 4760.6 4779.2 4930.6 4016.5 4737.1 4746.7 4795.9 4866.7 09:50:29 INFO - PROCESS | 1662 | 09:50:29 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:5057.65 Median:5713.31 stddev:1586.94 (27.8%) stddev-sans-first:1550.86 09:50:29 INFO - PROCESS | 1662 | Values: 309.7 315.8 322.3 328.0 328.8 5100.7 5859.2 5877.0 5902.6 5947.0 5571.8 5683.8 5781.7 5856.2 5860.9 4377.3 4396.3 4413.9 4417.2 4451.8 4226.6 4387.8 4394.6 4431.4 4447.2 4207.3 4399.7 4402.7 4426.5 4458.5 4387.9 4411.2 4418.3 4430.6 4433.3 3182.7 3362.0 3812.5 3817.3 3824.8 5419.3 5694.9 5711.4 5715.2 5728.9 5069.4 5073.6 5088.0 5103.4 5128.5 5193.9 5469.9 6175.1 6212.7 6234.5 3586.5 4061.2 4259.1 4290.0 4325.9 5170.4 6103.9 6197.8 6252.6 6580.0 3856.8 4313.0 4349.5 4362.7 4369.1 3670.3 4021.7 4175.7 4229.8 4447.9 4581.5 4880.3 5117.4 5135.8 5207.0 5438.3 6242.5 6251.7 6263.2 6277.9 1204.3 1288.4 1449.1 1537.5 1546.1 915.2 1002.8 1006.6 1019.7 1024.7 4848.3 5756.5 5798.3 5836.9 5846.4 5871.4 6231.8 6393.9 6435.8 6450.9 4473.0 4632.7 5638.7 5774.3 5785.7 3216.8 3366.9 3383.1 3401.2 3412.6 6294.9 6526.9 6652.4 6659.0 6683.0 5485.8 6554.5 6563.8 6582.3 6590.9 6116.4 6604.5 6608.6 6639.6 6685.9 5794.9 6103.5 6478.7 6490.7 6510.1 6298.4 6332.1 6402.9 6405.7 6440.8 6446.2 6735.8 6744.7 6748.6 6803.1 3961.3 4099.1 4116.9 4160.6 4184.2 5677.7 6399.2 6437.4 6460.3 6490.4 5057.7 6026.3 6066.8 6233.8 6240.5 5923.9 6300.2 6301.4 6330.1 6331.9 6060.9 6188.2 6194.2 6197.8 6210.3 6116.5 6278.4 6299.5 6301.7 6322.0 6147.3 6165.6 6167.3 6173.3 6215.9 09:50:29 INFO - PROCESS | 1662 | 09:50:29 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:17674.08 Median:32270.76 stddev:25313.11 (78.4%) stddev-sans-first:25351.81 09:50:29 INFO - PROCESS | 1662 | Values: 640.8 655.4 739.3 758.4 788.5 13581.2 14252.6 14737.6 15176.8 15211.5 13849.5 13893.7 13943.6 15125.5 15718.4 901.9 908.2 918.8 919.4 919.5 714.5 736.4 899.2 912.9 913.7 957.4 957.7 958.3 958.9 959.8 702.7 950.4 950.9 952.2 952.8 705.5 714.0 714.2 715.3 716.0 15301.9 15320.3 15345.2 15348.4 15377.3 12203.9 12233.4 12238.0 12542.4 12580.2 9867.8 10000.0 10033.6 10386.3 10465.6 3382.4 3428.9 3468.2 3499.2 3698.6 84485.0 85644.6 86714.0 88500.0 88886.7 9253.3 9389.5 9455.8 9524.1 9689.8 21478.6 21483.9 21496.2 21518.0 21564.5 7229.7 7391.8 7410.2 7423.6 7515.3 4094.7 4159.1 4160.9 4166.6 4183.1 2945.4 3634.0 3649.9 3657.5 3663.3 563.8 621.9 633.4 639.9 647.9 495.0 499.9 557.7 604.4 614.3 7517.0 7835.6 7882.4 8060.5 8227.7 468.8 520.4 552.5 580.3 581.2 3632.7 3661.4 3673.2 3722.6 3789.3 89250.6 90379.0 90500.6 92821.5 92845.5 61883.1 75778.2 76099.0 78151.6 78228.2 66878.0 67431.7 69744.0 70345.0 70489.6 7047.8 7123.5 7347.7 7497.1 7562.4 34810.5 36559.0 41675.4 43491.6 44090.8 59614.0 63900.7 64238.4 64251.9 65190.0 8154.7 8270.8 9835.9 9969.5 10703.3 10834.2 10893.0 10905.9 11289.0 11293.4 10283.5 10326.7 10532.2 10637.9 10737.4 10045.4 10406.2 10646.7 10689.4 11089.4 10246.7 10254.6 10416.4 10504.9 10587.2 8554.8 8612.4 8757.2 8857.3 9107.4 8705.3 8767.0 8880.8 8908.4 9044.6 09:50:29 INFO - PROCESS | 1662 | -------- Summary: end -------- 09:50:29 INFO - PROCESS | 1662 | 09:50:30 INFO - TEST-INFO | 1662: exit 0 09:50:30 INFO - TEST-OK | dromaeo_css | took 1115525ms 09:50:30 INFO - TEST-START | kraken 09:50:30 INFO - operating with platform_type : mac_ 09:50:30 INFO - Initialising browser for kraken test... 09:50:30 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpUBZIS0/profile http://localhost:49276/getInfo.html) 09:50:32 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 09:50:32 INFO - PROCESS | 1671 | colorDepth:24 09:50:32 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 09:50:32 INFO - PROCESS | 1671 | __metrics 09:50:32 INFO - PROCESS | 1671 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:50:32 ERROR - PROCESS | 1671 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:50:33 INFO - TEST-INFO | 1671: exit 0 09:50:33 INFO - Browser initialized. 09:50:33 INFO - Running cycle 1/1 for kraken test... 09:50:33 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpUBZIS0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:50:45 INFO - PROCESS | 1673 | RSS: Main: 237223936 09:50:45 INFO - PROCESS | 1673 | 09:50:47 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 09:50:48 INFO - PROCESS | 1673 | RSS: Main: 285118464 09:50:48 INFO - PROCESS | 1673 | 09:50:51 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 09:50:51 INFO - PROCESS | 1673 | RSS: Main: 298487808 09:50:51 INFO - PROCESS | 1673 | 09:50:55 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 09:50:56 INFO - PROCESS | 1673 | RSS: Main: 385028096 09:50:56 INFO - PROCESS | 1673 | 09:50:58 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 09:50:58 INFO - PROCESS | 1673 | RSS: Main: 285970432 09:50:58 INFO - PROCESS | 1673 | 09:51:00 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:51:01 INFO - PROCESS | 1673 | RSS: Main: 290320384 09:51:01 INFO - PROCESS | 1673 | 09:51:04 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 09:51:04 INFO - PROCESS | 1673 | RSS: Main: 328048640 09:51:04 INFO - PROCESS | 1673 | 09:51:07 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 09:51:08 INFO - PROCESS | 1673 | RSS: Main: 329412608 09:51:08 INFO - PROCESS | 1673 | 09:51:11 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 09:51:12 INFO - PROCESS | 1673 | RSS: Main: 326688768 09:51:12 INFO - PROCESS | 1673 | 09:51:13 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:51:14 INFO - PROCESS | 1673 | RSS: Main: 319488000 09:51:14 INFO - PROCESS | 1673 | 09:51:16 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:51:16 INFO - PROCESS | 1673 | RSS: Main: 326823936 09:51:16 INFO - PROCESS | 1673 | 09:51:18 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:51:19 INFO - PROCESS | 1673 | RSS: Main: 329543680 09:51:19 INFO - PROCESS | 1673 | 09:51:22 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:51:23 INFO - PROCESS | 1673 | RSS: Main: 325541888 09:51:23 INFO - PROCESS | 1673 | 09:51:26 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:51:26 INFO - PROCESS | 1673 | RSS: Main: 326033408 09:51:26 INFO - PROCESS | 1673 | 09:51:28 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 09:51:28 INFO - PROCESS | 1673 | RSS: Main: 391135232 09:51:28 INFO - PROCESS | 1673 | 09:51:28 INFO - PROCESS | 1673 | __start_tp_report 09:51:28 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 09:51:28 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 09:51:28 INFO - PROCESS | 1673 | |i|pagename|runs| 09:51:28 INFO - PROCESS | 1673 | |0;ai-astar;112;111;113;113;113;113;119;111;113;112 09:51:28 INFO - PROCESS | 1673 | |1;audio-beat-detection;156;123;144;180;150;146;130;138;211;148 09:51:28 INFO - PROCESS | 1673 | |2;audio-dft;186;186;184;184;187;187;183;183;183;184 09:51:28 INFO - PROCESS | 1673 | |3;audio-fft;77;77;109;118;83;57;58;78;83;54 09:51:28 INFO - PROCESS | 1673 | |4;audio-oscillator;92;102;141;79;77;76;157;120;118;156 09:51:28 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;114;85;88;84;88;83;87;85;88;84 09:51:28 INFO - PROCESS | 1673 | |6;imaging-darkroom;94;93;94;93;93;94;93;93;97;93 09:51:28 INFO - PROCESS | 1673 | |7;imaging-desaturate;86;103;134;132;85;84;121;133;102;85 09:51:28 INFO - PROCESS | 1673 | |8;json-parse-financial;60;64;58;59;59;59;61;76;59;57 09:51:28 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;64;71;77;73;71;67;67;68;67;66 09:51:28 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;81;82;82;83;82;82;80;80;82;83 09:51:28 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;140;148;139;136;133;141;134;147;188;245 09:51:28 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;170;171;219;227;168;172;169;169;206;167 09:51:28 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;72;70;70;68;71;69;70;69;66;66 09:51:28 INFO - PROCESS | 1673 | __end_tp_report 09:51:28 INFO - PROCESS | 1673 | __start_cc_report 09:51:28 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,1946 09:51:28 INFO - PROCESS | 1673 | __end_cc_report 09:51:28 INFO - PROCESS | 1673 | __startTimestamp1461084688737__endTimestamp 09:51:28 INFO - PROCESS | 1673 | 09:51:28 INFO - PROCESS | 1673 | ------- Summary: start ------- 09:51:28 INFO - PROCESS | 1673 | Number of tests: 14 09:51:28 INFO - PROCESS | 1673 | 09:51:28 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:113.00 Median:113.00 stddev:2.26 (2.0%) stddev-sans-first:2.37 09:51:28 INFO - PROCESS | 1673 | Values: 112.0 111.0 113.0 113.0 113.0 113.0 119.0 111.0 113.0 112.0 09:51:28 INFO - PROCESS | 1673 | 09:51:28 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:152.60 Median:149.00 stddev:25.64 (17.2%) stddev-sans-first:27.17 09:51:28 INFO - PROCESS | 1673 | Values: 156.0 123.0 144.0 180.0 150.0 146.0 130.0 138.0 211.0 148.0 09:51:28 INFO - PROCESS | 1673 | 09:51:28 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:184.70 Median:185.00 stddev:1.64 (0.9%) stddev-sans-first:1.67 09:51:28 INFO - PROCESS | 1673 | Values: 186.0 186.0 184.0 184.0 187.0 187.0 183.0 183.0 183.0 184.0 09:51:28 INFO - PROCESS | 1673 | 09:51:28 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:79.40 Median:77.00 stddev:21.11 (27.4%) stddev-sans-first:22.37 09:51:28 INFO - PROCESS | 1673 | Values: 77.0 77.0 109.0 118.0 83.0 57.0 58.0 78.0 83.0 54.0 09:51:28 INFO - PROCESS | 1673 | 09:51:28 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:111.80 Median:116.50 stddev:31.64 (27.2%) stddev-sans-first:32.74 09:51:28 INFO - PROCESS | 1673 | Values: 92.0 102.0 141.0 79.0 77.0 76.0 157.0 120.0 118.0 156.0 09:51:28 INFO - PROCESS | 1673 | 09:51:28 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:88.60 Median:86.00 stddev:9.12 (10.6%) stddev-sans-first:1.99 09:51:28 INFO - PROCESS | 1673 | Values: 114.0 85.0 88.0 84.0 88.0 83.0 87.0 85.0 88.0 84.0 09:51:28 INFO - PROCESS | 1673 | 09:51:28 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:93.70 Median:93.50 stddev:1.25 (1.3%) stddev-sans-first:1.32 09:51:28 INFO - PROCESS | 1673 | Values: 94.0 93.0 94.0 93.0 93.0 94.0 93.0 93.0 97.0 93.0 09:51:28 INFO - PROCESS | 1673 | 09:51:28 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:106.50 Median:109.00 stddev:21.61 (19.8%) stddev-sans-first:21.61 09:51:28 INFO - PROCESS | 1673 | Values: 86.0 103.0 134.0 132.0 85.0 84.0 121.0 133.0 102.0 85.0 09:51:28 INFO - PROCESS | 1673 | 09:51:28 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:61.20 Median:59.50 stddev:5.53 (9.3%) stddev-sans-first:5.85 09:51:28 INFO - PROCESS | 1673 | Values: 60.0 64.0 58.0 59.0 59.0 59.0 61.0 76.0 59.0 57.0 09:51:28 INFO - PROCESS | 1673 | 09:51:28 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:69.10 Median:69.50 stddev:3.87 (5.6%) stddev-sans-first:3.64 09:51:28 INFO - PROCESS | 1673 | Values: 64.0 71.0 77.0 73.0 71.0 67.0 67.0 68.0 67.0 66.0 09:51:28 INFO - PROCESS | 1673 | 09:51:28 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:81.70 Median:82.00 stddev:1.06 (1.3%) stddev-sans-first:1.09 09:51:28 INFO - PROCESS | 1673 | Values: 81.0 82.0 82.0 83.0 82.0 82.0 80.0 80.0 82.0 83.0 09:51:28 INFO - PROCESS | 1673 | 09:51:28 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:155.10 Median:144.00 stddev:35.38 (24.6%) stddev-sans-first:37.10 09:51:28 INFO - PROCESS | 1673 | Values: 140.0 148.0 139.0 136.0 133.0 141.0 134.0 147.0 188.0 245.0 09:51:28 INFO - PROCESS | 1673 | 09:51:28 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.80 Median:171.50 stddev:23.71 (13.8%) stddev-sans-first:24.62 09:51:28 INFO - PROCESS | 1673 | Values: 170.0 171.0 219.0 227.0 168.0 172.0 169.0 169.0 206.0 167.0 09:51:28 INFO - PROCESS | 1673 | 09:51:28 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:70.00 stddev:1.97 (2.8%) stddev-sans-first:1.79 09:51:28 INFO - PROCESS | 1673 | Values: 72.0 70.0 70.0 68.0 71.0 69.0 70.0 69.0 66.0 66.0 09:51:28 INFO - PROCESS | 1673 | -------- Summary: end -------- 09:51:28 INFO - PROCESS | 1673 | 09:51:29 INFO - TEST-INFO | 1673: exit 0 09:51:29 INFO - TEST-OK | kraken | took 59473ms 09:51:29 INFO - SUITE-END | took 1174s 09:51:29 INFO - Completed test suite (00:19:35) 09:51:29 INFO - javascript benchmark 09:51:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3387.4072037393457, 3415.7438192135583, 3419.7606167568265, 3453.274812289419, 3479.6172421033666, 5577.358603760569, 5685.943140568593, 5693.601447898638, 5712.9999999999945, 5719.0, 5619.100943848985, 5666.971665141678, 5702.885942281152, 5805.767769289223, 5856.795012174564, 5074.391073071237, 5092.516210959953, 5134.358205224347, 5136.178211486163, 5236.842894713146, 4188.916221675565, 5200.245964335169, 5250.159974404096, 5263.526282634562, 5303.814366497173, 5069.518395752398, 5085.415177254626, 5156.845294641129, 5161.354830646169, 5163.406208286039, 5134.537891589737, 5153.793848246066, 5155.046316431472, 5157.7936882524655, 5160.354955630546, 5101.566366858821, 5155.072087024334, 5158.174692049254, 5171.922421163686, 5186.92219616706, 5532.917006244909, 5573.996680597491, 5614.3543492498475, 5632.0, 5687.0, 6187.9480488317095, 6193.225846769154, 6221.222347206599, 6223.0, 6226.906596401058, 6107.534191793982, 6222.657753823538, 6273.153124328249, 6298.0, 6300.716467758962, 5769.163471296681, 5868.559858010581, 5871.735771890275, 5875.735591898332, 5877.882442351129, 6295.150154729145, 6304.905426418607, 6321.525885558602, 6323.588966717149, 6326.43062124411, 6014.368491308437, 6078.544109191828, 6084.148219249309, 6103.267607887082, 6116.602420842631, 6097.312956045969, 6156.907646385308, 6257.0, 6259.561830671809, 6265.8120256392385, 6162.969185154136, 6183.690815459209, 6190.690465476799, 6197.504199664107, 6214.161088253028, 6084.756609735561, 6240.188775459162, 6244.500439964792, 6246.3441338659695, 6266.592671476339, 4125.546189919107, 4868.14807408702, 4887.486813884562, 4915.188993816052, 4926.33494478248, 4388.956110438855, 4396.07682386696, 4402.493713223021, 4403.427554417905, 4421.46942366918, 5536.889262214733, 5565.1095824667855, 5646.632968857011, 5650.74571644277, 5698.8290351289525, 6171.166892469549, 6194.442500174916, 6287.654179020106, 6320.178376811077, 6329.082283068929, 5689.08974564076, 5710.828675139753, 5712.6286791358425, 5712.914306285409, 5726.742296596663, 5415.404305526389, 5415.675059496442, 5416.133418652997, 5426.484483974016, 5431.728413579305, 6195.473384762346, 6256.843578910472, 6335.619862808247, 6337.302896681454, 6343.9682801585695, 6288.5598008138995, 6312.368763123651, 6313.40022697843, 6317.273513545817, 6336.904946425715, 6194.690265486617, 6264.185655864709, 6307.306196318493, 6326.936730632635, 6341.778037768656, 6090.330063692902, 6115.453310713017, 6119.807225076679, 6128.136482154648, 6145.201123853871, 6066.545009124245, 6243.875122497616, 6264.561480696308, 6278.968605156945, 6297.275813281348, 5906.173135760911, 6017.729202185825, 6089.391060893875, 6098.237720284965, 6111.297200821785, 6091.086337049478, 6094.634321940698, 6102.328743838086, 6104.853288833144, 6144.78493252734, 5942.1383899335215, 5946.732397042057, 5952.672603006876, 5954.0, 5959.463648271676, 5866.3547009828035, 6084.0, 6108.847278818065, 6119.624418755179, 6122.265328160649, 5735.426457354231, 5904.793332233351, 5935.258092738408, 5959.940400595939, 6021.307549631849], "value": 5680.060022904499, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.913040217962, 502.43977964569507, 526.208056874404, 526.9815556455525, 580.4920694392405, 29797.255068623293, 29960.550591741143, 30350.544741828864, 30957.380852382994, 39208.019799505026, 41900.0, 42507.0, 42523.0, 42524.57475425245, 42528.14943701124, 9488.857667134998, 9706.466144362057, 9746.0, 9755.853662195037, 9757.756056098584, 8575.528345940971, 11618.593349232779, 11939.641810745692, 11959.0, 11960.401979901013, 11609.18735688502, 11681.0, 11693.883061169407, 11705.122115841346, 11722.941385293061, 11612.709682257926, 11693.649190524298, 11702.882971170264, 11703.473343699554, 11711.414429278544, 10123.341982771097, 11416.714582135513, 11521.884781152165, 11665.066794656492, 11855.288682679067, 39761.20477590461, 40217.0, 40224.0, 40306.0, 40325.19349612991, 3152.7004934531183, 3170.302533442639, 3286.277019055804, 3563.768355056913, 4044.373122166069, 28841.711582884112, 30179.69820301791, 31023.914163004185, 31037.8448107758, 31128.68871311303, 2609.3476630842288, 2689.0, 2788.219298596396, 2811.493931092423, 2817.887284508617, 268569.0, 320979.0, 321753.0, 323732.3813380965, 324729.0, 60441.69779151076, 61686.69156654188, 62553.68723156355, 62577.74844503085, 62606.0, 65061.67469162624, 65222.347776522576, 65287.0, 65311.0, 65480.67259663671, 16655.91672041632, 17190.0, 17193.914030430016, 17196.914015429844, 17212.913935430242, 11856.466459009192, 12149.392530373447, 13070.738585228431, 13121.212727236396, 13127.934360328138, 15287.61780955485, 17012.064336461364, 17209.0, 17225.913870430566, 17232.827671723124, 7249.456290778107, 7308.744193953186, 7704.844273358929, 8078.041759537901, 8164.713880113578, 913.3195769151891, 953.8557441608406, 1004.8293737795533, 1024.2727663358949, 1111.483160330434, 24811.5037699245, 29858.988954800236, 29909.850450748043, 30085.69914300873, 30132.355128141313, 1071.6945670483874, 1130.2370899642708, 1162.4071723420948, 1182.6570294614637, 1223.3516236394546, 13226.470941162439, 14186.794613161703, 14682.926585367004, 15726.449574265074, 16029.759553606706, 635060.0, 637585.8120709367, 651147.0, 651182.0, 651543.0, 60952.39047609467, 62097.689511552155, 62239.0, 62286.37713622896, 62331.0, 1495.9850401496064, 1524.5578782153052, 1525.6643538421752, 1539.0, 1544.79145315381, 30300.24249393783, 30365.848170759004, 30548.0, 30874.53688194724, 31147.0, 43846.34230486481, 53600.19599706086, 53948.73025634847, 54022.72988635032, 54025.45974540361, 577224.0, 580613.0, 581599.0, 581808.0, 584129.0, 26806.329841754112, 27238.863805680845, 42402.0, 44495.11009779851, 44601.330980034676, 44912.32631510465, 45438.091238175715, 45504.0, 45507.0, 45558.0, 35709.64290357167, 37388.439173411876, 37635.870923872324, 37789.95825498927, 38744.80627596844, 37140.0, 37333.0, 37510.43734343933, 38223.23553528969, 38442.42336364901, 37238.44142337813, 37549.62450375571, 37943.0, 38392.6160738389, 38479.65321213744, 30856.53715194229, 31742.36515269728, 32555.0, 33261.833690831394, 33492.83253583814, 24182.75817241805, 24213.394665133514, 24417.26748197557, 24849.254522364357, 25216.049844983914], "value": 20201.5415890837, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.8421731243035, 384.9826757795899, 391.65534329789796, 394.7808966023857, 399.8320705303774, 15135.108111486064, 15886.523404297875, 20166.495837604067, 20786.272480463187, 20848.0, 21087.051082701277, 21293.787062129373, 21462.89268553659, 21735.347939561798, 21743.45641358967, 5672.688002159869, 5877.970610146965, 5923.407659234085, 5939.67331796751, 6464.385883341075, 6446.065320528521, 6466.482681385502, 6485.027245913104, 6505.60966342021, 6511.218653761555, 6205.503559715212, 6302.96372125539, 6340.23917129945, 6346.777862774804, 6354.714037868284, 6209.9068513972325, 6316.115743795849, 6317.463015643676, 6324.71538780756, 6326.43062124411, 5443.700596467193, 5573.108302671593, 5600.1599760035915, 5618.8314350569135, 5633.436656334375, 25373.238802835946, 26466.941322347084, 27129.45741085167, 27135.05027324034, 27284.727152728417, 2180.760116387196, 2193.4187440328324, 2402.507485965373, 2456.5086982603552, 2860.7854410919263, 14251.78622320666, 14475.78286325706, 14617.926910365486, 14953.476628318065, 15306.770398444032, 1493.3503925792315, 1674.6650669866076, 1705.403108911871, 1785.7142857142794, 1879.7180422936533, 113789.0, 118375.0, 118637.22044169344, 118713.81286187113, 118935.0, 9438.575264113131, 10080.445575493271, 10177.643782467578, 10177.796444071077, 10311.74220644475, 12507.187032842836, 12513.0, 12624.17942833713, 12636.431360588796, 12649.557265495663, 8006.799830004296, 8184.754457366289, 8193.344532437392, 8281.21328473794, 8289.212524810133, 8407.95796021016, 8409.075001749803, 8450.788730281793, 8542.060373358925, 8602.612882420306, 3996.760194388346, 4014.698897582693, 4036.1927614476635, 4040.716994897932, 4083.5303940047274, 1931.734713762488, 1976.2193933396227, 1995.2441851171168, 2013.4161093283076, 2020.8181263686338, 905.6784841381346, 938.2728385501155, 950.6577632052456, 1007.4005966449951, 1013.5844303835393, 19790.505237368892, 20129.496762581053, 20961.37115886526, 21194.364169074954, 21670.34988950334, 924.523870206844, 925.6945208081318, 944.2729098594044, 1009.6718566465928, 1029.5418538750184, 10555.525001374957, 11008.0, 11042.668719938414, 11057.944710276397, 11128.554857805759, 565157.0, 573487.0, 574681.0, 597365.0, 603559.0, 118953.0, 125380.37309813393, 125552.0, 131342.34328828295, 131465.0, 1044.1646682653848, 1070.989290107089, 1079.794838980591, 1090.6837017264904, 1093.1691914145306, 18433.354832580797, 18704.251829926652, 18788.624227515647, 18936.52658683544, 18970.43088707341, 42832.5716742841, 49063.999999998574, 49411.75294123506, 50122.99754004972, 50266.0, 68147.97778033433, 76119.0, 76157.0, 76888.0, 79189.85265957694, 7662.6934922604705, 7810.726624568113, 8054.798630034296, 8065.83868322642, 8374.49753014821, 9962.601495940371, 11631.53473861036, 12496.2505249267, 12539.623811285675, 12971.22172669605, 10554.366737995744, 10618.946905265424, 10859.619913302997, 10891.18316126278, 11159.442027898735, 9223.723288301362, 9573.329866909527, 10317.845232321371, 10769.9461502692, 10893.673189804318, 10788.676339709822, 10854.240203185702, 11323.32060076365, 11374.260673056226, 11639.322955720401, 8073.7577872663915, 8112.269895709414, 8219.753407397788, 8354.373421993496, 8457.915420845959, 9350.0, 10812.135029197803, 10899.509522071685, 10972.0, 11005.504752286006], "value": 9569.992983890643, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1377.5454100146953, 1507.7964474795904, 1582.9445969391074, 1820.0990509697701, 1971.9605607887852, 7831.17772633873, 8574.271186949109, 8794.604242809073, 8813.118688131184, 8822.161894620001, 8433.578321083953, 8661.133886611336, 8670.0, 8675.436096653713, 8676.609552570133, 2136.166894910986, 2364.7044119485063, 2400.9639855402183, 2430.8176886733477, 2445.645381419693, 2448.192096608115, 2474.6906636670415, 2480.1443501991785, 2481.5036992601463, 2481.6525686403916, 2398.760123987605, 2450.681411416522, 2451.534208500379, 2453.43570978675, 2455.484348286862, 2128.5742851429777, 2355.163916809542, 2416.8912398942, 2426.187227278846, 2430.8419952703193, 2363.054778088778, 2382.023370418138, 2382.118616112032, 2382.24959137871, 2386.4988352446007, 8422.705205317845, 8440.873386899202, 8466.872996905051, 8467.195616416431, 8469.0, 5074.137396642543, 5185.870353241199, 5310.893782124374, 5315.548178404802, 5315.707636080014, 3468.8785892493643, 3484.9651503484893, 3548.27260411615, 3582.158192824684, 3592.802395868226, 1021.0555236406369, 1026.0457774269926, 1037.9896201037968, 1039.9272050956436, 1041.0578426524007, 11668.416579171093, 11706.297622142698, 11721.882781172164, 11722.413879306087, 11799.233049851819, 4625.838095666635, 4669.883252918704, 4816.54242846929, 4838.903221935612, 4867.221244600847, 9830.803383932282, 9956.900430995742, 9965.900340996643, 9974.152197063186, 9978.201743860474, 4585.174668559691, 5313.57491400687, 5345.1982202669915, 5384.111621582395, 5389.568834493231, 4334.848280310174, 4392.538783427758, 4414.227510185705, 4508.661850361236, 4856.344393506832, 3986.820593073319, 3996.7801770902297, 4042.777647229431, 4081.571434999342, 4116.670666346685, 1323.5896871969528, 1337.518493342396, 1337.6054064051127, 1351.9391627376792, 1351.986480135186, 801.5311043039887, 843.3169133002287, 877.7103555826562, 891.790429249126, 935.4761333653177, 5499.395066542677, 5510.283663123849, 5738.856528586819, 5775.5668324875805, 5925.0, 921.424109931293, 965.6716716316486, 971.4268581536927, 971.8833739951251, 984.640606178754, 3736.3274610570356, 3793.6016718244186, 3869.4582758413844, 3888.202918401721, 3894.5131858517684, 10360.585576577003, 10818.567257309776, 10844.024037836632, 10849.837252441219, 10875.184361172785, 10322.432266225429, 10514.894851051544, 10685.465726713634, 10845.0, 10853.728656783485, 8051.718189863327, 8135.308498777552, 8157.796055098552, 8192.67229310821, 8223.588820558949, 4419.447569053868, 5170.586353091819, 5644.774209031592, 5648.5763567731765, 5899.941000589939, 9465.905340946503, 9746.415215087118, 9759.0, 9776.608935642495, 9797.65708200238, 9133.041030691682, 9421.199198067967, 9538.380005299634, 9978.650747223812, 10037.69886903394, 6778.661066946732, 7153.070100886852, 7591.127020392725, 7608.8478230436185, 7610.238976102345, 8312.87530687028, 8341.332693384637, 8409.915900840913, 8419.95790021046, 8420.989481262288, 7192.96403518, 7448.5158464699625, 7457.925420745723, 7539.849203016019, 7563.243675632392, 7187.389071928945, 7242.529235599669, 7292.270772922878, 7394.186639469546, 7455.291747283999, 5788.768449262151, 6851.3491218334175, 6886.208086070166, 6889.689963951534, 6891.517593768589, 4591.150637132141, 4753.595944344769, 4760.571548560508, 4779.211430113992, 4930.605551555938, 4016.49793775778, 4737.076270127292, 4746.715197088186, 4795.928061079157, 4866.683665561727], "value": 4544.5758874386465, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [309.6934035305047, 315.75686721224656, 322.3038237407201, 328.0257634824572, 328.84051235150963, 5100.724818795301, 5859.209006784083, 5876.970615146924, 5902.586818922677, 5947.00775240295, 5571.824060285639, 5683.772649094031, 5781.65842833652, 5856.150858125569, 5860.882782344351, 4377.255866502686, 4396.252637051709, 4413.867583972486, 4417.1607394595085, 4451.844185453509, 4226.577342265779, 4387.758673272985, 4394.62645675118, 4431.446069241345, 4447.154430911184, 4207.347861081522, 4399.73601583906, 4402.691811573191, 4426.5130835608215, 4458.5318541553, 4387.9122417551625, 4411.1618792429335, 4418.3372494125815, 4430.623397011258, 4433.312836510346, 3182.6976437238427, 3361.9831900840586, 3812.5234345706785, 3817.3319669058083, 3824.770513769183, 5419.349678038621, 5694.943050569483, 5711.400302968212, 5715.228444160027, 5728.856778580569, 5069.366329208849, 5073.568746656539, 5088.0, 5103.387593488768, 5128.538431541179, 5193.948060519384, 5469.890602187934, 6175.116589440219, 6212.738312385988, 6234.532410069263, 3586.5337506124038, 4061.228366610364, 4259.125984566812, 4289.957100428987, 4325.935110973338, 5170.353705786739, 6103.901297766401, 6197.81406557804, 6252.624842509418, 6579.9732123223375, 3856.807159642007, 4313.0, 4349.478062632441, 4362.716423432466, 4369.077605853415, 3670.33933891902, 4021.678265738734, 4175.684659332301, 4229.836794881432, 4447.866564003085, 4581.5339091490405, 4880.266596054022, 5117.360329958755, 5135.794568217304, 5207.0, 5438.293021907127, 6242.469390101802, 6251.718672659741, 6263.217097862767, 6277.8879833709, 1204.3436327201696, 1288.3558220889556, 1449.1305216870005, 1537.4772577323763, 1546.072356586062, 915.231205787142, 1002.7944271424341, 1006.5772375602264, 1019.6889948565758, 1024.6925922223304, 4848.296996935495, 5756.510696590753, 5798.333191682926, 5836.9124463133085, 5846.386129456346, 5871.444067322163, 6231.75072997075, 6393.904091438632, 6435.774747883802, 6450.903236451457, 4472.955270447319, 4632.698874573142, 5638.731285460821, 5774.335951365562, 5785.71071446426, 3216.809780381227, 3366.865325386957, 3383.120388698956, 3401.1837159081642, 3412.6416726243883, 6294.905576416358, 6526.869462610721, 6652.401283884474, 6658.967859981711, 6682.966585167043, 5485.835424937259, 6554.541182117301, 6563.7702680405955, 6582.341765823475, 6590.934090659128, 6116.3577824328695, 6604.45728394562, 6608.570442921195, 6639.601623902581, 6685.899711504234, 5794.942050579441, 6103.481204097524, 6478.708458119301, 6490.740370385131, 6510.088587597835, 6298.370162983665, 6332.113504109337, 6402.871942561216, 6405.711742971671, 6440.774572889926, 6446.227287495976, 6735.831604209934, 6744.69648865812, 6748.628825414453, 6803.115594972622, 3961.306771315066, 4099.139180771952, 4116.897077573085, 4160.62554370096, 4184.225918205142, 5677.687727175045, 6399.232092148785, 6437.356264373525, 6460.289368169404, 6490.415862572391, 5057.671251368796, 6026.306974697965, 6066.787662431793, 6233.844153896189, 6240.469560087444, 5923.911141332969, 6300.243970723543, 6301.369863013845, 6330.056523716494, 6331.9366806331345, 6060.939390606214, 6188.226471691039, 6194.194754681863, 6197.845053873689, 6210.323212732627, 6116.480099191618, 6278.367624417593, 6299.527535434952, 6301.684915754102, 6321.96839015802, 6147.293061298008, 6165.599236049822, 6167.321594624498, 6173.259208894961, 6215.906761398673], "value": 4542.362989746348, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [640.8368810608748, 655.3642966322668, 739.2533541123465, 758.3516093739852, 788.4796034617153, 13581.185128892273, 14252.57242282732, 14737.557873263806, 15176.772348414783, 15211.543653690407, 13849.515266965658, 13893.652658683513, 13943.581692549214, 15125.470608528703, 15718.37126514941, 901.9188273055423, 908.1508789282043, 918.8254231695956, 919.4253591505309, 919.4850883505225, 714.4570126703717, 736.4476642518111, 899.2356496977582, 912.9497877616728, 913.6528119314651, 957.3729207369184, 957.7366224288306, 958.2717134977432, 958.8657587937695, 959.788846453779, 702.7364738223166, 950.3680052764903, 950.9381890177151, 952.2477242978039, 952.7903861150536, 705.5378726933867, 714.0039644695672, 714.1751277274756, 715.3311653603897, 716.0226291112646, 15301.923490382476, 15320.310586023656, 15345.156016419092, 15348.386064557404, 15377.308021138964, 12203.877961220363, 12233.388330583524, 12238.0, 12542.435590398363, 12580.182288151242, 9867.753306167331, 9999.950000250024, 10033.648822291185, 10386.27296089274, 10465.581376744922, 3382.4080785862375, 3428.879989200366, 3468.202313467891, 3499.1602015516346, 3698.6301369863068, 84485.0, 85644.57177714072, 86714.0, 88500.0, 88886.66669999955, 9253.306002049872, 9389.530523473799, 9455.763605909908, 9524.14282714559, 9689.757756056015, 21478.57042859134, 21483.925803709753, 21496.247631333143, 21518.0, 21564.4608884776, 7229.7469588564145, 7391.8152046199275, 7410.184879663233, 7423.554586724814, 7515.286047825448, 4094.733842300241, 4159.10579225468, 4160.895977600524, 4166.604172603597, 4183.100633363842, 2945.3520225550346, 3633.9636603634153, 3649.9270014600093, 3657.5062366580705, 3663.3405986922075, 563.7716724726492, 621.9191505104397, 633.3919437340205, 639.88162189995, 647.9481641468672, 494.99752501237265, 499.9250112483157, 557.6626141184524, 604.431834075976, 614.3089024847047, 7516.962415187889, 7835.6473958671995, 7882.448228623941, 8060.4760690556095, 8227.71203007892, 468.75624675168723, 520.3600812317861, 552.4972275229521, 580.2630659062967, 581.2356750872589, 3632.724393923545, 3661.35926212917, 3673.210259794157, 3722.6277372262557, 3789.2800367930004, 89250.55374723214, 90379.0, 90500.56996580226, 92821.53589232145, 92845.53577232205, 61883.07175392373, 75778.24221757823, 76099.0, 78151.60924195456, 78228.21771782209, 66877.99683004759, 67431.66284168548, 69744.0, 70345.0, 70489.64755176191, 7047.823804404931, 7123.501354905314, 7347.709649052674, 7497.10034795828, 7562.432817538596, 34810.47784283289, 36559.0, 41675.37486937759, 43491.56508434875, 44090.779546102065, 59614.0, 63900.68049659722, 64238.357616425106, 64251.90717737868, 65190.0, 8154.714584989734, 8270.793230169294, 9835.901640983784, 9969.50152492387, 10703.250772445854, 10834.187435942178, 10893.0, 10905.945470272598, 11289.0, 11293.43532823372, 10283.485825708534, 10326.741831453974, 10532.157427405637, 10637.9468102659, 10737.409442480739, 10045.447500387256, 10406.167506599606, 10646.680599582025, 10689.412082335546, 11089.390083545482, 10246.692599222035, 10254.6410875619, 10416.375017498975, 10504.947475262881, 10587.20595955291, 8554.828903422022, 8612.440191387539, 8757.211850933447, 8857.291416686778, 9107.408018478778, 8705.34709896773, 8767.0, 8880.777980550538, 8908.420952638058, 9044.638214471348], "value": 6644.211388016857, "unit": "score"}], "name": "dromaeo_css", "value": 7294.324075836986, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 113.0, 113.0, 113.0, 113.0, 119.0, 111.0, 113.0, 112.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 123.0, 144.0, 180.0, 150.0, 146.0, 130.0, 138.0, 211.0, 148.0], "value": 152.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 184.0, 184.0, 187.0, 187.0, 183.0, 183.0, 183.0, 184.0], "value": 184.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 109.0, 118.0, 83.0, 57.0, 58.0, 78.0, 83.0, 54.0], "value": 79.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 102.0, 141.0, 79.0, 77.0, 76.0, 157.0, 120.0, 118.0, 156.0], "value": 111.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 85.0, 88.0, 84.0, 88.0, 83.0, 87.0, 85.0, 88.0, 84.0], "value": 88.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 93.0, 93.0, 94.0, 93.0, 93.0, 97.0, 93.0], "value": 93.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 103.0, 134.0, 132.0, 85.0, 84.0, 121.0, 133.0, 102.0, 85.0], "value": 106.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 64.0, 58.0, 59.0, 59.0, 59.0, 61.0, 76.0, 59.0, 57.0], "value": 61.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 71.0, 77.0, 73.0, 71.0, 67.0, 67.0, 68.0, 67.0, 66.0], "value": 69.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 82.0, 83.0, 82.0, 82.0, 80.0, 80.0, 82.0, 83.0], "value": 81.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 148.0, 139.0, 136.0, 133.0, 141.0, 134.0, 147.0, 188.0, 245.0], "value": 155.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 171.0, 219.0, 227.0, 168.0, 172.0, 169.0, 169.0, 206.0, 167.0], "value": 183.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 70.0, 70.0, 68.0, 71.0, 69.0, 70.0, 69.0, 66.0, 66.0], "value": 69.1, "unit": "score"}], "name": "kraken", "value": 1550.3, "alertThreshold": 2.0}]} 09:51:30 INFO - Return code: 0 09:51:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:51:30 ERROR - # TBPL SUCCESS # 09:51:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:51:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:51:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:51:30 INFO - 2016-04-19 09:51:30.070 screenresolution[1677:9572] starting screenresolution argv=screenresolution get 09:51:30 INFO - 2016-04-19 09:51:30.072 screenresolution[1677:9572] Display 0: 1600x1200x32@60 09:51:30 INFO - 2016-04-19 09:51:30.078 screenresolution[1678:9575] starting screenresolution argv=screenresolution list 09:51:30 INFO - Available Modes on Display 0 09:51:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:51:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:51:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:51:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:51:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:51:30 INFO - Intel Iris: 09:51:30 INFO - Chipset Model: Intel Iris 09:51:30 INFO - Type: GPU 09:51:30 INFO - Bus: Built-In 09:51:30 INFO - VRAM (Dynamic, Max): 1536 MB 09:51:30 INFO - Vendor: Intel (0x8086) 09:51:30 INFO - Device ID: 0x0a2e 09:51:30 INFO - Revision ID: 0x0009 09:51:30 INFO - Displays: 09:51:30 INFO - Display: 09:51:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:51:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:51:30 INFO - Display Serial Number: 1600x1200 60 09:51:30 INFO - Main Display: Yes 09:51:30 INFO - Mirror: Off 09:51:30 INFO - Online: Yes 09:51:30 INFO - Rotation: Supported 09:51:30 INFO - Return code: 0 09:51:30 INFO - Running post-action listener: _resource_record_post_action 09:51:30 INFO - Running post-run listener: _resource_record_post_run 09:51:31 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 37651968; Write bytes: 690999808; Read time: 386; Write time: 1824 09:51:31 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 375495168; Write bytes: 369530880; Read time: 27967; Write time: 716 09:51:31 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 36685312; Write bytes: 294189568; Read time: 370; Write time: 1055 09:51:31 INFO - Running post-run listener: _upload_blobber_files 09:51:31 INFO - Blob upload gear active. 09:51:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:51:31 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:51:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:51:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:51:31 INFO - (blobuploader) - INFO - Open directory for files ... 09:51:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:51:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:51:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:51:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:51:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:51:32 INFO - (blobuploader) - INFO - Done attempting. 09:51:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:51:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:51:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:51:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:51:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:51:33 INFO - (blobuploader) - INFO - Done attempting. 09:51:33 INFO - (blobuploader) - INFO - Iteration through files over. 09:51:33 INFO - Return code: 0 09:51:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:51:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:51:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f4a1d345d39fc209213f7d9e9a59ad31fd998540c341738f817e2b1bd69a6b96b2dc9168434f897992517a0c3976096938ba5515eb3b7fddf41076483d530d7e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/538975dcc4f2b4e11714a63b85b96558f632261ec85a1026add0c9934df6d6e51b5bba4d67fa4e690230f1d6515513672a84996c69d0db7fc134e9f2e48d3d13"} 09:51:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:51:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:51:33 INFO - Contents: 09:51:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f4a1d345d39fc209213f7d9e9a59ad31fd998540c341738f817e2b1bd69a6b96b2dc9168434f897992517a0c3976096938ba5515eb3b7fddf41076483d530d7e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/538975dcc4f2b4e11714a63b85b96558f632261ec85a1026add0c9934df6d6e51b5bba4d67fa4e690230f1d6515513672a84996c69d0db7fc134e9f2e48d3d13"} 09:51:33 INFO - Running post-run listener: copy_logs_to_upload_dir 09:51:33 INFO - Copying logs to upload dir... 09:51:33 INFO - mkdir: /builds/slave/test/build/upload/logs 09:51:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.035640 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-04-19 09:51:33.906044) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-19 09:51:33.910069) ========= 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.14kr0p1lOk/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.HoVbgyESD3/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f4a1d345d39fc209213f7d9e9a59ad31fd998540c341738f817e2b1bd69a6b96b2dc9168434f897992517a0c3976096938ba5515eb3b7fddf41076483d530d7e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/538975dcc4f2b4e11714a63b85b96558f632261ec85a1026add0c9934df6d6e51b5bba4d67fa4e690230f1d6515513672a84996c69d0db7fc134e9f2e48d3d13"} build_url:https://queue.taskcluster.net/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011019 build_url: 'https://queue.taskcluster.net/v1/task/BoaopXinREeS_ZUM9N_FNg/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f4a1d345d39fc209213f7d9e9a59ad31fd998540c341738f817e2b1bd69a6b96b2dc9168434f897992517a0c3976096938ba5515eb3b7fddf41076483d530d7e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/538975dcc4f2b4e11714a63b85b96558f632261ec85a1026add0c9934df6d6e51b5bba4d67fa4e690230f1d6515513672a84996c69d0db7fc134e9f2e48d3d13"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-19 09:51:33.938102) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 09:51:33.938439) ========= 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.14kr0p1lOk/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.HoVbgyESD3/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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.005407 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 09:51:33.990901) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-19 09:51:33.991261) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-19 09:51:33.992145) ========= ========= Total master_lag: 0.16 =========