builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0007 starttime: 1461181005.18 results: success (0) buildid: 20160420103742 builduid: 074e92339f3b4ac0b4d77d67493e1eef revision: 51135b23fb79d814f0ffd9eb18395de80e3a1584 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-20 12:36:45.176954) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-20 12:36:45.177387) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-20 12:36:45.177671) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-20 12:36:45.195867) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 12:36:45.196175) ========= 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.QqBeeDEWAJ/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.mvSZqAPqa8/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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-20 12:36:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 156M=0s 2016-04-20 12:36:45 (156 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.095565 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 12:36:45.308549) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 12:36:45.308896) ========= 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.QqBeeDEWAJ/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.mvSZqAPqa8/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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.020421 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 12:36:45.357812) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-20 12:36:45.358161) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 51135b23fb79d814f0ffd9eb18395de80e3a1584 --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 51135b23fb79d814f0ffd9eb18395de80e3a1584 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QqBeeDEWAJ/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.mvSZqAPqa8/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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-20 12:36:45,431 truncating revision to first 12 chars 2016-04-20 12:36:45,431 Setting DEBUG logging. 2016-04-20 12:36:45,431 attempt 1/10 2016-04-20 12:36:45,431 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/51135b23fb79?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-20 12:36:46,601 unpacking tar archive at: fx-team-51135b23fb79/testing/mozharness/ program finished with exit code 0 elapsedTime=1.409751 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-20 12:36:46.784965) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-20 12:36:46.785333) ========= script_repo_revision: 51135b23fb79d814f0ffd9eb18395de80e3a1584 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-20 12:36:46.785809) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-20 12:36:46.786140) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-20 12:36:46.800015) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 22 secs) (at 2016-04-20 12:36:46.800421) ========= /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.QqBeeDEWAJ/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.mvSZqAPqa8/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 12:36:46 INFO - MultiFileLogger online at 20160420 12:36:46 in /builds/slave/test 12:36:46 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 12:36:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:36:46 INFO - {'append_to_log': False, 12:36:46 INFO - 'base_work_dir': '/builds/slave/test', 12:36:46 INFO - 'blob_upload_branch': 'Fx-Team', 12:36:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:36:46 INFO - 'branch': 'Fx-Team', 12:36:46 INFO - 'buildbot_json_path': 'buildprops.json', 12:36:46 INFO - 'config_files': ('talos/mac_config.py',), 12:36:46 INFO - 'default_actions': ('clobber', 12:36:46 INFO - 'read-buildbot-config', 12:36:46 INFO - 'download-and-extract', 12:36:46 INFO - 'populate-webroot', 12:36:46 INFO - 'create-virtualenv', 12:36:46 INFO - 'install', 12:36:46 INFO - 'run-tests'), 12:36:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:36:46 INFO - 'download_minidump_stackwalk': True, 12:36:46 INFO - 'download_symbols': 'ondemand', 12:36:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:36:46 INFO - 'tooltool.py': '/tools/tooltool.py', 12:36:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:36:46 INFO - '/tools/misc-python/virtualenv.py')}, 12:36:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:36:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:36:46 INFO - 'installer_path': 'installer.exe', 12:36:46 INFO - 'log_level': 'info', 12:36:46 INFO - 'log_name': 'talos', 12:36:46 INFO - 'log_to_console': True, 12:36:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:36:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:36:46 INFO - 'opt_config_files': (), 12:36:46 INFO - 'pip_index': False, 12:36:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:36:46 INFO - 'cmd': ('bash', 12:36:46 INFO - '-c', 12:36:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:36:46 INFO - 'enabled': True, 12:36:46 INFO - 'halt_on_failure': False, 12:36:46 INFO - 'name': 'check_screen_resolution'},), 12:36:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:36:46 INFO - 'cmd': ('bash', 12:36:46 INFO - '-c', 12:36:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:36:46 INFO - 'enabled': True, 12:36:46 INFO - 'halt_on_failure': False, 12:36:46 INFO - 'name': 'check_screen_resolution'},), 12:36:46 INFO - 'run_cmd_checks_enabled': True, 12:36:46 INFO - 'sps_profile': False, 12:36:46 INFO - 'sps_profile_interval': 0, 12:36:46 INFO - 'suite': 'dromaeojs', 12:36:46 INFO - 'system_bits': '32', 12:36:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:36:46 INFO - 'title': 't-yosemite-r7-0007', 12:36:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:36:46 INFO - 'use_talos_json': True, 12:36:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:36:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:36:46 INFO - 'work_dir': 'build'} 12:36:46 INFO - ##### 12:36:46 INFO - ##### Running clobber step. 12:36:46 INFO - ##### 12:36:46 INFO - Running pre-action listener: _resource_record_pre_action 12:36:46 INFO - Running main action method: clobber 12:36:46 INFO - rmtree: /builds/slave/test/build 12:36:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:36:48 INFO - Running post-action listener: _resource_record_post_action 12:36:48 INFO - ##### 12:36:48 INFO - ##### Running read-buildbot-config step. 12:36:48 INFO - ##### 12:36:48 INFO - Running pre-action listener: _resource_record_pre_action 12:36:48 INFO - Running main action method: read_buildbot_config 12:36:48 INFO - Using buildbot properties: 12:36:48 INFO - { 12:36:48 INFO - "project": "", 12:36:48 INFO - "product": "firefox", 12:36:48 INFO - "script_repo_revision": "production", 12:36:48 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 12:36:48 INFO - "repository": "", 12:36:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 12:36:48 INFO - "buildid": "20160420103742", 12:36:48 INFO - "pgo_build": "False", 12:36:48 INFO - "basedir": "/builds/slave/test", 12:36:48 INFO - "buildnumber": 657, 12:36:48 INFO - "slavename": "t-yosemite-r7-0007", 12:36:48 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 12:36:48 INFO - "platform": "yosemite_r7", 12:36:48 INFO - "scheduler": "tests-fx-team-macosx64-talos", 12:36:48 INFO - "branch": "fx-team", 12:36:48 INFO - "repo_path": "integration/fx-team", 12:36:48 INFO - "revision": "51135b23fb79d814f0ffd9eb18395de80e3a1584", 12:36:48 INFO - "stage_platform": "macosx64", 12:36:48 INFO - "builduid": "074e92339f3b4ac0b4d77d67493e1eef", 12:36:48 INFO - "slavebuilddir": "test" 12:36:48 INFO - } 12:36:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 12:36:48 INFO - Running post-action listener: _resource_record_post_action 12:36:48 INFO - ##### 12:36:48 INFO - ##### Running download-and-extract step. 12:36:48 INFO - ##### 12:36:48 INFO - Running pre-action listener: _resource_record_pre_action 12:36:48 INFO - Running main action method: download_and_extract 12:36:48 INFO - mkdir: /builds/slave/test/build/tests 12:36: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')]} 12:36:48 INFO - https://queue.taskcluster.net/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:36:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 12:36:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 12:36:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/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 12:36:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/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 12:36:49 INFO - Downloaded 1199 bytes. 12:36:49 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 12:36:49 INFO - Using the following test package requirements: 12:36:49 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 12:36:49 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:36:49 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 12:36:49 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:36:49 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 12:36:49 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:36:49 INFO - u'jsshell-mac.zip'], 12:36:49 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:36:49 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 12:36:49 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 12:36:49 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:36:49 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 12:36:49 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:36:49 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 12:36:49 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:36:49 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 12:36:49 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 12:36:49 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 12:36:49 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:36: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')]} 12:36:49 INFO - https://queue.taskcluster.net/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:36:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 12:36:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 12:36:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/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 12:36:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/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 12:36:50 INFO - Downloaded 18096157 bytes. 12:36:50 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'] 12:36:50 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 12:36:54 INFO - Return code: 0 12:36:54 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 12:36:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:36:54 INFO - https://queue.taskcluster.net/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:36:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 12:36:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 12:36:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/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 12:36:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/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 12:36:55 INFO - Downloaded 18096157 bytes. 12:36:55 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'] 12:36:55 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 12:36:59 INFO - Return code: 0 12:36:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:36:59 INFO - https://queue.taskcluster.net/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:36:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 12:36:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 12:36:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/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 12:36:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/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 12:37:00 INFO - Downloaded 10845952 bytes. 12:37:00 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'] 12:37:00 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 12:37:00 INFO - Return code: 0 12:37:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:37:00 INFO - https://queue.taskcluster.net/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 12:37:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 12:37:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 12:37:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 12:37:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/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 12:38:18 INFO - Downloaded 110020515 bytes. 12:38:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 12:38:18 INFO - mkdir: /builds/slave/test/properties 12:38:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:38:18 INFO - Writing to file /builds/slave/test/properties/build_url 12:38:18 INFO - Contents: 12:38:18 INFO - build_url:https://queue.taskcluster.net/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 12:38:21 INFO - Running post-action listener: _resource_record_post_action 12:38:21 INFO - Running post-action listener: set_extra_try_arguments 12:38:21 INFO - ##### 12:38:21 INFO - ##### Running populate-webroot step. 12:38:21 INFO - ##### 12:38:21 INFO - Running pre-action listener: _resource_record_pre_action 12:38:21 INFO - Running main action method: populate_webroot 12:38:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:38:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:38:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:38:21 INFO - '--tppagecycles', 12:38:21 INFO - '7'], 12:38:21 INFO - 'tests': ['tsvgm']}}, 12:38:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:38:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:38:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:38:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:38:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:38:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:38:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:38:21 INFO - 'talos_options': ['--disable-e10s'], 12:38:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:38:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:38:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:38:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:38:21 INFO - 'talos_options': ['--disable-e10s'], 12:38:21 INFO - 'tests': ['damp', 'tps']}, 12:38:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:38:21 INFO - 'tests': ['damp', 'tps']}, 12:38:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:38:21 INFO - 'tests': ['dromaeo_dom']}, 12:38:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:38:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:38:21 INFO - 'tests': ['a11yr', 12:38:21 INFO - 'ts_paint', 12:38:21 INFO - 'tpaint', 12:38:21 INFO - 'sessionrestore', 12:38:21 INFO - 'sessionrestore_no_auto_restore', 12:38:21 INFO - 'tabpaint']}, 12:38:21 INFO - 'other-e10s': {'tests': ['a11yr', 12:38:21 INFO - 'ts_paint', 12:38:21 INFO - 'tpaint', 12:38:21 INFO - 'sessionrestore', 12:38:21 INFO - 'sessionrestore_no_auto_restore', 12:38:21 INFO - 'tabpaint']}, 12:38:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:38:21 INFO - 'ts_paint', 12:38:21 INFO - 'tpaint', 12:38:21 INFO - 'sessionrestore', 12:38:21 INFO - 'sessionrestore_no_auto_restore', 12:38:21 INFO - 'tabpaint']}, 12:38:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:38:21 INFO - 'ts_paint', 12:38:21 INFO - 'tpaint', 12:38:21 INFO - 'sessionrestore', 12:38:21 INFO - 'sessionrestore_no_auto_restore', 12:38:21 INFO - 'tabpaint']}, 12:38:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:38:21 INFO - 'tests': ['a11yr', 12:38:21 INFO - 'ts_paint', 12:38:21 INFO - 'tpaint', 12:38:21 INFO - 'sessionrestore', 12:38:21 INFO - 'sessionrestore_no_auto_restore', 12:38:21 INFO - 'tabpaint']}, 12:38:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:38:21 INFO - 'tests': ['a11yr', 12:38:21 INFO - 'ts_paint', 12:38:21 INFO - 'tpaint', 12:38:21 INFO - 'sessionrestore', 12:38:21 INFO - 'sessionrestore_no_auto_restore', 12:38:21 INFO - 'tabpaint']}, 12:38:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:38:21 INFO - 'tests': ['tsvgx', 12:38:21 INFO - 'tsvgr_opacity', 12:38:21 INFO - 'tart', 12:38:21 INFO - 'tscrollx', 12:38:21 INFO - 'cart']}, 12:38:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:38:21 INFO - 'tsvgr_opacity', 12:38:21 INFO - 'tart', 12:38:21 INFO - 'tscrollx', 12:38:21 INFO - 'cart']}, 12:38:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:38:21 INFO - 'talos_options': ['--disable-e10s'], 12:38:21 INFO - 'tests': ['tp5o']}, 12:38:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:38:21 INFO - 'tests': ['tp5o']}, 12:38:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:38:21 INFO - 'talos_options': ['--disable-e10s', 12:38:21 INFO - '--xperf_path', 12:38:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:38:21 INFO - 'tests': ['tp5n']}, 12:38:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:38:21 INFO - 'talos_options': ['--xperf_path', 12:38:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:38:21 INFO - 'tests': ['tp5n']}}, 12:38:21 INFO - 'talos.zip': {'path': '', 12:38:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:38:21 INFO - Running post-action listener: _resource_record_post_action 12:38:21 INFO - ##### 12:38:21 INFO - ##### Running create-virtualenv step. 12:38:21 INFO - ##### 12:38:21 INFO - Running pre-action listener: _resource_record_pre_action 12:38:21 INFO - Running main action method: create_virtualenv 12:38:21 INFO - Creating virtualenv /builds/slave/test/build/venv 12:38:21 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 12:38:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:38:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:38:21 INFO - Using real prefix '/tools/python27' 12:38:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:38:22 INFO - Installing distribute.............................................................................................................................................................................................done. 12:38:25 INFO - Installing pip.................done. 12:38:25 INFO - Return code: 0 12:38:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:38:25 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')]} 12:38:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:25 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')]} 12:38:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:25 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 0x10635da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10636d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9b149c7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061fce00>, '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 0x106025db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061f7b20>, '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.QqBeeDEWAJ/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.mvSZqAPqa8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 12:38:25 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 12:38:25 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 12:38:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QqBeeDEWAJ/Render', 12:38:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:38:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:38:25 INFO - 'HOME': '/Users/cltbld', 12:38:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:38:25 INFO - 'LOGNAME': 'cltbld', 12:38:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:25 INFO - 'MOZ_NO_REMOTE': '1', 12:38:25 INFO - 'NO_EM_RESTART': '1', 12:38:25 INFO - 'PAGER': '/bin/cat', 12:38:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:38:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:25 INFO - 'PWD': '/builds/slave/test', 12:38:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:38:25 INFO - 'SHELL': '/bin/bash', 12:38:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mvSZqAPqa8/Listeners', 12:38:25 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 12:38:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:38:25 INFO - 'USER': 'cltbld', 12:38:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:38:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:38:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:25 INFO - 'XPC_FLAGS': '0x0', 12:38:25 INFO - 'XPC_SERVICE_NAME': '0', 12:38:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:38:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:25 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 12:38:25 INFO - Cleaning up... 12:38:25 INFO - Return code: 0 12:38:25 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 12:38:25 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')]} 12:38:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:25 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')]} 12:38:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:25 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 0x10635da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10636d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9b149c7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061fce00>, '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 0x106025db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061f7b20>, '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.QqBeeDEWAJ/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.mvSZqAPqa8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 12:38:25 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 12:38:25 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 12:38:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QqBeeDEWAJ/Render', 12:38:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:38:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:38:25 INFO - 'HOME': '/Users/cltbld', 12:38:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:38:25 INFO - 'LOGNAME': 'cltbld', 12:38:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:25 INFO - 'MOZ_NO_REMOTE': '1', 12:38:25 INFO - 'NO_EM_RESTART': '1', 12:38:25 INFO - 'PAGER': '/bin/cat', 12:38:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:38:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:25 INFO - 'PWD': '/builds/slave/test', 12:38:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:38:25 INFO - 'SHELL': '/bin/bash', 12:38:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mvSZqAPqa8/Listeners', 12:38:25 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 12:38:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:38:25 INFO - 'USER': 'cltbld', 12:38:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:38:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:38:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:25 INFO - 'XPC_FLAGS': '0x0', 12:38:25 INFO - 'XPC_SERVICE_NAME': '0', 12:38:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:38:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:25 INFO - Downloading/unpacking psutil>=0.7.1 12:38:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:38:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:38:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:38:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:38:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:38:29 INFO - Installing collected packages: psutil 12:38:29 INFO - Running setup.py install for psutil 12:38:29 INFO - building 'psutil._psutil_osx' extension 12:38:29 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 12:38:29 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 12:38:29 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 12:38:29 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 12:38:30 INFO - building 'psutil._psutil_posix' extension 12:38:30 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 12:38:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:38:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:38:30 INFO - ^ 12:38:30 INFO - 1 warning generated. 12:38:30 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 12:38:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:38:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:38:30 INFO - Successfully installed psutil 12:38:30 INFO - Cleaning up... 12:38:30 INFO - Return code: 0 12:38:30 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 12:38:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10635da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10636d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9b149c7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061fce00>, '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 0x106025db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061f7b20>, '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.QqBeeDEWAJ/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.mvSZqAPqa8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 12:38:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 12:38:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 12:38:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QqBeeDEWAJ/Render', 12:38:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:38:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:38:30 INFO - 'HOME': '/Users/cltbld', 12:38:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:38:30 INFO - 'LOGNAME': 'cltbld', 12:38:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:30 INFO - 'MOZ_NO_REMOTE': '1', 12:38:30 INFO - 'NO_EM_RESTART': '1', 12:38:30 INFO - 'PAGER': '/bin/cat', 12:38:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:38:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:30 INFO - 'PWD': '/builds/slave/test', 12:38:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:38:30 INFO - 'SHELL': '/bin/bash', 12:38:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mvSZqAPqa8/Listeners', 12:38:30 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 12:38:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:38:30 INFO - 'USER': 'cltbld', 12:38:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:38:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:38:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:30 INFO - 'XPC_FLAGS': '0x0', 12:38:30 INFO - 'XPC_SERVICE_NAME': '0', 12:38:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:38:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:30 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:38:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:38:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:38:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:32 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:38:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:38:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 12:38:33 INFO - Installing collected packages: mozsystemmonitor 12:38:33 INFO - Running setup.py install for mozsystemmonitor 12:38:33 INFO - Successfully installed mozsystemmonitor 12:38:33 INFO - Cleaning up... 12:38:33 INFO - Return code: 0 12:38:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:38: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')]} 12:38:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38: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')]} 12:38:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:33 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 0x10635da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10636d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9b149c7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061fce00>, '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 0x106025db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061f7b20>, '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.QqBeeDEWAJ/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.mvSZqAPqa8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 12:38:33 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 12:38:33 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 12:38:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QqBeeDEWAJ/Render', 12:38:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:38:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:38:33 INFO - 'HOME': '/Users/cltbld', 12:38:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:38:33 INFO - 'LOGNAME': 'cltbld', 12:38:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:33 INFO - 'MOZ_NO_REMOTE': '1', 12:38:33 INFO - 'NO_EM_RESTART': '1', 12:38:33 INFO - 'PAGER': '/bin/cat', 12:38:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:38:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:33 INFO - 'PWD': '/builds/slave/test', 12:38:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:38:33 INFO - 'SHELL': '/bin/bash', 12:38:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mvSZqAPqa8/Listeners', 12:38:33 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 12:38:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:38:33 INFO - 'USER': 'cltbld', 12:38:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:38:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:38:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:33 INFO - 'XPC_FLAGS': '0x0', 12:38:33 INFO - 'XPC_SERVICE_NAME': '0', 12:38:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:38:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:33 INFO - Downloading/unpacking blobuploader==1.2.4 12:38:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:38:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:38:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:36 INFO - Downloading blobuploader-1.2.4.tar.gz 12:38:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:38:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:38:36 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 12:38:36 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 12:38:36 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 12:38:36 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 12:38:36 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 12:38:36 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 12:38:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:38:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:38:36 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 12:38:36 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 12:38:36 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 12:38:36 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 12:38:36 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 12:38:36 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 12:38:37 INFO - Downloading docopt-0.6.1.tar.gz 12:38:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:38:37 INFO - Installing collected packages: blobuploader, requests, docopt 12:38:37 INFO - Running setup.py install for blobuploader 12:38:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:38:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:38:37 INFO - Running setup.py install for requests 12:38:38 INFO - Running setup.py install for docopt 12:38:38 INFO - Successfully installed blobuploader requests docopt 12:38:38 INFO - Cleaning up... 12:38:38 INFO - Return code: 0 12:38:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:38: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')]} 12:38:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38: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')]} 12:38:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:38 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 0x10635da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10636d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9b149c7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061fce00>, '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 0x106025db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061f7b20>, '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.QqBeeDEWAJ/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.mvSZqAPqa8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 12:38:38 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 12:38:38 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 12:38:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QqBeeDEWAJ/Render', 12:38:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:38:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:38:38 INFO - 'HOME': '/Users/cltbld', 12:38:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:38:38 INFO - 'LOGNAME': 'cltbld', 12:38:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:38 INFO - 'MOZ_NO_REMOTE': '1', 12:38:38 INFO - 'NO_EM_RESTART': '1', 12:38:38 INFO - 'PAGER': '/bin/cat', 12:38:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:38:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:38 INFO - 'PWD': '/builds/slave/test', 12:38:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:38:38 INFO - 'SHELL': '/bin/bash', 12:38:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mvSZqAPqa8/Listeners', 12:38:38 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 12:38:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:38:38 INFO - 'USER': 'cltbld', 12:38:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:38:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:38:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:38 INFO - 'XPC_FLAGS': '0x0', 12:38:38 INFO - 'XPC_SERVICE_NAME': '0', 12:38:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:38:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:38:38 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-G298wF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:38:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:38:38 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-Fo7zrK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:38:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:38:38 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-3HQLxA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:38:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:38:38 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-ApeCo4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:38:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:38:38 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-lE81kQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:38:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:38:39 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-5sr_wG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:38:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:38:39 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-OAvNdw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:38:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:38:39 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-IOE0j4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:38:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:38:39 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-I5DgXz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:38:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:38:39 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-ffCHgP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:38:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:38:39 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-are_VK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:38:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:38:39 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-V7pWyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:38:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:38:39 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-ccZUt9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:38:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:38:39 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-qljM8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:38:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:38:39 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-wUHtzp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:38:40 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-etZkuw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:38:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:38:40 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-3M9xjd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:38:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:38:40 INFO - Running setup.py install for manifestparser 12:38:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:38:40 INFO - Running setup.py install for mozcrash 12:38:40 INFO - Running setup.py install for mozdebug 12:38:40 INFO - Running setup.py install for mozdevice 12:38:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:38:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:38:40 INFO - Running setup.py install for mozfile 12:38:41 INFO - Running setup.py install for mozhttpd 12:38:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:38:41 INFO - Running setup.py install for mozinfo 12:38:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:38:41 INFO - Running setup.py install for mozInstall 12:38:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:38:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:38:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:38:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:38:41 INFO - Running setup.py install for mozleak 12:38:41 INFO - Running setup.py install for mozlog 12:38:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:38:41 INFO - Running setup.py install for moznetwork 12:38:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:38:41 INFO - Running setup.py install for mozprocess 12:38:42 INFO - Running setup.py install for mozprofile 12:38:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:38:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:38:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:38:42 INFO - Running setup.py install for mozrunner 12:38:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:38:42 INFO - Running setup.py install for mozscreenshot 12:38:42 INFO - Running setup.py install for moztest 12:38:42 INFO - Running setup.py install for mozversion 12:38:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:38:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:38:42 INFO - Cleaning up... 12:38:42 INFO - Return code: 0 12:38:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:38:42 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')]} 12:38:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:42 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')]} 12:38:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:42 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 0x10635da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10636d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9b149c7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061fce00>, '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 0x106025db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061f7b20>, '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.QqBeeDEWAJ/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.mvSZqAPqa8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 12:38:42 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 12:38:42 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 12:38:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QqBeeDEWAJ/Render', 12:38:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:38:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:38:42 INFO - 'HOME': '/Users/cltbld', 12:38:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:38:42 INFO - 'LOGNAME': 'cltbld', 12:38:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:42 INFO - 'MOZ_NO_REMOTE': '1', 12:38:42 INFO - 'NO_EM_RESTART': '1', 12:38:42 INFO - 'PAGER': '/bin/cat', 12:38:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:38:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:42 INFO - 'PWD': '/builds/slave/test', 12:38:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:38:42 INFO - 'SHELL': '/bin/bash', 12:38:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mvSZqAPqa8/Listeners', 12:38:42 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 12:38:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:38:42 INFO - 'USER': 'cltbld', 12:38:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:38:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:38:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:42 INFO - 'XPC_FLAGS': '0x0', 12:38:42 INFO - 'XPC_SERVICE_NAME': '0', 12:38:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:38:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:38:43 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-HliofP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:38:43 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)) 12:38:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:38:43 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-Qa7hl1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:38:43 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)) 12:38:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:38:43 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-R3MMMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:38:43 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)) 12:38:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:38:43 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-Nl82z6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:38:43 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)) 12:38:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:38:43 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-M1ZNAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:38:43 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)) 12:38:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:38:43 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-T2LEbB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:38:43 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)) 12:38:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:38:43 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-E95MgQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:38:43 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)) 12:38:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:38:43 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-45pjK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:38:43 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)) 12:38:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:38:43 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-1XCR6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:38:43 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)) 12:38:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:38:44 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-cJyy7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:38:44 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)) 12:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:38:44 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-SYZHY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:38:44 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)) 12:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:38:44 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-05bSwU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:38:44 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)) 12:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:38:44 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-ct1ThW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:38:44 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)) 12:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:38:44 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-_CMlH8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:38:44 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)) 12:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:38:44 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-BUy1Ov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:38:44 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)) 12:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:38:44 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-YcoxXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:38:44 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)) 12:38:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:38:44 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-wNDGys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:38:44 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)) 12:38:44 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)) 12:38:44 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)) 12:38:44 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)) 12:38:44 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)) 12:38:44 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)) 12:38:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:38:44 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 12:38:44 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 12:38:44 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 12:38:44 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 12:38:44 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 12:38:44 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 12:38:47 INFO - Downloading blessings-1.6.tar.gz 12:38:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:38:47 INFO - Installing collected packages: blessings 12:38:47 INFO - Running setup.py install for blessings 12:38:47 INFO - Successfully installed blessings 12:38:47 INFO - Cleaning up... 12:38:47 INFO - Return code: 0 12:38:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:38:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:38:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:38:47 INFO - Reading from file tmpfile_stdout 12:38:47 INFO - Current package versions: 12:38:47 INFO - blessings == 1.6 12:38:47 INFO - blobuploader == 1.2.4 12:38:47 INFO - docopt == 0.6.1 12:38:47 INFO - manifestparser == 1.1 12:38:47 INFO - mozInstall == 1.12 12:38:47 INFO - mozcrash == 0.17 12:38:47 INFO - mozdebug == 0.1 12:38:47 INFO - mozdevice == 0.48 12:38:47 INFO - mozfile == 1.2 12:38:47 INFO - mozhttpd == 0.7 12:38:47 INFO - mozinfo == 0.9 12:38:47 INFO - mozleak == 0.1 12:38:47 INFO - mozlog == 3.1 12:38:47 INFO - moznetwork == 0.27 12:38:47 INFO - mozprocess == 0.22 12:38:47 INFO - mozprofile == 0.28 12:38:47 INFO - mozrunner == 6.11 12:38:47 INFO - mozscreenshot == 0.1 12:38:47 INFO - mozsystemmonitor == 0.0 12:38:47 INFO - moztest == 0.7 12:38:47 INFO - mozversion == 1.4 12:38:47 INFO - psutil == 3.1.1 12:38:47 INFO - requests == 1.2.3 12:38:47 INFO - wsgiref == 0.1.2 12:38:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:38:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:47 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 0x10635da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10636d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9b149c7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061fce00>, '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 0x106025db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061f7b20>, '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.QqBeeDEWAJ/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.mvSZqAPqa8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 12:38:47 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 12:38:47 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 12:38:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QqBeeDEWAJ/Render', 12:38:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:38:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:38:47 INFO - 'HOME': '/Users/cltbld', 12:38:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:38:47 INFO - 'LOGNAME': 'cltbld', 12:38:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:47 INFO - 'MOZ_NO_REMOTE': '1', 12:38:47 INFO - 'NO_EM_RESTART': '1', 12:38:47 INFO - 'PAGER': '/bin/cat', 12:38:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:38:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:47 INFO - 'PWD': '/builds/slave/test', 12:38:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:38:47 INFO - 'SHELL': '/bin/bash', 12:38:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mvSZqAPqa8/Listeners', 12:38:47 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 12:38:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:38:47 INFO - 'USER': 'cltbld', 12:38:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:38:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:38:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:47 INFO - 'XPC_FLAGS': '0x0', 12:38:47 INFO - 'XPC_SERVICE_NAME': '0', 12:38:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:38:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:48 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)) 12:38:48 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)) 12:38:48 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)) 12:38:48 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)) 12:38:48 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)) 12:38:48 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)) 12:38:48 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)) 12:38:48 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)) 12:38:48 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)) 12:38:48 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)) 12:38:48 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)) 12:38:48 INFO - Cleaning up... 12:38:48 INFO - Return code: 0 12:38:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:38: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')]} 12:38:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38: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')]} 12:38:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10635da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10636d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9b149c7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061fce00>, '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 0x106025db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061f7b20>, '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.QqBeeDEWAJ/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.mvSZqAPqa8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 12:38: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', 'jsonschema'] in /builds/slave/test/build 12:38: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 jsonschema 12:38:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QqBeeDEWAJ/Render', 12:38:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:38:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:38:48 INFO - 'HOME': '/Users/cltbld', 12:38:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:38:48 INFO - 'LOGNAME': 'cltbld', 12:38:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:48 INFO - 'MOZ_NO_REMOTE': '1', 12:38:48 INFO - 'NO_EM_RESTART': '1', 12:38:48 INFO - 'PAGER': '/bin/cat', 12:38:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:38:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:48 INFO - 'PWD': '/builds/slave/test', 12:38:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:38:48 INFO - 'SHELL': '/bin/bash', 12:38:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mvSZqAPqa8/Listeners', 12:38:48 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 12:38:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:38:48 INFO - 'USER': 'cltbld', 12:38:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:38:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:38:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:48 INFO - 'XPC_FLAGS': '0x0', 12:38:48 INFO - 'XPC_SERVICE_NAME': '0', 12:38:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:38:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:48 INFO - Downloading/unpacking jsonschema 12:38:48 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 12:38:48 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 12:38:48 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 12:38:48 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 12:38:48 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 12:38:48 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 12:38:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:38:50 INFO - Downloading/unpacking functools32 (from jsonschema) 12:38:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:38:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:38:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:51 INFO - Downloading functools32-3.2.3-2.tar.gz 12:38:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:38:51 INFO - warning: no files found matching '*.txt' 12:38:51 INFO - no previously-included directories found matching 'build' 12:38:51 INFO - no previously-included directories found matching 'dist' 12:38:51 INFO - no previously-included directories found matching '.git*' 12:38:51 INFO - Installing collected packages: jsonschema, functools32 12:38:51 INFO - Running setup.py install for functools32 12:38:51 INFO - warning: no files found matching '*.txt' 12:38:51 INFO - no previously-included directories found matching 'build' 12:38:51 INFO - no previously-included directories found matching 'dist' 12:38:51 INFO - no previously-included directories found matching '.git*' 12:38:51 INFO - Successfully installed jsonschema functools32 12:38:51 INFO - Cleaning up... 12:38:51 INFO - Return code: 0 12:38:51 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:38:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:38:51 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 0x10635da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10636d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9b149c7f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061fce00>, '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 0x106025db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061f7b20>, '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.QqBeeDEWAJ/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.mvSZqAPqa8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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 12:38:51 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 12:38:51 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 12:38:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QqBeeDEWAJ/Render', 12:38:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:38:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:38:51 INFO - 'HOME': '/Users/cltbld', 12:38:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:38:51 INFO - 'LOGNAME': 'cltbld', 12:38:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:51 INFO - 'MOZ_NO_REMOTE': '1', 12:38:51 INFO - 'NO_EM_RESTART': '1', 12:38:51 INFO - 'PAGER': '/bin/cat', 12:38:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:38:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:51 INFO - 'PWD': '/builds/slave/test', 12:38:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:38:51 INFO - 'SHELL': '/bin/bash', 12:38:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mvSZqAPqa8/Listeners', 12:38:51 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 12:38:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:38:51 INFO - 'USER': 'cltbld', 12:38:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:38:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:38:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:51 INFO - 'XPC_FLAGS': '0x0', 12:38:51 INFO - 'XPC_SERVICE_NAME': '0', 12:38:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:38:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:51 INFO - Downloading/unpacking flake8 12:38:51 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 12:38:51 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 12:38:51 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 12:38:51 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 12:38:51 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 12:38:51 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 12:38:54 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:38:54 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:38:54 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 12:38:54 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 12:38:54 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 12:38:54 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 12:38:54 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 12:38:54 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 12:38:54 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:38:54 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:38:54 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 12:38:54 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 12:38:54 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 12:38:54 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 12:38:54 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 12:38:54 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 12:38:54 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:38:54 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 12:38:54 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 12:38:54 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 12:38:54 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 12:38:54 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 12:38:54 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 12:38:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:38:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:38:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:38:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:38:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:38:55 INFO - no previously-included directories found matching 'docs/_build' 12:38:55 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:38:55 INFO - Running setup.py install for pep8 12:38:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:38:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:38:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:38:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:38:55 INFO - no previously-included directories found matching 'docs/_build' 12:38:55 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:38:55 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:38:55 INFO - Cleaning up... 12:38:55 INFO - Return code: 0 12:38:55 INFO - Running post-action listener: _resource_record_post_action 12:38:55 INFO - Running post-action listener: _start_resource_monitoring 12:38:55 INFO - Starting resource monitoring. 12:38:55 INFO - ##### 12:38:55 INFO - ##### Running install step. 12:38:55 INFO - ##### 12:38:55 INFO - Running pre-action listener: _resource_record_pre_action 12:38:55 INFO - Running main action method: install 12:38:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:38:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:38:56 INFO - Reading from file tmpfile_stdout 12:38:56 INFO - Detecting whether we're running mozinstall >=1.0... 12:38:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:38:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:38:56 INFO - Reading from file tmpfile_stdout 12:38:56 INFO - Output received: 12:38:56 INFO - Usage: mozinstall [options] installer 12:38:56 INFO - Options: 12:38:56 INFO - -h, --help show this help message and exit 12:38:56 INFO - -d DEST, --destination=DEST 12:38:56 INFO - Directory to install application into. [default: 12:38:56 INFO - "/builds/slave/test"] 12:38:56 INFO - --app=APP Application being installed. [default: firefox] 12:38:56 INFO - mkdir: /builds/slave/test/build/application 12:38:56 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'] 12:38:56 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 12:39:29 INFO - Reading from file tmpfile_stdout 12:39:29 INFO - Output received: 12:39:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:39:29 INFO - Running post-action listener: _resource_record_post_action 12:39:29 INFO - ##### 12:39:29 INFO - ##### Running run-tests step. 12:39:29 INFO - ##### 12:39:29 INFO - Running pre-action listener: _resource_record_pre_action 12:39:29 INFO - Running main action method: run_tests 12:39:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:39:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:39:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:39:29 INFO - 2016-04-20 12:39:29.401 screenresolution[1916:14888] starting screenresolution argv=screenresolution get 12:39:29 INFO - 2016-04-20 12:39:29.403 screenresolution[1916:14888] Display 0: 1600x1200x32@60 12:39:29 INFO - 2016-04-20 12:39:29.409 screenresolution[1917:14890] starting screenresolution argv=screenresolution list 12:39:29 INFO - Available Modes on Display 0 12:39:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:39:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:39:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:39:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:39:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:39:29 INFO - Intel Iris: 12:39:29 INFO - Chipset Model: Intel Iris 12:39:29 INFO - Type: GPU 12:39:29 INFO - Bus: Built-In 12:39:29 INFO - VRAM (Dynamic, Max): 1536 MB 12:39:29 INFO - Vendor: Intel (0x8086) 12:39:29 INFO - Device ID: 0x0a2e 12:39:29 INFO - Revision ID: 0x0009 12:39:29 INFO - Displays: 12:39:29 INFO - Display: 12:39:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:39:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:39:29 INFO - Display Serial Number: 1600x1200 60 12:39:29 INFO - Main Display: Yes 12:39:29 INFO - Mirror: Off 12:39:29 INFO - Online: Yes 12:39:29 INFO - Rotation: Supported 12:39:29 INFO - Return code: 0 12:39:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:39:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:39:29 INFO - Python 2.7.3 12:39:29 INFO - Return code: 0 12:39:29 INFO - grabbing minidump binary from tooltool 12:39: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')]} 12:39:29 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 0x1061fce00>, '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 0x106025db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1061f7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:39:29 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 12:39:29 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 12:39:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:39:29 INFO - Return code: 0 12:39:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:39:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:39:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:39:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:39:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:39:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:39:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:39:30 INFO - Return code: 0 12:39:30 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/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0007', '--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 12:39:30 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/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0007 --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 12:39:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QqBeeDEWAJ/Render', 12:39:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:39:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:39:30 INFO - 'HOME': '/Users/cltbld', 12:39:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:39:30 INFO - 'LOGNAME': 'cltbld', 12:39:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:39:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:39:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:39:30 INFO - 'MOZ_NO_REMOTE': '1', 12:39:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:39:30 INFO - 'NO_EM_RESTART': '1', 12:39:30 INFO - 'PAGER': '/bin/cat', 12:39:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:39:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:39:30 INFO - 'PWD': '/builds/slave/test', 12:39:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:39:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:39:30 INFO - 'SHELL': '/bin/bash', 12:39:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mvSZqAPqa8/Listeners', 12:39:30 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 12:39:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:39:30 INFO - 'USER': 'cltbld', 12:39:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:39:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:39:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:39:30 INFO - 'XPC_FLAGS': '0x0', 12:39:30 INFO - 'XPC_SERVICE_NAME': '0', 12:39:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:39:30 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/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0007', '--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 12:39:31 INFO - mozversion application_buildid: 20160420103742 12:39:31 INFO - mozversion application_changeset: 51135b23fb79d814f0ffd9eb18395de80e3a1584 12:39:31 INFO - mozversion application_display_name: Nightly 12:39:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:39:31 INFO - mozversion application_name: Firefox 12:39:31 INFO - mozversion application_remotingname: firefox 12:39:31 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 12:39:31 INFO - mozversion application_vendor: Mozilla 12:39:31 INFO - mozversion application_version: 48.0a1 12:39:31 INFO - mozversion platform_buildid: 20160420103742 12:39:31 INFO - mozversion platform_changeset: 51135b23fb79d814f0ffd9eb18395de80e3a1584 12:39:31 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 12:39:31 INFO - mozversion platform_version: 48.0a1 12:39:31 INFO - using testdate: 1461181171 12:39:31 INFO - actual date: 1461181171 12:39:31 INFO - starting webserver on 'localhost:52331' 12:39:31 INFO - SUITE-START | Running 2 tests 12:39:31 INFO - TEST-START | dromaeo_css 12:39:31 INFO - operating with platform_type : mac_ 12:39:31 INFO - Initialising browser for dromaeo_css test... 12:39:31 INFO - TEST-INFO | started process 1935 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmp7sJQLp/profile http://localhost:52331/getInfo.html) 12:39:33 INFO - PROCESS | 1935 | __metrics Screen width/height:1600/1200 12:39:33 INFO - PROCESS | 1935 | colorDepth:24 12:39:33 INFO - PROCESS | 1935 | Browser inner width/height: 1024/689 12:39:33 INFO - PROCESS | 1935 | __metrics 12:39:33 INFO - PROCESS | 1935 | 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" 12:39:33 ERROR - PROCESS | 1935 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:39:34 INFO - TEST-INFO | 1935: exit 0 12:39:34 INFO - Browser initialized. 12:39:34 INFO - Running cycle 1/1 for dromaeo_css test... 12:39:34 INFO - TEST-INFO | started process 1937 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmp7sJQLp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:39:45 INFO - PROCESS | 1937 | RSS: Main: 238018560 12:39:45 INFO - PROCESS | 1937 | 12:40:37 INFO - PROCESS | 1937 | 1461181237475 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] 12:42:48 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:52331/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52331/tests/dromaeo/cssquery-ext.html) 12:42:49 INFO - PROCESS | 1937 | RSS: Main: 347901952 12:42:49 INFO - PROCESS | 1937 | 12:45:52 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:52331/tests/dromaeo/cssquery-ext.html (next: http://localhost:52331/tests/dromaeo/cssquery-jquery.html) 12:45:53 INFO - PROCESS | 1937 | RSS: Main: 379224064 12:45:53 INFO - PROCESS | 1937 | 12:48:55 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:52331/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52331/tests/dromaeo/cssquery-mootools.html) 12:48:55 INFO - PROCESS | 1937 | RSS: Main: 379113472 12:48:55 INFO - PROCESS | 1937 | 12:51:58 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:52331/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52331/tests/dromaeo/cssquery-prototype.html) 12:51:58 INFO - PROCESS | 1937 | RSS: Main: 375668736 12:51:58 INFO - PROCESS | 1937 | 12:55:02 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:52331/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52331/tests/dromaeo/cssquery-yui.html) 12:55:02 INFO - PROCESS | 1937 | RSS: Main: 346959872 12:55:02 INFO - PROCESS | 1937 | 12:58:05 INFO - PROCESS | 1937 | Cycle 1(1): loaded http://localhost:52331/tests/dromaeo/cssquery-yui.html (next: http://localhost:52331/tests/dromaeo/cssquery-dojo.html) 12:58:05 INFO - PROCESS | 1937 | RSS: Main: 382357504 12:58:05 INFO - PROCESS | 1937 | 12:58:05 INFO - PROCESS | 1937 | __start_tp_report 12:58:05 INFO - PROCESS | 1937 | _x_x_mozilla_page_load 12:58:05 INFO - PROCESS | 1937 | _x_x_mozilla_page_load_details 12:58:05 INFO - PROCESS | 1937 | |i|pagename|runs| 12:58:05 INFO - PROCESS | 1937 | |0;dojo.html;3280.5899262592175;3368.275820698551;3393.8472768725405;3393.8472768725405;3429.8799542016036;5532.3914369419335;5546.639468434549;5561.221428999938;5565.499105080547;5620.606557540974;5626.099824028156;5659.292588426447;5677.063284558043;5773;5785.855353616162;5060.443351231362;5107.821226257091;5142.357205349332;5143.871403214912;5202.219667049935;5208.687478751268;5219.843404697846;5248.6063545234065;5249.842504724846;5309.362876454831;4329.701089673085;5052.418971818214;5054.823081192159;5164;5190.92213616796;4811.422629284508;5164.664296820694;5174.43081261061;5174.7671354788745;5217.765200565946;5058.822941197077;5090.5927525798215;5134.768935397916;5161.66449180802;5162.664426812245;5406.945930540723;5515.338159420855;5533.778648854041;5543.334799824047;5552.611317207797;6093.847653808691;6118.082287656843;6126.387361263838;6129.1419201311855;6143.293521245069;6154.323024467305;6195.938040619626;6199.535034872358;6208.068789681539;6213.192285002966;5577.6653400796085;5635.3519345275;5652.519535839499;5686.1755045518585;5743.655380677173;6275.654838983808;6289.654069026156;6300.936990630126;6301.306856245809;6326.810195694136;6003.699815009232;6009.729562169713;6016.338202797689;6018.127371531104;6022.819315420545;5990.101484777231;6105.938940610537;6131.601445906002;6176;6176.69116544171;5725.456081672234;6100.816975490742;6109.1010297800785;6115.602485838406;6135;6123.432740139189;6143.109249158847;6167.907481387782;6170.907436388457;6181.660008699475;4779.689320194177;4848.345473361122;4866.6350023747655;4883.706977581356;4909.877253068702;4349.282368409177;4368.279233926367;4404.933925991113;4436;4442.400275962704;5619.185218143346;5638.859028524238;5645.633033852787;5662.235598194191;5678.545716342765;6046.3349031606485;6108.068892179526;6227.906581401283;6228.5951413158;6238.469730072995;4901.191303434894;5600.131979543177;5661.971690141523;5665.263515742927;5676.829695109153;5291.920621190685;5322.068637988336;5329.973350133225;5330.706811125426;5357.383900851374;6157.630542167484;6222.782202622887;6288.874222515524;6300.464460520907;6322.0200868865395;5771.567132465166;6018.939810601838;6129.695011270001;6185.41238582334;6264.248290205204;6300.149479820166;6309.337519560564;6312.210973628296;6317.368263173646;6321.431071203614;5968.462838344396;6097.101247990999;6124.203853499017;6134.846628834315;6150.138980542817;6131.233595800525;6194.752209911553;6196.535259855439;6206.131141640264;6224.439800417803;5947.04847224442;5977.491913187428;5992.61048031894;5999.940000600112;6019.518438524995;4505.324201369821;5789.3921138279875;5931.110333450017;5980.37206093354;6019.638821670714;5836.358000619844;5845.41545845412;5914.822555323347;5951.22634057587;5953.583249172516;5986.850328741816;6011.789587364421;6023.427774361428;6063.242094738158;6078.513718902565;5621.184928185472;5765.164051212467;5894.705264736832;5907.2911250650195;5920.674362910081 12:58:05 INFO - PROCESS | 1937 | |1;ext.html;520.1106108554371;526.2448386565277;539.059341449171;557.297804765995;578.2656026845904;24559.386015349624;37937.810310948444;38947.22105557887;39016.609833901726;39053.02367440812;40705.57530486434;41220.793896030555;41303.38044929328;41420.79289603548;41514;8075.838483230332;8579.785505362353;9827.557759900821;9836.360636558637;9880.851787223197;8673.522956237404;11537.365444900528;11789.941050294736;11790.292582445038;11820.231684940494;11354.545818167264;11465.369404682739;11490.425478726072;11500.482478288497;11553.826692599618;8563.314934805201;11293.604723834667;11345.602903898407;11454.713632159179;11649.941750291278;11312.660620181408;11479.942600287028;11481.311121332747;11482.770344593062;11485.310881347063;38854.41718374227;39462;39467.802660986796;39482.60517394818;39491.01272468182;3234.660360662144;3445.63820798815;3503.649635036476;3574.6067932527403;3877.3408520551293;29510.409791804042;30027.399452011057;30144.09567712972;30178;30206.84896575503;2717.932051698723;2750.5324094903713;2753.325435268372;2866.613007244037;2926.4586051580313;502272;503056.48471757775;504193;504694;504783;63384;63735.36264637387;64096.269891660704;64178.6791066051;64255.35744642494;61764.382356176764;62012.379876201565;69405;70937.2906270941;71167.6441617799;16412.75380869288;16639.6672066558;16674.33302667904;16717.414890479016;16719.414820481226;11728.296302221723;12011.579594714143;12028.578999734968;12037.87962120385;12699.428525716365;17351.392701255394;18731.854771208527;20419.8979005104;20492.282770102975;20527.4868128295;6605.405513503808;7889.317632005146;7980.720674776471;8029.3175080118845;8051.838963220703;892.4689809563299;961.6682244625594;998.7253505286104;1025.4206373399006;1056.6671498477954;29105;29176.708232917823;29742.702572973994;29836.403271934872;31260;1005.1556692378438;1105.8728246251785;1117.8658560972735;1145.8109411946934;1195.7429152732204;15580.987235829634;15592.064476131241;15605;15697.215139242991;15964;637551.8122409359;640619;641971;642222;642427;61619.383806161364;62134;62388;62414.68792656008;62541;1313.6518822512041;1437.698083402497;1475.4098360655823;1478.2534819915875;1494.7010597880467;30003.399932001674;30247;30426.087217383512;30517.389652207276;30560.08319750411;42896.92757681083;51335.9465621371;51975;52555;52647.68380790511;458485;469739;470106.6494667505;470800;472076;35991.28017439689;43131.13737725165;43162.78418607887;44295.557044429144;45174.77412612916;46735;48032.27951580799;48190.5180948186;49441;49646.75176624094;37739.245215096096;39556;39899.20201595894;39986.33250635065;40528.3920741195;32127.51808722918;38676.613233868426;38942.6105738939;39038.21923561456;39308;38121.618783811806;38591.80704096462;39041.2191756169;39103.41344879886;39125.41311880267;33430;33764.81076585142;34314.852772084385;34383.828080859435;34538.82730586331;25367.492650147262;25431.6185257225;25637.61543576885;25642.743572564035;26125 12:58:05 INFO - PROCESS | 1937 | |2;jquery.html;400.86971734186386;404.736921001349;409.3000968344131;412.0831150689714;420.89267236854596;15649.921750391246;19085.809141908576;20388.592228155427;20402.183912643493;20421.897890510547;19780.901095494537;19858.900705496453;21196.78803211971;21217.151313947423;21234.575308493822;5776.740046697898;6068.453839154476;6070.119832624267;6074.848128796793;6080.96959515204;5756.65460072397;5971.4327138921735;6028.4574388305045;6073.757049718006;6089.604175728586;5802.12968054791;5948.256467941507;5982.162497250389;5982.79060232892;5987.9101813472835;5932.495737862287;5992.49063829575;6026.78906238284;6037.366076561942;6057.363976782441;5187.118189907725;5193.195054766517;5222;5238.97380513095;5240.842774716726;25316.87341563299;25463.61804572933;25574.87212563944;25731.61402578963;26099.347516312246;1995.740553728006;2433.561958847407;2720.7279272072833;2980;2987.357718090621;9490.715278541655;14289;14535.78196327056;14885;15077.698446031127;1720.7504911787846;1731.7142671459194;1761.9030953297563;1819.699749541324;1828.2686925229882;88164;124262.37868810598;124528.37735811353;124654;126758.36620816929;11599.88400116005;11736.237144585575;11749.941250293865;11759.823602645967;11844.940775296069;15035.699286014218;15328.846711532742;15347.61630959235;15386.153761542999;15427.768583471257;8767.605457754416;8797.868031979526;8813.867791983126;8823.735287941372;8907.376483646083;8631.43895646781;8638.956805215934;8739.169778870997;8750.431221970679;8759.605817738217;4558.156741002858;4643.3963584733765;4649.465311489222;4653.418322709661;4695.577398034125;1904.2668572599373;1952.3752399232394;1957.7063440484042;1974.9802501975084;1996.4010796760913;923.4828496042238;965.9806803864024;975.4692334818465;990.7424069741919;996.6362277768562;20326.288579899632;20467.590648186953;20538.075786589638;20604.27885024017;20851.061702223436;896.2561074308309;962.4985023363575;991.9553620087113;994.3834822410151;1032.3444612671008;9167.945686246167;10029.398236105857;10847.186461015297;10865.293755905841;10916.345019298868;573647;577215;594085;598427.0078649579;600540;100206.49896750615;114035;137596.6240337604;137833;140511.05656019147;1015.8374660054503;1033.5762337441613;1035.8342665173536;1057.4871187474068;1061.5010944855906;15803.288852001917;16441.753373699165;18008.459746207634;18278.543036424195;18479.98360090208;39175.41236881506;47675;48140;48809.75595122002;49775.75112124416;56211.156832646724;72106.27893720995;72188.27811721816;72717.27282727105;73879;8169.918300817153;8289.958550207451;8362.331013519026;8377.204165604258;8700.086490918362;11993.820092698443;12448.190867593577;12620.810687839874;12882.03384746129;12940;10991;11227.663170104583;11317.151213658844;11369.943150284196;11389.088872889983;8627.110352332864;9310.975792662937;10756.946215268874;10813.513391897226;11158.776824463628;10842.78314433691;11343.092552595937;11417.771644566896;11624.88375116238;11696.47365868555;8172.387070969582;8364.874526882224;8372.706955256537;8418.663253469793;8562;10037;10566.735831604272;11160;11440.82838757436;11490.712732181762 12:58:05 INFO - PROCESS | 1937 | |3;mootools.html;1428;1608.1396452897698;1610.7020201262765;1887.669657809883;2012.185065048655;7887.014123234596;8182.30450411715;8601.053884072748;8803.691870784523;8832.690855820048;8617.95691021544;8620.956895215531;8810.823783524342;8810.867836982434;8866;2152.9354119376444;2373.9169129080487;2430.5260474207535;2474.2329877737957;2474.616434452663;2469.987650061756;2496.213692686798;2496.4507808282237;2496.7253602103756;2503.173952595648;2406.4104294447884;2440.2923152285816;2459.434330104077;2464.37158524576;2467.925962221136;2019.3840878532042;2138.9144434222612;2363.6572696959015;2425.993212816679;2440.7437219092094;2440.7681270279295;2463.9014439422403;2464.3099932019063;2466.099873546161;2470.8393954393086;8575.82848343036;8637.956810215972;8663.39356245063;8669.393142480028;8676.305895528405;5101.387833459971;5343.438938911398;5345.144776835727;5356.6518176318805;5365.168398898177;3244.772865899388;3374.4094783412806;3480.2598700649673;3489.162600975773;3489.755717099804;1046.2467023742893;1049.9055085042382;1050.0969166516788;1057.6985559115615;1066.2642776484201;11550.36472993985;11612.36132012739;11629.476673549627;11638.941805291004;11663.94168029163;4741.865552318417;4746.738929358849;4801.5438533339275;4912.9508704913205;4919.311296418504;9816.705498835046;9911.504424778732;9926.2059035277;9933.701988940344;9963.501824908726;5366.651167674088;5367.677939323653;5389.380221274525;5391.757370918318;5425.321834770653;4279.522211569197;4380.6057454829215;4516.503184649686;4568.223402021665;4921.557059864558;3800.1069748609057;3927.273454410943;4159.147374788161;4177.26897792885;4193.790310484464;1130.5251794246435;1346.118292518402;1366.9248191349373;1368.4799776085224;1373.2378529915793;791.4855344026429;813.2599334605599;816.2775943290112;836.957987305796;873.9825203495894;5413.242146099549;5593.972030139823;5711.114777209539;5803.796867109715;5871.853203669943;846.2679781988659;938.7700013496737;943.2925306020485;987.5335127335901;1001.8196724589498;3633.418653015505;3781.7920014398924;3789.052736815796;3815.465834783133;3839.6352346527037;10247.538860751283;10469.84295235572;10572.41851698164;10597.364158150536;10621.468926553642;10518.105960993249;10567.260291779656;10647.14822814173;10688.358698478116;10698.839517407245;7994.805324542274;8034.758957231293;8188.959055204686;8235.547044912588;8241;5452.146270268128;5493.725313734377;5550.306211723535;5564.666120032811;5698.20225168485;9565.899921508837;9621.374610650286;9758.609655613696;9844.901550984685;9902.900970990198;8084.39367047462;9419.576119074794;9976.501174941079;9981.800363992534;10032.398056116657;8109.7161599343735;8121.715739949073;8153.0216374033125;8223.712170074019;8284.917150828414;7542.698292068256;7789.688412463438;8077.838443230985;8301.003879534253;8308.418410711434;7302.488825782144;7374.705011799468;7381.815454613463;7384.261573842573;7466.178720340649;7270.054892863894;7274.418046556369;7350.338469537767;7407.666655000646;7445.590492522963;6852.486063545353;6947.166340039025;6971.268016858156;7023.964880175567;7038.2961703829205;4528.547145285445;4575.17646823575;4773.737444440589;4817.132916075;4863.781129849236;4814.470408255019;4846.297286893451;4881.9023619528125;4936.111499930047;5095.617828662791 12:58:05 INFO - PROCESS | 1937 | |4;prototype.html;320.5176209804245;323.9611246650401;332.80198282022195;333.3599488981156;337.85978818790204;5771.653700777951;5794.739236734346;5838.211841401409;5861.589688721791;5866.64800111993;4699.778057704995;4835.371401717772;5499.532539734128;5510.862228444291;5748.912212691766;3971.5631280559187;4021.351245989145;4363.956360436387;4394.780260986954;4441.600255976961;4271.910662780986;4408.845690400821;4422.513523512411;4429.55704429556;4435.445569303837;4387.7367357958465;4402.625776808975;4422.18189634917;4437.5387197985465;4438.822447102112;4143.81352839123;4248.235317642823;4256.978325201961;4431.7340959542535;4441.200583894898;3189.6012998375204;3383.8308084595674;3660.396034654279;3834.9424758628643;3858.2606349491894;5336.706481143536;5570.774429625476;5677;5747.856303592402;5764.337101233328;4869.2939523768855;5090.567301779353;5126.282320475137;5131.7434128293435;5154.896902061938;5154.768035438415;6131.478824299896;6138.741557980603;6208.96895515524;6230.8130756076935;4156.459660244149;4283.507396649394;4285.507166675841;4289;4299;5566.526845218162;6161.7535298588;6214.316425193225;6447.677616119222;6453.740860865233;4221.704480686384;4226.429432026698;4249.235137675187;4257.87226383209;4267;3438.329525742506;3657.670809627093;3994.8601798936897;4286.485621725351;4323.416338794223;4796.688215265996;4874.417303282155;5034.597232221414;5056.115179843513;5074.06129865976;6209.627422354673;6256.186695729617;6256.74973001084;6266.154069200691;6285.560010799291;1232.8150777383464;1446.3636000159895;1469.6913648133798;1473.5284708893266;1474.3439169569654;929.789811734386;956.8756061711933;969.2585172343163;969.2682025071026;978.6770365779275;5631;5687.516561092353;5697.800576979786;5706.2011318414625;5709.229254050733;5765.827025189251;6104.694765261719;6319.842003949846;6326.873462530723;6328.46208072319;5422.701751403711;5664.660120392789;5676.460736230051;5715.485606295453;5818.0981947798155;3309.5697559317136;3319.618243901934;3324.2852786650988;3335.981731052727;3369.029117966017;6322.71547780351;6604.273529911706;6610.735570577219;6627.900581491282;6820.556663816936;6675.566088204251;6687.431568316748;6688.7324507019175;6691.197056353269;6709.127813384229;5906.645601263938;6785.626790526469;6785.89821152693;6790.5246632736225;6795.2185498667295;5427.918581221364;5787.508061814625;6050.21347224858;6508.8047358579315;6540.411362977355;6111.908321375272;6451.741930322923;6453.9354606453335;6463.61218326883;6464.935350646433;6564.4420224281475;6577.375149360803;6749.662516874235;6811.352921472452;6812.557183783038;3926.2736393767245;4209.1581683664;4223.894402639959;4241.53343132249;4246.44796176495;6228.449783623291;6434.86249525684;6472.546921715435;6485;6489.415952564292;5921.555883308855;5974.61165024272;6137.3862613738265;6243.531735119794;6254.718537665907;5963.254593175853;6243.43809057187;6254.278704171011;6262.843428914313;6263.3736626337;5617.915786213483;6073;6124.846878828065;6129.850925222834;6136.539759518143;5402.729863506887;6218.19163508741;6240.937590624218;6251.374862513712;6255.343188965275;5830.737616807169;6062.575619706578;6121.755129794758;6141.355157708376;6175.166352542349 12:58:05 INFO - PROCESS | 1937 | |5;yui.html;681.8738533371327;745.6309126982142;747.2378174262255;750.9511881727688;757.9962100189498;14607.707845843077;15082.245887705612;17234.310627574883;18023.72964405532;18033.819661803376;16230.350785968576;16356;16437.42469013585;16440.588985275375;16460.09469479178;735.0150797930771;749.8387846612964;914.6387177065055;916.0564618443015;918.4030380252856;736.0799001248439;877.2236570635002;909.2634965677822;909.56340956341;909.9772505687345;956.4548207521715;963.6530848894392;965.903409659032;967.5114067395955;969.2391472693937;744.5383862005591;788.4086934798901;911.4531281231275;943.8725772020758;945.6028468043438;600.3966014155745;713.1086142322098;714.2322003845886;714.6462501061956;716.2765606737181;15639.609009774733;15735.370585176579;15741.92129039359;15755.842441575553;15762.76355854663;12399.442025108801;12456.439460224312;12505.624831254985;12749.426275817517;12780.808287875689;9990;10222;10238;10283.588656453807;10757.892421075845;3431.9828400858087;3508.4912687660403;3511.227529943434;3511.2450823072904;3579.427291633352;85704.57147714222;85830.570847146;86005;87461.12538874593;87668;9336.903952995772;9471.573779179953;9587.712368628952;9758.560864761102;9824;21312.680309795367;21554.13783448676;21570.24504142379;21595.568088638454;21618.783812161677;7208.783736487913;7404.888926666104;7498.250174982458;7574.431917606202;7609.467337286447;4087.8364865405647;4095.5494895561465;4118.855840045584;4187.623113919762;4233.534311225762;3606.4590311453494;3714;3720.4233343831747;3722.683571896365;3722.683571896419;592.2330581896455;598.2760859360136;623.8814625221194;656.6979189572754;669.8191488298132;537.4115343698644;588.5232961301359;619.0868469008177;626.2157119606212;634.6477704873747;7524.661390237453;7772.417068719868;7808.297253247235;8012.31895288907;8023.558704271321;552.7236381809096;577.8295402856168;601.5909181756448;605.7879742090233;610.7801191571031;3722.7021838252876;3756.248750249906;3770.208256266215;3772.7924964127237;3871.399933010388;88215.11784882197;90143.54928225317;90790;90868.1826363469;90956.54521727482;76884.61557692176;77571.22428775753;78242.60878695684;78281;78733.21266787374;66778.98483704637;67479.98780018304;67694.66152669206;68010;69168.999999999;7366.7053317866685;7390.334869861738;7425.331720145213;7438.330550250287;7513.962430188033;43674.56325436705;45203;45623;45872;46009.76995115137;41621.37567936544;52487.47512524826;53370.19944700755;61005.47486312878;61186.38813611807;10877.891221087688;11232.55069797199;11285.450245443924;11309.434528273718;11584.362860042778;9564.426134431957;11508.676502202354;12040.096992725405;12041.879581204075;12555;9182.403143795633;11147.665570032912;11313.264637798517;11334;11337.093032557541;11397.487113079766;11593;11627.069834413396;11739.941300293785;11861.406929653655;11060.557577696802;11222.775544489228;11326.263792853439;11414.429278536207;11529.250598711056;9473.857892131762;9561.61753529851;9598.85601715961;9973;9983;9321.533923303943;9363.250939924927;9550.283728720235;9565.665201717906;9580.856287155837 12:58:05 INFO - PROCESS | 1937 | __end_tp_report 12:58:05 INFO - PROCESS | 1937 | __start_cc_report 12:58:05 INFO - PROCESS | 1937 | _x_x_mozilla_cycle_collect,1107 12:58:05 INFO - PROCESS | 1937 | __end_cc_report 12:58:05 INFO - PROCESS | 1937 | __startTimestamp1461182285954__endTimestamp 12:58:05 INFO - PROCESS | 1937 | 12:58:05 INFO - PROCESS | 1937 | ------- Summary: start ------- 12:58:05 INFO - PROCESS | 1937 | Number of tests: 6 12:58:05 INFO - PROCESS | 1937 | 12:58:05 INFO - PROCESS | 1937 | [#0] dojo.html Cycles:165 Average:5652.76 Median:5845.42 stddev:640.85 (11.0%) stddev-sans-first:615.21 12:58:05 INFO - PROCESS | 1937 | Values: 3280.6 3368.3 3393.8 3393.8 3429.9 5532.4 5546.6 5561.2 5565.5 5620.6 5626.1 5659.3 5677.1 5773.0 5785.9 5060.4 5107.8 5142.4 5143.9 5202.2 5208.7 5219.8 5248.6 5249.8 5309.4 4329.7 5052.4 5054.8 5164.0 5190.9 4811.4 5164.7 5174.4 5174.8 5217.8 5058.8 5090.6 5134.8 5161.7 5162.7 5406.9 5515.3 5533.8 5543.3 5552.6 6093.8 6118.1 6126.4 6129.1 6143.3 6154.3 6195.9 6199.5 6208.1 6213.2 5577.7 5635.4 5652.5 5686.2 5743.7 6275.7 6289.7 6300.9 6301.3 6326.8 6003.7 6009.7 6016.3 6018.1 6022.8 5990.1 6105.9 6131.6 6176.0 6176.7 5725.5 6100.8 6109.1 6115.6 6135.0 6123.4 6143.1 6167.9 6170.9 6181.7 4779.7 4848.3 4866.6 4883.7 4909.9 4349.3 4368.3 4404.9 4436.0 4442.4 5619.2 5638.9 5645.6 5662.2 5678.5 6046.3 6108.1 6227.9 6228.6 6238.5 4901.2 5600.1 5662.0 5665.3 5676.8 5291.9 5322.1 5330.0 5330.7 5357.4 6157.6 6222.8 6288.9 6300.5 6322.0 5771.6 6018.9 6129.7 6185.4 6264.2 6300.1 6309.3 6312.2 6317.4 6321.4 5968.5 6097.1 6124.2 6134.8 6150.1 6131.2 6194.8 6196.5 6206.1 6224.4 5947.0 5977.5 5992.6 5999.9 6019.5 4505.3 5789.4 5931.1 5980.4 6019.6 5836.4 5845.4 5914.8 5951.2 5953.6 5986.9 6011.8 6023.4 6063.2 6078.5 5621.2 5765.2 5894.7 5907.3 5920.7 12:58:05 INFO - PROCESS | 1937 | 12:58:05 INFO - PROCESS | 1937 | [#1] ext.html Cycles:180 Average:68494.62 Median:38634.21 stddev:144693.26 (374.5%) stddev-sans-first:145009.16 12:58:05 INFO - PROCESS | 1937 | Values: 520.1 526.2 539.1 557.3 578.3 24559.4 37937.8 38947.2 39016.6 39053.0 40705.6 41220.8 41303.4 41420.8 41514.0 8075.8 8579.8 9827.6 9836.4 9880.9 8673.5 11537.4 11789.9 11790.3 11820.2 11354.5 11465.4 11490.4 11500.5 11553.8 8563.3 11293.6 11345.6 11454.7 11649.9 11312.7 11479.9 11481.3 11482.8 11485.3 38854.4 39462.0 39467.8 39482.6 39491.0 3234.7 3445.6 3503.6 3574.6 3877.3 29510.4 30027.4 30144.1 30178.0 30206.8 2717.9 2750.5 2753.3 2866.6 2926.5 502272.0 503056.5 504193.0 504694.0 504783.0 63384.0 63735.4 64096.3 64178.7 64255.4 61764.4 62012.4 69405.0 70937.3 71167.6 16412.8 16639.7 16674.3 16717.4 16719.4 11728.3 12011.6 12028.6 12037.9 12699.4 17351.4 18731.9 20419.9 20492.3 20527.5 6605.4 7889.3 7980.7 8029.3 8051.8 892.5 961.7 998.7 1025.4 1056.7 29105.0 29176.7 29742.7 29836.4 31260.0 1005.2 1105.9 1117.9 1145.8 1195.7 15581.0 15592.1 15605.0 15697.2 15964.0 637551.8 640619.0 641971.0 642222.0 642427.0 61619.4 62134.0 62388.0 62414.7 62541.0 1313.7 1437.7 1475.4 1478.3 1494.7 30003.4 30247.0 30426.1 30517.4 30560.1 42896.9 51335.9 51975.0 52555.0 52647.7 458485.0 469739.0 470106.6 470800.0 472076.0 35991.3 43131.1 43162.8 44295.6 45174.8 46735.0 48032.3 48190.5 49441.0 49646.8 37739.2 39556.0 39899.2 39986.3 40528.4 32127.5 38676.6 38942.6 39038.2 39308.0 38121.6 38591.8 39041.2 39103.4 39125.4 33430.0 33764.8 34314.9 34383.8 34538.8 25367.5 25431.6 25637.6 25642.7 26125.0 12:58:05 INFO - PROCESS | 1937 | 12:58:05 INFO - PROCESS | 1937 | [#2] jquery.html Cycles:180 Average:34368.57 Median:25653.24 stddev:98243.17 (383.0%) stddev-sans-first:98485.66 12:58:05 INFO - PROCESS | 1937 | Values: 400.9 404.7 409.3 412.1 420.9 15649.9 19085.8 20388.6 20402.2 20421.9 19780.9 19858.9 21196.8 21217.2 21234.6 5776.7 6068.5 6070.1 6074.8 6081.0 5756.7 5971.4 6028.5 6073.8 6089.6 5802.1 5948.3 5982.2 5982.8 5987.9 5932.5 5992.5 6026.8 6037.4 6057.4 5187.1 5193.2 5222.0 5239.0 5240.8 25316.9 25463.6 25574.9 25731.6 26099.3 1995.7 2433.6 2720.7 2980.0 2987.4 9490.7 14289.0 14535.8 14885.0 15077.7 1720.8 1731.7 1761.9 1819.7 1828.3 88164.0 124262.4 124528.4 124654.0 126758.4 11599.9 11736.2 11749.9 11759.8 11844.9 15035.7 15328.8 15347.6 15386.2 15427.8 8767.6 8797.9 8813.9 8823.7 8907.4 8631.4 8639.0 8739.2 8750.4 8759.6 4558.2 4643.4 4649.5 4653.4 4695.6 1904.3 1952.4 1957.7 1975.0 1996.4 923.5 966.0 975.5 990.7 996.6 20326.3 20467.6 20538.1 20604.3 20851.1 896.3 962.5 992.0 994.4 1032.3 9167.9 10029.4 10847.2 10865.3 10916.3 573647.0 577215.0 594085.0 598427.0 600540.0 100206.5 114035.0 137596.6 137833.0 140511.1 1015.8 1033.6 1035.8 1057.5 1061.5 15803.3 16441.8 18008.5 18278.5 18480.0 39175.4 47675.0 48140.0 48809.8 49775.8 56211.2 72106.3 72188.3 72717.3 73879.0 8169.9 8290.0 8362.3 8377.2 8700.1 11993.8 12448.2 12620.8 12882.0 12940.0 10991.0 11227.7 11317.2 11369.9 11389.1 8627.1 9311.0 10756.9 10813.5 11158.8 10842.8 11343.1 11417.8 11624.9 11696.5 8172.4 8364.9 8372.7 8418.7 8562.0 10037.0 10566.7 11160.0 11440.8 11490.7 12:58:05 INFO - PROCESS | 1937 | 12:58:05 INFO - PROCESS | 1937 | [#3] mootools.html Cycles:180 Average:5661.64 Median:5015.86 stddev:3148.26 (62.8%) stddev-sans-first:3141.02 12:58:05 INFO - PROCESS | 1937 | Values: 1428.0 1608.1 1610.7 1887.7 2012.2 7887.0 8182.3 8601.1 8803.7 8832.7 8618.0 8621.0 8810.8 8810.9 8866.0 2152.9 2373.9 2430.5 2474.2 2474.6 2470.0 2496.2 2496.5 2496.7 2503.2 2406.4 2440.3 2459.4 2464.4 2467.9 2019.4 2138.9 2363.7 2426.0 2440.7 2440.8 2463.9 2464.3 2466.1 2470.8 8575.8 8638.0 8663.4 8669.4 8676.3 5101.4 5343.4 5345.1 5356.7 5365.2 3244.8 3374.4 3480.3 3489.2 3489.8 1046.2 1049.9 1050.1 1057.7 1066.3 11550.4 11612.4 11629.5 11638.9 11663.9 4741.9 4746.7 4801.5 4913.0 4919.3 9816.7 9911.5 9926.2 9933.7 9963.5 5366.7 5367.7 5389.4 5391.8 5425.3 4279.5 4380.6 4516.5 4568.2 4921.6 3800.1 3927.3 4159.1 4177.3 4193.8 1130.5 1346.1 1366.9 1368.5 1373.2 791.5 813.3 816.3 837.0 874.0 5413.2 5594.0 5711.1 5803.8 5871.9 846.3 938.8 943.3 987.5 1001.8 3633.4 3781.8 3789.1 3815.5 3839.6 10247.5 10469.8 10572.4 10597.4 10621.5 10518.1 10567.3 10647.1 10688.4 10698.8 7994.8 8034.8 8189.0 8235.5 8241.0 5452.1 5493.7 5550.3 5564.7 5698.2 9565.9 9621.4 9758.6 9844.9 9902.9 8084.4 9419.6 9976.5 9981.8 10032.4 8109.7 8121.7 8153.0 8223.7 8284.9 7542.7 7789.7 8077.8 8301.0 8308.4 7302.5 7374.7 7381.8 7384.3 7466.2 7270.1 7274.4 7350.3 7407.7 7445.6 6852.5 6947.2 6971.3 7024.0 7038.3 4528.5 4575.2 4773.7 4817.1 4863.8 4814.5 4846.3 4881.9 4936.1 5095.6 12:58:05 INFO - PROCESS | 1937 | 12:58:05 INFO - PROCESS | 1937 | [#4] prototype.html Cycles:180 Average:5060.95 Median:5731.67 stddev:1594.13 (27.8%) stddev-sans-first:1558.39 12:58:05 INFO - PROCESS | 1937 | Values: 320.5 324.0 332.8 333.4 337.9 5771.7 5794.7 5838.2 5861.6 5866.6 4699.8 4835.4 5499.5 5510.9 5748.9 3971.6 4021.4 4364.0 4394.8 4441.6 4271.9 4408.8 4422.5 4429.6 4435.4 4387.7 4402.6 4422.2 4437.5 4438.8 4143.8 4248.2 4257.0 4431.7 4441.2 3189.6 3383.8 3660.4 3834.9 3858.3 5336.7 5570.8 5677.0 5747.9 5764.3 4869.3 5090.6 5126.3 5131.7 5154.9 5154.8 6131.5 6138.7 6209.0 6230.8 4156.5 4283.5 4285.5 4289.0 4299.0 5566.5 6161.8 6214.3 6447.7 6453.7 4221.7 4226.4 4249.2 4257.9 4267.0 3438.3 3657.7 3994.9 4286.5 4323.4 4796.7 4874.4 5034.6 5056.1 5074.1 6209.6 6256.2 6256.7 6266.2 6285.6 1232.8 1446.4 1469.7 1473.5 1474.3 929.8 956.9 969.3 969.3 978.7 5631.0 5687.5 5697.8 5706.2 5709.2 5765.8 6104.7 6319.8 6326.9 6328.5 5422.7 5664.7 5676.5 5715.5 5818.1 3309.6 3319.6 3324.3 3336.0 3369.0 6322.7 6604.3 6610.7 6627.9 6820.6 6675.6 6687.4 6688.7 6691.2 6709.1 5906.6 6785.6 6785.9 6790.5 6795.2 5427.9 5787.5 6050.2 6508.8 6540.4 6111.9 6451.7 6453.9 6463.6 6464.9 6564.4 6577.4 6749.7 6811.4 6812.6 3926.3 4209.2 4223.9 4241.5 4246.4 6228.4 6434.9 6472.5 6485.0 6489.4 5921.6 5974.6 6137.4 6243.5 6254.7 5963.3 6243.4 6254.3 6262.8 6263.4 5617.9 6073.0 6124.8 6129.9 6136.5 5402.7 6218.2 6240.9 6251.4 6255.3 5830.7 6062.6 6121.8 6141.4 6175.2 12:58:05 INFO - PROCESS | 1937 | 12:58:05 INFO - PROCESS | 1937 | [#5] yui.html Cycles:180 Average:17913.01 Median:61095.93 stddev:25034.52 (41.0%) stddev-sans-first:25071.31 12:58:05 INFO - PROCESS | 1937 | Values: 681.9 745.6 747.2 751.0 758.0 14607.7 15082.2 17234.3 18023.7 18033.8 16230.4 16356.0 16437.4 16440.6 16460.1 735.0 749.8 914.6 916.1 918.4 736.1 877.2 909.3 909.6 910.0 956.5 963.7 965.9 967.5 969.2 744.5 788.4 911.5 943.9 945.6 600.4 713.1 714.2 714.6 716.3 15639.6 15735.4 15741.9 15755.8 15762.8 12399.4 12456.4 12505.6 12749.4 12780.8 9990.0 10222.0 10238.0 10283.6 10757.9 3432.0 3508.5 3511.2 3511.2 3579.4 85704.6 85830.6 86005.0 87461.1 87668.0 9336.9 9471.6 9587.7 9758.6 9824.0 21312.7 21554.1 21570.2 21595.6 21618.8 7208.8 7404.9 7498.3 7574.4 7609.5 4087.8 4095.5 4118.9 4187.6 4233.5 3606.5 3714.0 3720.4 3722.7 3722.7 592.2 598.3 623.9 656.7 669.8 537.4 588.5 619.1 626.2 634.6 7524.7 7772.4 7808.3 8012.3 8023.6 552.7 577.8 601.6 605.8 610.8 3722.7 3756.2 3770.2 3772.8 3871.4 88215.1 90143.5 90790.0 90868.2 90956.5 76884.6 77571.2 78242.6 78281.0 78733.2 66779.0 67480.0 67694.7 68010.0 69169.0 7366.7 7390.3 7425.3 7438.3 7514.0 43674.6 45203.0 45623.0 45872.0 46009.8 41621.4 52487.5 53370.2 61005.5 61186.4 10877.9 11232.6 11285.5 11309.4 11584.4 9564.4 11508.7 12040.1 12041.9 12555.0 9182.4 11147.7 11313.3 11334.0 11337.1 11397.5 11593.0 11627.1 11739.9 11861.4 11060.6 11222.8 11326.3 11414.4 11529.3 9473.9 9561.6 9598.9 9973.0 9983.0 9321.5 9363.3 9550.3 9565.7 9580.9 12:58:05 INFO - PROCESS | 1937 | -------- Summary: end -------- 12:58:05 INFO - PROCESS | 1937 | 12:58:06 INFO - TEST-INFO | 1937: exit 0 12:58:06 INFO - TEST-OK | dromaeo_css | took 1115371ms 12:58:06 INFO - TEST-START | kraken 12:58:06 INFO - operating with platform_type : mac_ 12:58:06 INFO - Initialising browser for kraken test... 12:58:06 INFO - TEST-INFO | started process 1941 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmpsPIgnM/profile http://localhost:52331/getInfo.html) 12:58:08 INFO - PROCESS | 1941 | __metrics Screen width/height:1600/1200 12:58:08 INFO - PROCESS | 1941 | colorDepth:24 12:58:08 INFO - PROCESS | 1941 | Browser inner width/height: 1024/689 12:58:08 INFO - PROCESS | 1941 | __metrics 12:58:08 INFO - PROCESS | 1941 | 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" 12:58:08 ERROR - PROCESS | 1941 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:58:09 INFO - TEST-INFO | 1941: exit 0 12:58:09 INFO - Browser initialized. 12:58:09 INFO - Running cycle 1/1 for kraken test... 12:58:09 INFO - TEST-INFO | started process 1943 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmpsPIgnM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:58:21 INFO - PROCESS | 1943 | RSS: Main: 238981120 12:58:21 INFO - PROCESS | 1943 | 12:58:24 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52331/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52331/tests/kraken/driver.html?testName=audio-beat-detection) 12:58:24 INFO - PROCESS | 1943 | RSS: Main: 285708288 12:58:24 INFO - PROCESS | 1943 | 12:58:27 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52331/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52331/tests/kraken/driver.html?testName=audio-dft) 12:58:28 INFO - PROCESS | 1943 | RSS: Main: 299192320 12:58:28 INFO - PROCESS | 1943 | 12:58:31 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52331/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52331/tests/kraken/driver.html?testName=audio-fft) 12:58:32 INFO - PROCESS | 1943 | RSS: Main: 383475712 12:58:32 INFO - PROCESS | 1943 | 12:58:34 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52331/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52331/tests/kraken/driver.html?testName=audio-oscillator) 12:58:34 INFO - PROCESS | 1943 | RSS: Main: 285315072 12:58:34 INFO - PROCESS | 1943 | 12:58:36 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52331/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52331/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:58:37 INFO - PROCESS | 1943 | RSS: Main: 290557952 12:58:37 INFO - PROCESS | 1943 | 12:58:40 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52331/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52331/tests/kraken/driver.html?testName=imaging-darkroom) 12:58:40 INFO - PROCESS | 1943 | RSS: Main: 327811072 12:58:40 INFO - PROCESS | 1943 | 12:58:43 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52331/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52331/tests/kraken/driver.html?testName=imaging-desaturate) 12:58:44 INFO - PROCESS | 1943 | RSS: Main: 327118848 12:58:44 INFO - PROCESS | 1943 | 12:58:47 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52331/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52331/tests/kraken/driver.html?testName=json-parse-financial) 12:58:47 INFO - PROCESS | 1943 | RSS: Main: 327868416 12:58:47 INFO - PROCESS | 1943 | 12:58:49 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52331/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52331/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:58:49 INFO - PROCESS | 1943 | RSS: Main: 319803392 12:58:49 INFO - PROCESS | 1943 | 12:58:51 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52331/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52331/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:58:52 INFO - PROCESS | 1943 | RSS: Main: 326619136 12:58:52 INFO - PROCESS | 1943 | 12:58:54 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52331/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52331/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:58:55 INFO - PROCESS | 1943 | RSS: Main: 329920512 12:58:55 INFO - PROCESS | 1943 | 12:58:58 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52331/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52331/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:58:58 INFO - PROCESS | 1943 | RSS: Main: 331563008 12:58:58 INFO - PROCESS | 1943 | 12:59:01 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52331/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52331/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:59:02 INFO - PROCESS | 1943 | RSS: Main: 331780096 12:59:02 INFO - PROCESS | 1943 | 12:59:03 INFO - PROCESS | 1943 | Cycle 1(1): loaded http://localhost:52331/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52331/tests/kraken/driver.html?testName=ai-astar) 12:59:04 INFO - PROCESS | 1943 | RSS: Main: 396836864 12:59:04 INFO - PROCESS | 1943 | 12:59:04 INFO - PROCESS | 1943 | __start_tp_report 12:59:04 INFO - PROCESS | 1943 | _x_x_mozilla_page_load 12:59:04 INFO - PROCESS | 1943 | _x_x_mozilla_page_load_details 12:59:04 INFO - PROCESS | 1943 | |i|pagename|runs| 12:59:04 INFO - PROCESS | 1943 | |0;ai-astar;113;110;112;112;111;114;117;111;112;114 12:59:04 INFO - PROCESS | 1943 | |1;audio-beat-detection;157;122;145;166;115;142;129;138;212;117 12:59:04 INFO - PROCESS | 1943 | |2;audio-dft;187;185;186;183;186;179;180;184;186;186 12:59:04 INFO - PROCESS | 1943 | |3;audio-fft;77;72;89;90;57;60;57;78;87;55 12:59:04 INFO - PROCESS | 1943 | |4;audio-oscillator;89;99;139;78;78;78;125;80;77;128 12:59:04 INFO - PROCESS | 1943 | |5;imaging-gaussian-blur;87;83;86;84;90;129;86;84;86;84 12:59:04 INFO - PROCESS | 1943 | |6;imaging-darkroom;92;92;92;92;92;92;92;92;91;92 12:59:04 INFO - PROCESS | 1943 | |7;imaging-desaturate;85;86;87;88;86;87;86;86;87;84 12:59:04 INFO - PROCESS | 1943 | |8;json-parse-financial;60;61;60;60;61;61;61;77;57;57 12:59:04 INFO - PROCESS | 1943 | |9;json-stringify-tinderbox;63;62;73;62;70;70;67;67;66;67 12:59:04 INFO - PROCESS | 1943 | |10;stanford-crypto-aes;81;82;82;82;80;81;81;82;83;87 12:59:04 INFO - PROCESS | 1943 | |11;stanford-crypto-ccm;139;171;153;148;124;155;153;152;213;159 12:59:04 INFO - PROCESS | 1943 | |12;stanford-crypto-pbkdf2;169;168;169;168;169;168;170;164;164;165 12:59:04 INFO - PROCESS | 1943 | |13;stanford-crypto-sha256-iterative;70;66;68;67;67;67;67;68;64;68 12:59:04 INFO - PROCESS | 1943 | __end_tp_report 12:59:04 INFO - PROCESS | 1943 | __start_cc_report 12:59:04 INFO - PROCESS | 1943 | _x_x_mozilla_cycle_collect,1952 12:59:04 INFO - PROCESS | 1943 | __end_cc_report 12:59:04 INFO - PROCESS | 1943 | __startTimestamp1461182344170__endTimestamp 12:59:04 INFO - PROCESS | 1943 | 12:59:04 INFO - PROCESS | 1943 | ------- Summary: start ------- 12:59:04 INFO - PROCESS | 1943 | Number of tests: 14 12:59:04 INFO - PROCESS | 1943 | 12:59:04 INFO - PROCESS | 1943 | [#0] ai-astar Cycles:10 Average:112.60 Median:112.50 stddev:2.01 (1.8%) stddev-sans-first:2.13 12:59:04 INFO - PROCESS | 1943 | Values: 113.0 110.0 112.0 112.0 111.0 114.0 117.0 111.0 112.0 114.0 12:59:04 INFO - PROCESS | 1943 | 12:59:04 INFO - PROCESS | 1943 | [#1] audio-beat-detection Cycles:10 Average:144.30 Median:143.50 stddev:29.05 (20.2%) stddev-sans-first:30.45 12:59:04 INFO - PROCESS | 1943 | Values: 157.0 122.0 145.0 166.0 115.0 142.0 129.0 138.0 212.0 117.0 12:59:04 INFO - PROCESS | 1943 | 12:59:04 INFO - PROCESS | 1943 | [#2] audio-dft Cycles:10 Average:184.20 Median:186.00 stddev:2.74 (1.5%) stddev-sans-first:2.71 12:59:04 INFO - PROCESS | 1943 | Values: 187.0 185.0 186.0 183.0 186.0 179.0 180.0 184.0 186.0 186.0 12:59:04 INFO - PROCESS | 1943 | 12:59:04 INFO - PROCESS | 1943 | [#3] audio-fft Cycles:10 Average:72.20 Median:77.50 stddev:14.07 (18.2%) stddev-sans-first:14.82 12:59:04 INFO - PROCESS | 1943 | Values: 77.0 72.0 89.0 90.0 57.0 60.0 57.0 78.0 87.0 55.0 12:59:04 INFO - PROCESS | 1943 | 12:59:04 INFO - PROCESS | 1943 | [#4] audio-oscillator Cycles:10 Average:97.10 Median:78.00 stddev:24.38 (31.3%) stddev-sans-first:25.68 12:59:04 INFO - PROCESS | 1943 | Values: 89.0 99.0 139.0 78.0 78.0 78.0 125.0 80.0 77.0 128.0 12:59:04 INFO - PROCESS | 1943 | 12:59:04 INFO - PROCESS | 1943 | [#5] imaging-gaussian-blur Cycles:10 Average:89.90 Median:86.00 stddev:13.88 (16.1%) stddev-sans-first:14.69 12:59:04 INFO - PROCESS | 1943 | Values: 87.0 83.0 86.0 84.0 90.0 129.0 86.0 84.0 86.0 84.0 12:59:04 INFO - PROCESS | 1943 | 12:59:04 INFO - PROCESS | 1943 | [#6] imaging-darkroom Cycles:10 Average:91.90 Median:92.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 12:59:04 INFO - PROCESS | 1943 | Values: 92.0 92.0 92.0 92.0 92.0 92.0 92.0 92.0 91.0 92.0 12:59:04 INFO - PROCESS | 1943 | 12:59:04 INFO - PROCESS | 1943 | [#7] imaging-desaturate Cycles:10 Average:86.20 Median:86.50 stddev:1.14 (1.3%) stddev-sans-first:1.12 12:59:04 INFO - PROCESS | 1943 | Values: 85.0 86.0 87.0 88.0 86.0 87.0 86.0 86.0 87.0 84.0 12:59:04 INFO - PROCESS | 1943 | 12:59:04 INFO - PROCESS | 1943 | [#8] json-parse-financial Cycles:10 Average:61.50 Median:61.00 stddev:5.66 (9.3%) stddev-sans-first:5.98 12:59:04 INFO - PROCESS | 1943 | Values: 60.0 61.0 60.0 60.0 61.0 61.0 61.0 77.0 57.0 57.0 12:59:04 INFO - PROCESS | 1943 | 12:59:04 INFO - PROCESS | 1943 | [#9] json-stringify-tinderbox Cycles:10 Average:66.70 Median:67.00 stddev:3.65 (5.5%) stddev-sans-first:3.62 12:59:04 INFO - PROCESS | 1943 | Values: 63.0 62.0 73.0 62.0 70.0 70.0 67.0 67.0 66.0 67.0 12:59:04 INFO - PROCESS | 1943 | 12:59:04 INFO - PROCESS | 1943 | [#10] stanford-crypto-aes Cycles:10 Average:82.10 Median:82.00 stddev:1.91 (2.3%) stddev-sans-first:1.99 12:59:04 INFO - PROCESS | 1943 | Values: 81.0 82.0 82.0 82.0 80.0 81.0 81.0 82.0 83.0 87.0 12:59:04 INFO - PROCESS | 1943 | 12:59:04 INFO - PROCESS | 1943 | [#11] stanford-crypto-ccm Cycles:10 Average:156.70 Median:154.00 stddev:23.31 (15.1%) stddev-sans-first:23.83 12:59:04 INFO - PROCESS | 1943 | Values: 139.0 171.0 153.0 148.0 124.0 155.0 153.0 152.0 213.0 159.0 12:59:04 INFO - PROCESS | 1943 | 12:59:04 INFO - PROCESS | 1943 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.40 Median:168.50 stddev:2.22 (1.3%) stddev-sans-first:2.28 12:59:04 INFO - PROCESS | 1943 | Values: 169.0 168.0 169.0 168.0 169.0 168.0 170.0 164.0 164.0 165.0 12:59:04 INFO - PROCESS | 1943 | 12:59:04 INFO - PROCESS | 1943 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.50 stddev:1.55 (2.3%) stddev-sans-first:1.27 12:59:04 INFO - PROCESS | 1943 | Values: 70.0 66.0 68.0 67.0 67.0 67.0 67.0 68.0 64.0 68.0 12:59:04 INFO - PROCESS | 1943 | -------- Summary: end -------- 12:59:04 INFO - PROCESS | 1943 | 12:59:05 INFO - TEST-INFO | 1943: exit 0 12:59:05 INFO - TEST-OK | kraken | took 58625ms 12:59:05 INFO - SUITE-END | took 1173s 12:59:05 INFO - Completed test suite (00:19:34) 12:59:05 INFO - javascript benchmark 12:59:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3280.5899262592175, 3368.275820698551, 3393.8472768725405, 3393.8472768725405, 3429.8799542016036, 5532.3914369419335, 5546.639468434549, 5561.221428999938, 5565.499105080547, 5620.606557540974, 5626.099824028156, 5659.292588426447, 5677.063284558043, 5773.0, 5785.855353616162, 5060.443351231362, 5107.821226257091, 5142.357205349332, 5143.871403214912, 5202.219667049935, 5208.687478751268, 5219.843404697846, 5248.6063545234065, 5249.842504724846, 5309.362876454831, 4329.701089673085, 5052.418971818214, 5054.823081192159, 5164.0, 5190.92213616796, 4811.422629284508, 5164.664296820694, 5174.43081261061, 5174.7671354788745, 5217.765200565946, 5058.822941197077, 5090.5927525798215, 5134.768935397916, 5161.66449180802, 5162.664426812245, 5406.945930540723, 5515.338159420855, 5533.778648854041, 5543.334799824047, 5552.611317207797, 6093.847653808691, 6118.082287656843, 6126.387361263838, 6129.1419201311855, 6143.293521245069, 6154.323024467305, 6195.938040619626, 6199.535034872358, 6208.068789681539, 6213.192285002966, 5577.6653400796085, 5635.3519345275, 5652.519535839499, 5686.1755045518585, 5743.655380677173, 6275.654838983808, 6289.654069026156, 6300.936990630126, 6301.306856245809, 6326.810195694136, 6003.699815009232, 6009.729562169713, 6016.338202797689, 6018.127371531104, 6022.819315420545, 5990.101484777231, 6105.938940610537, 6131.601445906002, 6176.0, 6176.69116544171, 5725.456081672234, 6100.816975490742, 6109.1010297800785, 6115.602485838406, 6135.0, 6123.432740139189, 6143.109249158847, 6167.907481387782, 6170.907436388457, 6181.660008699475, 4779.689320194177, 4848.345473361122, 4866.6350023747655, 4883.706977581356, 4909.877253068702, 4349.282368409177, 4368.279233926367, 4404.933925991113, 4436.0, 4442.400275962704, 5619.185218143346, 5638.859028524238, 5645.633033852787, 5662.235598194191, 5678.545716342765, 6046.3349031606485, 6108.068892179526, 6227.906581401283, 6228.5951413158, 6238.469730072995, 4901.191303434894, 5600.131979543177, 5661.971690141523, 5665.263515742927, 5676.829695109153, 5291.920621190685, 5322.068637988336, 5329.973350133225, 5330.706811125426, 5357.383900851374, 6157.630542167484, 6222.782202622887, 6288.874222515524, 6300.464460520907, 6322.0200868865395, 5771.567132465166, 6018.939810601838, 6129.695011270001, 6185.41238582334, 6264.248290205204, 6300.149479820166, 6309.337519560564, 6312.210973628296, 6317.368263173646, 6321.431071203614, 5968.462838344396, 6097.101247990999, 6124.203853499017, 6134.846628834315, 6150.138980542817, 6131.233595800525, 6194.752209911553, 6196.535259855439, 6206.131141640264, 6224.439800417803, 5947.04847224442, 5977.491913187428, 5992.61048031894, 5999.940000600112, 6019.518438524995, 4505.324201369821, 5789.3921138279875, 5931.110333450017, 5980.37206093354, 6019.638821670714, 5836.358000619844, 5845.41545845412, 5914.822555323347, 5951.22634057587, 5953.583249172516, 5986.850328741816, 6011.789587364421, 6023.427774361428, 6063.242094738158, 6078.513718902565, 5621.184928185472, 5765.164051212467, 5894.705264736832, 5907.2911250650195, 5920.674362910081], "value": 5612.487588531087, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [520.1106108554371, 526.2448386565277, 539.059341449171, 557.297804765995, 578.2656026845904, 24559.386015349624, 37937.810310948444, 38947.22105557887, 39016.609833901726, 39053.02367440812, 40705.57530486434, 41220.793896030555, 41303.38044929328, 41420.79289603548, 41514.0, 8075.838483230332, 8579.785505362353, 9827.557759900821, 9836.360636558637, 9880.851787223197, 8673.522956237404, 11537.365444900528, 11789.941050294736, 11790.292582445038, 11820.231684940494, 11354.545818167264, 11465.369404682739, 11490.425478726072, 11500.482478288497, 11553.826692599618, 8563.314934805201, 11293.604723834667, 11345.602903898407, 11454.713632159179, 11649.941750291278, 11312.660620181408, 11479.942600287028, 11481.311121332747, 11482.770344593062, 11485.310881347063, 38854.41718374227, 39462.0, 39467.802660986796, 39482.60517394818, 39491.01272468182, 3234.660360662144, 3445.63820798815, 3503.649635036476, 3574.6067932527403, 3877.3408520551293, 29510.409791804042, 30027.399452011057, 30144.09567712972, 30178.0, 30206.84896575503, 2717.932051698723, 2750.5324094903713, 2753.325435268372, 2866.613007244037, 2926.4586051580313, 502272.0, 503056.48471757775, 504193.0, 504694.0, 504783.0, 63384.0, 63735.36264637387, 64096.269891660704, 64178.6791066051, 64255.35744642494, 61764.382356176764, 62012.379876201565, 69405.0, 70937.2906270941, 71167.6441617799, 16412.75380869288, 16639.6672066558, 16674.33302667904, 16717.414890479016, 16719.414820481226, 11728.296302221723, 12011.579594714143, 12028.578999734968, 12037.87962120385, 12699.428525716365, 17351.392701255394, 18731.854771208527, 20419.8979005104, 20492.282770102975, 20527.4868128295, 6605.405513503808, 7889.317632005146, 7980.720674776471, 8029.3175080118845, 8051.838963220703, 892.4689809563299, 961.6682244625594, 998.7253505286104, 1025.4206373399006, 1056.6671498477954, 29105.0, 29176.708232917823, 29742.702572973994, 29836.403271934872, 31260.0, 1005.1556692378438, 1105.8728246251785, 1117.8658560972735, 1145.8109411946934, 1195.7429152732204, 15580.987235829634, 15592.064476131241, 15605.0, 15697.215139242991, 15964.0, 637551.8122409359, 640619.0, 641971.0, 642222.0, 642427.0, 61619.383806161364, 62134.0, 62388.0, 62414.68792656008, 62541.0, 1313.6518822512041, 1437.698083402497, 1475.4098360655823, 1478.2534819915875, 1494.7010597880467, 30003.399932001674, 30247.0, 30426.087217383512, 30517.389652207276, 30560.08319750411, 42896.92757681083, 51335.9465621371, 51975.0, 52555.0, 52647.68380790511, 458485.0, 469739.0, 470106.6494667505, 470800.0, 472076.0, 35991.28017439689, 43131.13737725165, 43162.78418607887, 44295.557044429144, 45174.77412612916, 46735.0, 48032.27951580799, 48190.5180948186, 49441.0, 49646.75176624094, 37739.245215096096, 39556.0, 39899.20201595894, 39986.33250635065, 40528.3920741195, 32127.51808722918, 38676.613233868426, 38942.6105738939, 39038.21923561456, 39308.0, 38121.618783811806, 38591.80704096462, 39041.2191756169, 39103.41344879886, 39125.41311880267, 33430.0, 33764.81076585142, 34314.852772084385, 34383.828080859435, 34538.82730586331, 25367.492650147262, 25431.6185257225, 25637.61543576885, 25642.743572564035, 26125.0], "value": 20595.99202336422, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.86971734186386, 404.736921001349, 409.3000968344131, 412.0831150689714, 420.89267236854596, 15649.921750391246, 19085.809141908576, 20388.592228155427, 20402.183912643493, 20421.897890510547, 19780.901095494537, 19858.900705496453, 21196.78803211971, 21217.151313947423, 21234.575308493822, 5776.740046697898, 6068.453839154476, 6070.119832624267, 6074.848128796793, 6080.96959515204, 5756.65460072397, 5971.4327138921735, 6028.4574388305045, 6073.757049718006, 6089.604175728586, 5802.12968054791, 5948.256467941507, 5982.162497250389, 5982.79060232892, 5987.9101813472835, 5932.495737862287, 5992.49063829575, 6026.78906238284, 6037.366076561942, 6057.363976782441, 5187.118189907725, 5193.195054766517, 5222.0, 5238.97380513095, 5240.842774716726, 25316.87341563299, 25463.61804572933, 25574.87212563944, 25731.61402578963, 26099.347516312246, 1995.740553728006, 2433.561958847407, 2720.7279272072833, 2980.0, 2987.357718090621, 9490.715278541655, 14289.0, 14535.78196327056, 14885.0, 15077.698446031127, 1720.7504911787846, 1731.7142671459194, 1761.9030953297563, 1819.699749541324, 1828.2686925229882, 88164.0, 124262.37868810598, 124528.37735811353, 124654.0, 126758.36620816929, 11599.88400116005, 11736.237144585575, 11749.941250293865, 11759.823602645967, 11844.940775296069, 15035.699286014218, 15328.846711532742, 15347.61630959235, 15386.153761542999, 15427.768583471257, 8767.605457754416, 8797.868031979526, 8813.867791983126, 8823.735287941372, 8907.376483646083, 8631.43895646781, 8638.956805215934, 8739.169778870997, 8750.431221970679, 8759.605817738217, 4558.156741002858, 4643.3963584733765, 4649.465311489222, 4653.418322709661, 4695.577398034125, 1904.2668572599373, 1952.3752399232394, 1957.7063440484042, 1974.9802501975084, 1996.4010796760913, 923.4828496042238, 965.9806803864024, 975.4692334818465, 990.7424069741919, 996.6362277768562, 20326.288579899632, 20467.590648186953, 20538.075786589638, 20604.27885024017, 20851.061702223436, 896.2561074308309, 962.4985023363575, 991.9553620087113, 994.3834822410151, 1032.3444612671008, 9167.945686246167, 10029.398236105857, 10847.186461015297, 10865.293755905841, 10916.345019298868, 573647.0, 577215.0, 594085.0, 598427.0078649579, 600540.0, 100206.49896750615, 114035.0, 137596.6240337604, 137833.0, 140511.05656019147, 1015.8374660054503, 1033.5762337441613, 1035.8342665173536, 1057.4871187474068, 1061.5010944855906, 15803.288852001917, 16441.753373699165, 18008.459746207634, 18278.543036424195, 18479.98360090208, 39175.41236881506, 47675.0, 48140.0, 48809.75595122002, 49775.75112124416, 56211.156832646724, 72106.27893720995, 72188.27811721816, 72717.27282727105, 73879.0, 8169.918300817153, 8289.958550207451, 8362.331013519026, 8377.204165604258, 8700.086490918362, 11993.820092698443, 12448.190867593577, 12620.810687839874, 12882.03384746129, 12940.0, 10991.0, 11227.663170104583, 11317.151213658844, 11369.943150284196, 11389.088872889983, 8627.110352332864, 9310.975792662937, 10756.946215268874, 10813.513391897226, 11158.776824463628, 10842.78314433691, 11343.092552595937, 11417.771644566896, 11624.88375116238, 11696.47365868555, 8172.387070969582, 8364.874526882224, 8372.706955256537, 8418.663253469793, 8562.0, 10037.0, 10566.735831604272, 11160.0, 11440.82838757436, 11490.712732181762], "value": 9661.780758405734, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1428.0, 1608.1396452897698, 1610.7020201262765, 1887.669657809883, 2012.185065048655, 7887.014123234596, 8182.30450411715, 8601.053884072748, 8803.691870784523, 8832.690855820048, 8617.95691021544, 8620.956895215531, 8810.823783524342, 8810.867836982434, 8866.0, 2152.9354119376444, 2373.9169129080487, 2430.5260474207535, 2474.2329877737957, 2474.616434452663, 2469.987650061756, 2496.213692686798, 2496.4507808282237, 2496.7253602103756, 2503.173952595648, 2406.4104294447884, 2440.2923152285816, 2459.434330104077, 2464.37158524576, 2467.925962221136, 2019.3840878532042, 2138.9144434222612, 2363.6572696959015, 2425.993212816679, 2440.7437219092094, 2440.7681270279295, 2463.9014439422403, 2464.3099932019063, 2466.099873546161, 2470.8393954393086, 8575.82848343036, 8637.956810215972, 8663.39356245063, 8669.393142480028, 8676.305895528405, 5101.387833459971, 5343.438938911398, 5345.144776835727, 5356.6518176318805, 5365.168398898177, 3244.772865899388, 3374.4094783412806, 3480.2598700649673, 3489.162600975773, 3489.755717099804, 1046.2467023742893, 1049.9055085042382, 1050.0969166516788, 1057.6985559115615, 1066.2642776484201, 11550.36472993985, 11612.36132012739, 11629.476673549627, 11638.941805291004, 11663.94168029163, 4741.865552318417, 4746.738929358849, 4801.5438533339275, 4912.9508704913205, 4919.311296418504, 9816.705498835046, 9911.504424778732, 9926.2059035277, 9933.701988940344, 9963.501824908726, 5366.651167674088, 5367.677939323653, 5389.380221274525, 5391.757370918318, 5425.321834770653, 4279.522211569197, 4380.6057454829215, 4516.503184649686, 4568.223402021665, 4921.557059864558, 3800.1069748609057, 3927.273454410943, 4159.147374788161, 4177.26897792885, 4193.790310484464, 1130.5251794246435, 1346.118292518402, 1366.9248191349373, 1368.4799776085224, 1373.2378529915793, 791.4855344026429, 813.2599334605599, 816.2775943290112, 836.957987305796, 873.9825203495894, 5413.242146099549, 5593.972030139823, 5711.114777209539, 5803.796867109715, 5871.853203669943, 846.2679781988659, 938.7700013496737, 943.2925306020485, 987.5335127335901, 1001.8196724589498, 3633.418653015505, 3781.7920014398924, 3789.052736815796, 3815.465834783133, 3839.6352346527037, 10247.538860751283, 10469.84295235572, 10572.41851698164, 10597.364158150536, 10621.468926553642, 10518.105960993249, 10567.260291779656, 10647.14822814173, 10688.358698478116, 10698.839517407245, 7994.805324542274, 8034.758957231293, 8188.959055204686, 8235.547044912588, 8241.0, 5452.146270268128, 5493.725313734377, 5550.306211723535, 5564.666120032811, 5698.20225168485, 9565.899921508837, 9621.374610650286, 9758.609655613696, 9844.901550984685, 9902.900970990198, 8084.39367047462, 9419.576119074794, 9976.501174941079, 9981.800363992534, 10032.398056116657, 8109.7161599343735, 8121.715739949073, 8153.0216374033125, 8223.712170074019, 8284.917150828414, 7542.698292068256, 7789.688412463438, 8077.838443230985, 8301.003879534253, 8308.418410711434, 7302.488825782144, 7374.705011799468, 7381.815454613463, 7384.261573842573, 7466.178720340649, 7270.054892863894, 7274.418046556369, 7350.338469537767, 7407.666655000646, 7445.590492522963, 6852.486063545353, 6947.166340039025, 6971.268016858156, 7023.964880175567, 7038.2961703829205, 4528.547145285445, 4575.17646823575, 4773.737444440589, 4817.132916075, 4863.781129849236, 4814.470408255019, 4846.297286893451, 4881.9023619528125, 4936.111499930047, 5095.617828662791], "value": 4565.351152934873, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.5176209804245, 323.9611246650401, 332.80198282022195, 333.3599488981156, 337.85978818790204, 5771.653700777951, 5794.739236734346, 5838.211841401409, 5861.589688721791, 5866.64800111993, 4699.778057704995, 4835.371401717772, 5499.532539734128, 5510.862228444291, 5748.912212691766, 3971.5631280559187, 4021.351245989145, 4363.956360436387, 4394.780260986954, 4441.600255976961, 4271.910662780986, 4408.845690400821, 4422.513523512411, 4429.55704429556, 4435.445569303837, 4387.7367357958465, 4402.625776808975, 4422.18189634917, 4437.5387197985465, 4438.822447102112, 4143.81352839123, 4248.235317642823, 4256.978325201961, 4431.7340959542535, 4441.200583894898, 3189.6012998375204, 3383.8308084595674, 3660.396034654279, 3834.9424758628643, 3858.2606349491894, 5336.706481143536, 5570.774429625476, 5677.0, 5747.856303592402, 5764.337101233328, 4869.2939523768855, 5090.567301779353, 5126.282320475137, 5131.7434128293435, 5154.896902061938, 5154.768035438415, 6131.478824299896, 6138.741557980603, 6208.96895515524, 6230.8130756076935, 4156.459660244149, 4283.507396649394, 4285.507166675841, 4289.0, 4299.0, 5566.526845218162, 6161.7535298588, 6214.316425193225, 6447.677616119222, 6453.740860865233, 4221.704480686384, 4226.429432026698, 4249.235137675187, 4257.87226383209, 4267.0, 3438.329525742506, 3657.670809627093, 3994.8601798936897, 4286.485621725351, 4323.416338794223, 4796.688215265996, 4874.417303282155, 5034.597232221414, 5056.115179843513, 5074.06129865976, 6209.627422354673, 6256.186695729617, 6256.74973001084, 6266.154069200691, 6285.560010799291, 1232.8150777383464, 1446.3636000159895, 1469.6913648133798, 1473.5284708893266, 1474.3439169569654, 929.789811734386, 956.8756061711933, 969.2585172343163, 969.2682025071026, 978.6770365779275, 5631.0, 5687.516561092353, 5697.800576979786, 5706.2011318414625, 5709.229254050733, 5765.827025189251, 6104.694765261719, 6319.842003949846, 6326.873462530723, 6328.46208072319, 5422.701751403711, 5664.660120392789, 5676.460736230051, 5715.485606295453, 5818.0981947798155, 3309.5697559317136, 3319.618243901934, 3324.2852786650988, 3335.981731052727, 3369.029117966017, 6322.71547780351, 6604.273529911706, 6610.735570577219, 6627.900581491282, 6820.556663816936, 6675.566088204251, 6687.431568316748, 6688.7324507019175, 6691.197056353269, 6709.127813384229, 5906.645601263938, 6785.626790526469, 6785.89821152693, 6790.5246632736225, 6795.2185498667295, 5427.918581221364, 5787.508061814625, 6050.21347224858, 6508.8047358579315, 6540.411362977355, 6111.908321375272, 6451.741930322923, 6453.9354606453335, 6463.61218326883, 6464.935350646433, 6564.4420224281475, 6577.375149360803, 6749.662516874235, 6811.352921472452, 6812.557183783038, 3926.2736393767245, 4209.1581683664, 4223.894402639959, 4241.53343132249, 4246.44796176495, 6228.449783623291, 6434.86249525684, 6472.546921715435, 6485.0, 6489.415952564292, 5921.555883308855, 5974.61165024272, 6137.3862613738265, 6243.531735119794, 6254.718537665907, 5963.254593175853, 6243.43809057187, 6254.278704171011, 6262.843428914313, 6263.3736626337, 5617.915786213483, 6073.0, 6124.846878828065, 6129.850925222834, 6136.539759518143, 5402.729863506887, 6218.19163508741, 6240.937590624218, 6251.374862513712, 6255.343188965275, 5830.737616807169, 6062.575619706578, 6121.755129794758, 6141.355157708376, 6175.166352542349], "value": 4542.497010197381, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [681.8738533371327, 745.6309126982142, 747.2378174262255, 750.9511881727688, 757.9962100189498, 14607.707845843077, 15082.245887705612, 17234.310627574883, 18023.72964405532, 18033.819661803376, 16230.350785968576, 16356.0, 16437.42469013585, 16440.588985275375, 16460.09469479178, 735.0150797930771, 749.8387846612964, 914.6387177065055, 916.0564618443015, 918.4030380252856, 736.0799001248439, 877.2236570635002, 909.2634965677822, 909.56340956341, 909.9772505687345, 956.4548207521715, 963.6530848894392, 965.903409659032, 967.5114067395955, 969.2391472693937, 744.5383862005591, 788.4086934798901, 911.4531281231275, 943.8725772020758, 945.6028468043438, 600.3966014155745, 713.1086142322098, 714.2322003845886, 714.6462501061956, 716.2765606737181, 15639.609009774733, 15735.370585176579, 15741.92129039359, 15755.842441575553, 15762.76355854663, 12399.442025108801, 12456.439460224312, 12505.624831254985, 12749.426275817517, 12780.808287875689, 9990.0, 10222.0, 10238.0, 10283.588656453807, 10757.892421075845, 3431.9828400858087, 3508.4912687660403, 3511.227529943434, 3511.2450823072904, 3579.427291633352, 85704.57147714222, 85830.570847146, 86005.0, 87461.12538874593, 87668.0, 9336.903952995772, 9471.573779179953, 9587.712368628952, 9758.560864761102, 9824.0, 21312.680309795367, 21554.13783448676, 21570.24504142379, 21595.568088638454, 21618.783812161677, 7208.783736487913, 7404.888926666104, 7498.250174982458, 7574.431917606202, 7609.467337286447, 4087.8364865405647, 4095.5494895561465, 4118.855840045584, 4187.623113919762, 4233.534311225762, 3606.4590311453494, 3714.0, 3720.4233343831747, 3722.683571896365, 3722.683571896419, 592.2330581896455, 598.2760859360136, 623.8814625221194, 656.6979189572754, 669.8191488298132, 537.4115343698644, 588.5232961301359, 619.0868469008177, 626.2157119606212, 634.6477704873747, 7524.661390237453, 7772.417068719868, 7808.297253247235, 8012.31895288907, 8023.558704271321, 552.7236381809096, 577.8295402856168, 601.5909181756448, 605.7879742090233, 610.7801191571031, 3722.7021838252876, 3756.248750249906, 3770.208256266215, 3772.7924964127237, 3871.399933010388, 88215.11784882197, 90143.54928225317, 90790.0, 90868.1826363469, 90956.54521727482, 76884.61557692176, 77571.22428775753, 78242.60878695684, 78281.0, 78733.21266787374, 66778.98483704637, 67479.98780018304, 67694.66152669206, 68010.0, 69168.999999999, 7366.7053317866685, 7390.334869861738, 7425.331720145213, 7438.330550250287, 7513.962430188033, 43674.56325436705, 45203.0, 45623.0, 45872.0, 46009.76995115137, 41621.37567936544, 52487.47512524826, 53370.19944700755, 61005.47486312878, 61186.38813611807, 10877.891221087688, 11232.55069797199, 11285.450245443924, 11309.434528273718, 11584.362860042778, 9564.426134431957, 11508.676502202354, 12040.096992725405, 12041.879581204075, 12555.0, 9182.403143795633, 11147.665570032912, 11313.264637798517, 11334.0, 11337.093032557541, 11397.487113079766, 11593.0, 11627.069834413396, 11739.941300293785, 11861.406929653655, 11060.557577696802, 11222.775544489228, 11326.263792853439, 11414.429278536207, 11529.250598711056, 9473.857892131762, 9561.61753529851, 9598.85601715961, 9973.0, 9983.0, 9321.533923303943, 9363.250939924927, 9550.283728720235, 9565.665201717906, 9580.856287155837], "value": 6851.525640812389, "unit": "score"}], "name": "dromaeo_css", "value": 7358.101944177628, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 110.0, 112.0, 112.0, 111.0, 114.0, 117.0, 111.0, 112.0, 114.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 122.0, 145.0, 166.0, 115.0, 142.0, 129.0, 138.0, 212.0, 117.0], "value": 144.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 185.0, 186.0, 183.0, 186.0, 179.0, 180.0, 184.0, 186.0, 186.0], "value": 184.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 72.0, 89.0, 90.0, 57.0, 60.0, 57.0, 78.0, 87.0, 55.0], "value": 72.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 99.0, 139.0, 78.0, 78.0, 78.0, 125.0, 80.0, 77.0, 128.0], "value": 97.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 86.0, 84.0, 90.0, 129.0, 86.0, 84.0, 86.0, 84.0], "value": 89.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 92.0, 92.0, 92.0, 92.0, 92.0, 92.0, 91.0, 92.0], "value": 91.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 87.0, 88.0, 86.0, 87.0, 86.0, 86.0, 87.0, 84.0], "value": 86.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 60.0, 60.0, 61.0, 61.0, 61.0, 77.0, 57.0, 57.0], "value": 61.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 73.0, 62.0, 70.0, 70.0, 67.0, 67.0, 66.0, 67.0], "value": 66.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 82.0, 82.0, 80.0, 81.0, 81.0, 82.0, 83.0, 87.0], "value": 82.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 171.0, 153.0, 148.0, 124.0, 155.0, 153.0, 152.0, 213.0, 159.0], "value": 156.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 168.0, 169.0, 168.0, 169.0, 168.0, 170.0, 164.0, 164.0, 165.0], "value": 167.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 66.0, 68.0, 67.0, 67.0, 67.0, 67.0, 68.0, 64.0, 68.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1480.0000000000002, "alertThreshold": 2.0}]} 12:59:05 INFO - Return code: 0 12:59:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:59:05 ERROR - # TBPL SUCCESS # 12:59:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:59:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:59:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:59:05 INFO - 2016-04-20 12:59:05.474 screenresolution[1947:16921] starting screenresolution argv=screenresolution get 12:59:05 INFO - 2016-04-20 12:59:05.476 screenresolution[1947:16921] Display 0: 1600x1200x32@60 12:59:05 INFO - 2016-04-20 12:59:05.482 screenresolution[1948:16924] starting screenresolution argv=screenresolution list 12:59:05 INFO - Available Modes on Display 0 12:59:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:59:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:59:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:59:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:59:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:59:05 INFO - Intel Iris: 12:59:05 INFO - Chipset Model: Intel Iris 12:59:05 INFO - Type: GPU 12:59:05 INFO - Bus: Built-In 12:59:05 INFO - VRAM (Dynamic, Max): 1536 MB 12:59:05 INFO - Vendor: Intel (0x8086) 12:59:05 INFO - Device ID: 0x0a2e 12:59:05 INFO - Revision ID: 0x0009 12:59:05 INFO - Displays: 12:59:05 INFO - Display: 12:59:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:59:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:59:05 INFO - Display Serial Number: 1600x1200 60 12:59:05 INFO - Main Display: Yes 12:59:05 INFO - Mirror: Off 12:59:05 INFO - Online: Yes 12:59:05 INFO - Rotation: Supported 12:59:05 INFO - Return code: 0 12:59:05 INFO - Running post-action listener: _resource_record_post_action 12:59:05 INFO - Running post-run listener: _resource_record_post_run 12:59:06 INFO - Total resource usage - Wall time: 1209s; CPU: 53.0%; Read bytes: 1929216; Write bytes: 691670016; Read time: 20; Write time: 1910 12:59:06 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 383945216; Write bytes: 378218496; Read time: 28609; Write time: 680 12:59:06 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 1929216; Write bytes: 293704704; Read time: 20; Write time: 1187 12:59:06 INFO - Running post-run listener: _upload_blobber_files 12:59:06 INFO - Blob upload gear active. 12:59:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:59:06 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 12:59:06 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'] 12:59:06 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 12:59:07 INFO - (blobuploader) - INFO - Open directory for files ... 12:59:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:59:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:59:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:59:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:59:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:59:07 INFO - (blobuploader) - INFO - Done attempting. 12:59:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:59:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:59:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:59:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:59:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:59:08 INFO - (blobuploader) - INFO - Done attempting. 12:59:08 INFO - (blobuploader) - INFO - Iteration through files over. 12:59:08 INFO - Return code: 0 12:59:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:59:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:59:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/349df77b33c9cf75944a8eb0ab676615d144c5f0deffe8ee67c301850e8441a0f5555a7b62fe37bd5bd88f4dd61989fa2486227415d0236e5bcb259a08370d78", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2c885338fa0f5d8955c6c8c16a6768a0d11a61f4e40f9a11d35f626ace012e806e41ce69b5a64cd36a908dd5505a0e6f06eb071f784a77c5e1f1607db9ad52d7"} 12:59:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:59:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:59:08 INFO - Contents: 12:59:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/349df77b33c9cf75944a8eb0ab676615d144c5f0deffe8ee67c301850e8441a0f5555a7b62fe37bd5bd88f4dd61989fa2486227415d0236e5bcb259a08370d78", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2c885338fa0f5d8955c6c8c16a6768a0d11a61f4e40f9a11d35f626ace012e806e41ce69b5a64cd36a908dd5505a0e6f06eb071f784a77c5e1f1607db9ad52d7"} 12:59:08 INFO - Running post-run listener: copy_logs_to_upload_dir 12:59:08 INFO - Copying logs to upload dir... 12:59:08 INFO - mkdir: /builds/slave/test/build/upload/logs 12:59:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1342.170061 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 22 secs) (at 2016-04-20 12:59:09.006329) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-20 12:59:09.010195) ========= 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.QqBeeDEWAJ/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.mvSZqAPqa8/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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/349df77b33c9cf75944a8eb0ab676615d144c5f0deffe8ee67c301850e8441a0f5555a7b62fe37bd5bd88f4dd61989fa2486227415d0236e5bcb259a08370d78", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2c885338fa0f5d8955c6c8c16a6768a0d11a61f4e40f9a11d35f626ace012e806e41ce69b5a64cd36a908dd5505a0e6f06eb071f784a77c5e1f1607db9ad52d7"} build_url:https://queue.taskcluster.net/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011244 build_url: 'https://queue.taskcluster.net/v1/task/Q0X4qSr1RUyjZJ0sS2YLXA/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/349df77b33c9cf75944a8eb0ab676615d144c5f0deffe8ee67c301850e8441a0f5555a7b62fe37bd5bd88f4dd61989fa2486227415d0236e5bcb259a08370d78", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2c885338fa0f5d8955c6c8c16a6768a0d11a61f4e40f9a11d35f626ace012e806e41ce69b5a64cd36a908dd5505a0e6f06eb071f784a77c5e1f1607db9ad52d7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-20 12:59:09.038240) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 12:59:09.038663) ========= 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.QqBeeDEWAJ/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.mvSZqAPqa8/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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.005068 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-20 12:59:09.088283) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-20 12:59:09.088607) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-20 12:59:09.089085) ========= ========= Total master_lag: 0.16 =========