builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-018 starttime: 1482865007.08 results: success (0) buildid: 20161227104237 builduid: 7c1034f8b3d84143af688e91f5ed8229 revision: 545975b950e85f2acf1db2ee9b02a432829bc980 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-27 10:56:47.081414) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-27 10:56:47.081847) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-27 10:56:47.082141) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-27 10:56:47.159992) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 10:56:47.160352) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1482864883.280121-828086330 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-27 10:56:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 228M=0s 2016-12-27 10:56:47 (228 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.120201 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 10:56:47.294268) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 10:56:47.296628) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1482864883.280121-828086330 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019209 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 10:56:47.346333) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 10:56:47.349823) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 545975b950e85f2acf1db2ee9b02a432829bc980 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 545975b950e85f2acf1db2ee9b02a432829bc980 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1482864883.280121-828086330 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-27 10:56:47,651 truncating revision to first 12 chars 2016-12-27 10:56:47,651 Setting DEBUG logging. 2016-12-27 10:56:47,651 attempt 1/10 2016-12-27 10:56:47,651 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/545975b950e8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-27 10:56:48,110 unpacking tar archive at: graphics-545975b950e8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.768042 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 10:56:48.132203) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-27 10:56:48.132554) ========= script_repo_revision: 545975b950e85f2acf1db2ee9b02a432829bc980 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-27 10:56:48.132950) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-27 10:56:48.133215) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-27 10:56:48.141978) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-12-27 10:56:48.142463) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1482864883.280121-828086330 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:56:48 INFO - MultiFileLogger online at 20161227 10:56:48 in /builds/slave/test 10:56:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 10:56:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:56:48 INFO - {'append_to_log': False, 10:56:48 INFO - 'base_work_dir': '/builds/slave/test', 10:56:48 INFO - 'blob_upload_branch': 'Graphics', 10:56:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:56:48 INFO - 'branch': 'Graphics', 10:56:48 INFO - 'buildbot_json_path': 'buildprops.json', 10:56:48 INFO - 'config_files': ('talos/linux_config.py',), 10:56:48 INFO - 'default_actions': ('clobber', 10:56:48 INFO - 'read-buildbot-config', 10:56:48 INFO - 'download-and-extract', 10:56:48 INFO - 'populate-webroot', 10:56:48 INFO - 'create-virtualenv', 10:56:48 INFO - 'install', 10:56:48 INFO - 'run-tests'), 10:56:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:56:48 INFO - 'download_minidump_stackwalk': True, 10:56:48 INFO - 'download_symbols': 'ondemand', 10:56:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:56:48 INFO - 'tooltool.py': '/tools/tooltool.py', 10:56:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:56:48 INFO - '/tools/misc-python/virtualenv.py')}, 10:56:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:56:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:56:48 INFO - 'installer_path': 'installer.exe', 10:56:48 INFO - 'log_level': 'info', 10:56:48 INFO - 'log_name': 'talos', 10:56:48 INFO - 'log_to_console': True, 10:56:48 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:56:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:56:48 INFO - 'opt_config_files': (), 10:56:48 INFO - 'pip_index': False, 10:56:48 INFO - 'sps_profile': False, 10:56:48 INFO - 'sps_profile_interval': 0, 10:56:48 INFO - 'suite': 'dromaeojs', 10:56:48 INFO - 'system_bits': '32', 10:56:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:56:48 INFO - 'title': 'talos-linux64-ix-018', 10:56:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:56:48 INFO - 'use_talos_json': True, 10:56:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:56:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:56:48 INFO - 'work_dir': 'build'} 10:56:48 INFO - [mozharness: 2016-12-27 18:56:48.542398Z] Running clobber step. 10:56:48 INFO - Running pre-action listener: _resource_record_pre_action 10:56:48 INFO - Running main action method: clobber 10:56:48 INFO - rmtree: /builds/slave/test/build 10:56:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:56:49 INFO - Running post-action listener: _resource_record_post_action 10:56:49 INFO - [mozharness: 2016-12-27 18:56:49.003565Z] Finished clobber step (success) 10:56:49 INFO - [mozharness: 2016-12-27 18:56:49.003666Z] Running read-buildbot-config step. 10:56:49 INFO - Running pre-action listener: _resource_record_pre_action 10:56:49 INFO - Running main action method: read_buildbot_config 10:56:49 INFO - Using buildbot properties: 10:56:49 INFO - { 10:56:49 INFO - "project": "", 10:56:49 INFO - "product": "firefox", 10:56:49 INFO - "script_repo_revision": "default", 10:56:49 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 10:56:49 INFO - "repository": "", 10:56:49 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 10:56:49 INFO - "buildid": "20161227104237", 10:56:49 INFO - "pgo_build": "False", 10:56:49 INFO - "basedir": "/builds/slave/test", 10:56:49 INFO - "buildnumber": 43, 10:56:49 INFO - "slavename": "talos-linux64-ix-018", 10:56:49 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 10:56:49 INFO - "platform": "ubuntu64_hw", 10:56:49 INFO - "scheduler": "tests-graphics-linux64-talos", 10:56:49 INFO - "branch": "graphics", 10:56:49 INFO - "repo_path": "projects/graphics", 10:56:49 INFO - "revision": "545975b950e85f2acf1db2ee9b02a432829bc980", 10:56:49 INFO - "stage_platform": "linux64", 10:56:49 INFO - "builduid": "7c1034f8b3d84143af688e91f5ed8229", 10:56:49 INFO - "slavebuilddir": "test" 10:56:49 INFO - } 10:56:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/AtSqGWIzSKSlNBraD7c1Xg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 10:56:49 INFO - Running post-action listener: _resource_record_post_action 10:56:49 INFO - [mozharness: 2016-12-27 18:56:49.006149Z] Finished read-buildbot-config step (success) 10:56:49 INFO - [mozharness: 2016-12-27 18:56:49.006239Z] Running download-and-extract step. 10:56:49 INFO - Running pre-action listener: _resource_record_pre_action 10:56:49 INFO - Running main action method: download_and_extract 10:56:49 INFO - mkdir: /builds/slave/test/build/tests 10:56: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:56:49 INFO - trying https://queue.taskcluster.net/v1/task/AtSqGWIzSKSlNBraD7c1Xg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 10:56:49 INFO - Downloading https://queue.taskcluster.net/v1/task/AtSqGWIzSKSlNBraD7c1Xg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 10:56:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AtSqGWIzSKSlNBraD7c1Xg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 10:56:49 INFO - Downloaded 1361 bytes. 10:56:49 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 10:56:49 INFO - Using the following test package requirements: 10:56:49 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 10:56:49 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 10:56:49 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 10:56:49 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 10:56:49 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 10:56:49 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 10:56:49 INFO - u'jsshell-linux-x86_64.zip'], 10:56:49 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 10:56:49 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 10:56:49 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 10:56:49 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 10:56:49 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 10:56:49 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 10:56:49 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 10:56:49 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 10:56:49 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 10:56:49 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 10:56:49 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 10:56:49 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:56:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AtSqGWIzSKSlNBraD7c1Xg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 10:56:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AtSqGWIzSKSlNBraD7c1Xg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:56:49 INFO - Fetch https://queue.taskcluster.net/v1/task/AtSqGWIzSKSlNBraD7c1Xg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 10:56:51 INFO - Expected file size: 20241804 10:56:51 INFO - Obtained file size: 20241804 10:56:55 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 10:56:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AtSqGWIzSKSlNBraD7c1Xg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 10:56:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AtSqGWIzSKSlNBraD7c1Xg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:56:55 INFO - Fetch https://queue.taskcluster.net/v1/task/AtSqGWIzSKSlNBraD7c1Xg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 10:56:56 INFO - Expected file size: 20241804 10:56:56 INFO - Obtained file size: 20241804 10:57:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AtSqGWIzSKSlNBraD7c1Xg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 10:57:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AtSqGWIzSKSlNBraD7c1Xg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 10:57:00 INFO - Fetch https://queue.taskcluster.net/v1/task/AtSqGWIzSKSlNBraD7c1Xg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 10:57:02 INFO - Expected file size: 14409776 10:57:02 INFO - Obtained file size: 14409776 10:57:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:02 INFO - trying https://queue.taskcluster.net/v1/task/AtSqGWIzSKSlNBraD7c1Xg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 10:57:02 INFO - Downloading https://queue.taskcluster.net/v1/task/AtSqGWIzSKSlNBraD7c1Xg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 10:57:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AtSqGWIzSKSlNBraD7c1Xg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 10:57:05 INFO - Downloaded 55913406 bytes. 10:57:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AtSqGWIzSKSlNBraD7c1Xg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 10:57:05 INFO - mkdir: /builds/slave/test/properties 10:57:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:57:05 INFO - Writing to file /builds/slave/test/properties/build_url 10:57:05 INFO - Contents: 10:57:05 INFO - build_url:https://queue.taskcluster.net/v1/task/AtSqGWIzSKSlNBraD7c1Xg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 10:57:06 INFO - Running post-action listener: _resource_record_post_action 10:57:06 INFO - Running post-action listener: set_extra_try_arguments 10:57:06 INFO - [mozharness: 2016-12-27 18:57:06.332899Z] Finished download-and-extract step (success) 10:57:06 INFO - [mozharness: 2016-12-27 18:57:06.333127Z] Running populate-webroot step. 10:57:06 INFO - Running pre-action listener: _resource_record_pre_action 10:57:06 INFO - Running main action method: populate_webroot 10:57:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:57:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:57:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:57:06 INFO - '--tppagecycles', 10:57:06 INFO - '7'], 10:57:06 INFO - 'tests': ['tsvgm']}}, 10:57:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:57:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:57:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:57:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:57:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:57:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:57:06 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:57:06 INFO - 'talos_options': ['--disable-e10s'], 10:57:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:57:06 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:57:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:57:06 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:57:06 INFO - 'talos_options': ['--disable-e10s'], 10:57:06 INFO - 'tests': ['damp', 'tps']}, 10:57:06 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:57:06 INFO - 'tests': ['damp', 'tps']}, 10:57:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:57:06 INFO - 'tests': ['dromaeo_dom']}, 10:57:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:57:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:57:06 INFO - 'tests': ['basic_compositor_video']}, 10:57:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:57:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:57:06 INFO - 'tests': ['a11yr', 10:57:06 INFO - 'ts_paint', 10:57:06 INFO - 'tpaint', 10:57:06 INFO - 'sessionrestore', 10:57:06 INFO - 'sessionrestore_no_auto_restore', 10:57:06 INFO - 'tabpaint']}, 10:57:06 INFO - 'other-e10s': {'tests': ['a11yr', 10:57:06 INFO - 'ts_paint', 10:57:06 INFO - 'tpaint', 10:57:06 INFO - 'sessionrestore', 10:57:06 INFO - 'sessionrestore_no_auto_restore', 10:57:06 INFO - 'tabpaint']}, 10:57:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:57:06 INFO - 'ts_paint', 10:57:06 INFO - 'tpaint', 10:57:06 INFO - 'sessionrestore', 10:57:06 INFO - 'sessionrestore_no_auto_restore', 10:57:06 INFO - 'tabpaint']}, 10:57:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:57:06 INFO - 'ts_paint', 10:57:06 INFO - 'tpaint', 10:57:06 INFO - 'sessionrestore', 10:57:06 INFO - 'sessionrestore_no_auto_restore', 10:57:06 INFO - 'tabpaint']}, 10:57:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:57:06 INFO - 'tests': ['a11yr', 10:57:06 INFO - 'ts_paint', 10:57:06 INFO - 'tpaint', 10:57:06 INFO - 'sessionrestore', 10:57:06 INFO - 'sessionrestore_no_auto_restore', 10:57:06 INFO - 'tabpaint']}, 10:57:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:57:06 INFO - 'tests': ['a11yr', 10:57:06 INFO - 'ts_paint', 10:57:06 INFO - 'tpaint', 10:57:06 INFO - 'sessionrestore', 10:57:06 INFO - 'sessionrestore_no_auto_restore', 10:57:06 INFO - 'tabpaint']}, 10:57:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:57:06 INFO - 'tests': ['tsvgx', 10:57:06 INFO - 'tsvgr_opacity', 10:57:06 INFO - 'tart', 10:57:06 INFO - 'tscrollx', 10:57:06 INFO - 'cart', 10:57:06 INFO - 'tsvg_static']}, 10:57:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:57:06 INFO - 'tsvgr_opacity', 10:57:06 INFO - 'tart', 10:57:06 INFO - 'tscrollx', 10:57:06 INFO - 'cart', 10:57:06 INFO - 'tsvg_static']}, 10:57:06 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:57:06 INFO - 'talos_options': ['--disable-e10s'], 10:57:06 INFO - 'tests': ['tp5o']}, 10:57:06 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:57:06 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:57:06 INFO - 'talos_options': ['--disable-e10s', 10:57:06 INFO - '--xperf_path', 10:57:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:57:06 INFO - 'tests': ['tp5n']}, 10:57:06 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:57:06 INFO - 'talos_options': ['--xperf_path', 10:57:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:57:06 INFO - 'tests': ['tp5n']}}, 10:57:06 INFO - 'talos.zip': {'path': '', 10:57:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:57:06 INFO - Running post-action listener: _resource_record_post_action 10:57:06 INFO - [mozharness: 2016-12-27 18:57:06.354045Z] Finished populate-webroot step (success) 10:57:06 INFO - [mozharness: 2016-12-27 18:57:06.354134Z] Running create-virtualenv step. 10:57:06 INFO - Running pre-action listener: _resource_record_pre_action 10:57:06 INFO - Running main action method: create_virtualenv 10:57:06 INFO - Creating virtualenv /builds/slave/test/build/venv 10:57:06 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 10:57:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:57:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:57:06 INFO - Using real prefix '/usr' 10:57:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:57:06 INFO - Installing distribute.............................................................................................................................................................................................done. 10:57:08 INFO - Installing pip.................done. 10:57:08 INFO - Return code: 0 10:57:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:57:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:57:09 INFO - Reading from file tmpfile_stdout 10:57:09 INFO - Output received: 10:57:09 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 10:57:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:57:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e34c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e323f0>, '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 0x1e32cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e331a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1482864883.280121-828086330', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:57:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:57:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:57:09 INFO - Using env: {'DISPLAY': ':0', 10:57:09 INFO - 'HOME': '/home/cltbld', 10:57:09 INFO - 'LANG': 'en_US.UTF-8', 10:57:09 INFO - 'LANGUAGE': 'en_US:en', 10:57:09 INFO - 'LOGNAME': 'cltbld', 10:57:09 INFO - 'MAIL': '/var/mail/cltbld', 10:57:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:09 INFO - 'MOZ_NO_REMOTE': '1', 10:57:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:57:09 INFO - 'NO_EM_RESTART': '1', 10:57:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:57:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:09 INFO - 'PWD': '/builds/slave/test', 10:57:09 INFO - 'SHELL': '/bin/bash', 10:57:09 INFO - 'SHLVL': '1', 10:57:09 INFO - 'TERM': 'linux', 10:57:09 INFO - 'TMOUT': '86400', 10:57:09 INFO - 'USER': 'cltbld', 10:57:09 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1482864883.280121-828086330', 10:57:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:09 INFO - '_': '/tools/buildbot/bin/python'} 10:57:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:09 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 10:57:09 INFO - Cleaning up... 10:57:09 INFO - Return code: 0 10:57:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:57:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e34c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e323f0>, '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 0x1e32cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e331a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1482864883.280121-828086330', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:57:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:57:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:57:09 INFO - Using env: {'DISPLAY': ':0', 10:57:09 INFO - 'HOME': '/home/cltbld', 10:57:09 INFO - 'LANG': 'en_US.UTF-8', 10:57:09 INFO - 'LANGUAGE': 'en_US:en', 10:57:09 INFO - 'LOGNAME': 'cltbld', 10:57:09 INFO - 'MAIL': '/var/mail/cltbld', 10:57:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:09 INFO - 'MOZ_NO_REMOTE': '1', 10:57:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:57:09 INFO - 'NO_EM_RESTART': '1', 10:57:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:57:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:09 INFO - 'PWD': '/builds/slave/test', 10:57:09 INFO - 'SHELL': '/bin/bash', 10:57:09 INFO - 'SHLVL': '1', 10:57:09 INFO - 'TERM': 'linux', 10:57:09 INFO - 'TMOUT': '86400', 10:57:09 INFO - 'USER': 'cltbld', 10:57:09 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1482864883.280121-828086330', 10:57:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:09 INFO - '_': '/tools/buildbot/bin/python'} 10:57:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:09 INFO - Downloading/unpacking psutil>=3.1.1 10:57:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:57:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:57:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:57:11 INFO - Installing collected packages: psutil 10:57:11 INFO - Running setup.py install for psutil 10:57:11 INFO - building 'psutil._psutil_linux' extension 10:57:11 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 10:57:11 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 10:57:11 INFO - building 'psutil._psutil_posix' extension 10:57:11 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 10:57:12 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 10:57:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:57:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:57:12 INFO - Successfully installed psutil 10:57:12 INFO - Cleaning up... 10:57:12 INFO - Return code: 0 10:57:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:57:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e34c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e323f0>, '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 0x1e32cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e331a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1482864883.280121-828086330', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:57:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:57:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:57:12 INFO - Using env: {'DISPLAY': ':0', 10:57:12 INFO - 'HOME': '/home/cltbld', 10:57:12 INFO - 'LANG': 'en_US.UTF-8', 10:57:12 INFO - 'LANGUAGE': 'en_US:en', 10:57:12 INFO - 'LOGNAME': 'cltbld', 10:57:12 INFO - 'MAIL': '/var/mail/cltbld', 10:57:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:12 INFO - 'MOZ_NO_REMOTE': '1', 10:57:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:57:12 INFO - 'NO_EM_RESTART': '1', 10:57:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:57:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:12 INFO - 'PWD': '/builds/slave/test', 10:57:12 INFO - 'SHELL': '/bin/bash', 10:57:12 INFO - 'SHLVL': '1', 10:57:12 INFO - 'TERM': 'linux', 10:57:12 INFO - 'TMOUT': '86400', 10:57:12 INFO - 'USER': 'cltbld', 10:57:12 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1482864883.280121-828086330', 10:57:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:12 INFO - '_': '/tools/buildbot/bin/python'} 10:57:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:57:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:57:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:57:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:57:14 INFO - Installing collected packages: mozsystemmonitor 10:57:14 INFO - Running setup.py install for mozsystemmonitor 10:57:14 INFO - Successfully installed mozsystemmonitor 10:57:14 INFO - Cleaning up... 10:57:14 INFO - Return code: 0 10:57:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:57:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e34c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e323f0>, '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 0x1e32cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e331a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1482864883.280121-828086330', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:57:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:57:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:57:14 INFO - Using env: {'DISPLAY': ':0', 10:57:14 INFO - 'HOME': '/home/cltbld', 10:57:14 INFO - 'LANG': 'en_US.UTF-8', 10:57:14 INFO - 'LANGUAGE': 'en_US:en', 10:57:14 INFO - 'LOGNAME': 'cltbld', 10:57:14 INFO - 'MAIL': '/var/mail/cltbld', 10:57:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:14 INFO - 'MOZ_NO_REMOTE': '1', 10:57:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:57:14 INFO - 'NO_EM_RESTART': '1', 10:57:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:57:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:14 INFO - 'PWD': '/builds/slave/test', 10:57:14 INFO - 'SHELL': '/bin/bash', 10:57:14 INFO - 'SHLVL': '1', 10:57:14 INFO - 'TERM': 'linux', 10:57:14 INFO - 'TMOUT': '86400', 10:57:14 INFO - 'USER': 'cltbld', 10:57:14 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1482864883.280121-828086330', 10:57:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:14 INFO - '_': '/tools/buildbot/bin/python'} 10:57:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:14 INFO - Downloading/unpacking jsonschema==2.5.1 10:57:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:57:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:57:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:17 INFO - Downloading functools32-3.2.3-2.tar.gz 10:57:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:57:17 INFO - warning: no files found matching '*.txt' 10:57:17 INFO - no previously-included directories found matching 'build' 10:57:17 INFO - no previously-included directories found matching 'dist' 10:57:17 INFO - no previously-included directories found matching '.git*' 10:57:17 INFO - Installing collected packages: jsonschema, functools32 10:57:17 INFO - Running setup.py install for functools32 10:57:17 INFO - warning: no files found matching '*.txt' 10:57:17 INFO - no previously-included directories found matching 'build' 10:57:17 INFO - no previously-included directories found matching 'dist' 10:57:17 INFO - no previously-included directories found matching '.git*' 10:57:17 INFO - Successfully installed jsonschema functools32 10:57:17 INFO - Cleaning up... 10:57:17 INFO - Return code: 0 10:57:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:57:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:17 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ac31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e34c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e323f0>, '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 0x1e32cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e331a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1482864883.280121-828086330', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:57:17 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:57:17 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 functools32==3.2.3-2 10:57:17 INFO - Using env: {'DISPLAY': ':0', 10:57:17 INFO - 'HOME': '/home/cltbld', 10:57:17 INFO - 'LANG': 'en_US.UTF-8', 10:57:17 INFO - 'LANGUAGE': 'en_US:en', 10:57:17 INFO - 'LOGNAME': 'cltbld', 10:57:17 INFO - 'MAIL': '/var/mail/cltbld', 10:57:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:17 INFO - 'MOZ_NO_REMOTE': '1', 10:57:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:57:17 INFO - 'NO_EM_RESTART': '1', 10:57:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:57:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:17 INFO - 'PWD': '/builds/slave/test', 10:57:17 INFO - 'SHELL': '/bin/bash', 10:57:17 INFO - 'SHLVL': '1', 10:57:17 INFO - 'TERM': 'linux', 10:57:17 INFO - 'TMOUT': '86400', 10:57:17 INFO - 'USER': 'cltbld', 10:57:17 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1482864883.280121-828086330', 10:57:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:17 INFO - '_': '/tools/buildbot/bin/python'} 10:57:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:57:17 INFO - Cleaning up... 10:57:17 INFO - Return code: 0 10:57:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:57:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:17 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 0x1ac31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e34c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e323f0>, '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 0x1e32cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e331a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1482864883.280121-828086330', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:57:17 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 10:57:17 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 10:57:17 INFO - Using env: {'DISPLAY': ':0', 10:57:17 INFO - 'HOME': '/home/cltbld', 10:57:17 INFO - 'LANG': 'en_US.UTF-8', 10:57:17 INFO - 'LANGUAGE': 'en_US:en', 10:57:17 INFO - 'LOGNAME': 'cltbld', 10:57:17 INFO - 'MAIL': '/var/mail/cltbld', 10:57:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:17 INFO - 'MOZ_NO_REMOTE': '1', 10:57:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:57:17 INFO - 'NO_EM_RESTART': '1', 10:57:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:57:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:17 INFO - 'PWD': '/builds/slave/test', 10:57:17 INFO - 'SHELL': '/bin/bash', 10:57:17 INFO - 'SHLVL': '1', 10:57:17 INFO - 'TERM': 'linux', 10:57:17 INFO - 'TMOUT': '86400', 10:57:17 INFO - 'USER': 'cltbld', 10:57:17 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1482864883.280121-828086330', 10:57:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:17 INFO - '_': '/tools/buildbot/bin/python'} 10:57:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:17 INFO - Downloading/unpacking blobuploader==1.2.4 10:57:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:19 INFO - Downloading blobuploader-1.2.4.tar.gz 10:57:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:57:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:57:19 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 10:57:19 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 10:57:19 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 10:57:19 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 10:57:19 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 10:57:19 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 10:57:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:57:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:57:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:57:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:57:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:57:20 INFO - Downloading docopt-0.6.1.tar.gz 10:57:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:57:20 INFO - Installing collected packages: blobuploader, requests, docopt 10:57:20 INFO - Running setup.py install for blobuploader 10:57:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:57:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:57:20 INFO - Running setup.py install for requests 10:57:21 INFO - Running setup.py install for docopt 10:57:21 INFO - Successfully installed blobuploader requests docopt 10:57:21 INFO - Cleaning up... 10:57:21 INFO - Return code: 0 10:57:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:57:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:21 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 0x1ac31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e34c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e323f0>, '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 0x1e32cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e331a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1482864883.280121-828086330', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:57:21 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 10:57:21 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 10:57:21 INFO - Using env: {'DISPLAY': ':0', 10:57:21 INFO - 'HOME': '/home/cltbld', 10:57:21 INFO - 'LANG': 'en_US.UTF-8', 10:57:21 INFO - 'LANGUAGE': 'en_US:en', 10:57:21 INFO - 'LOGNAME': 'cltbld', 10:57:21 INFO - 'MAIL': '/var/mail/cltbld', 10:57:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:21 INFO - 'MOZ_NO_REMOTE': '1', 10:57:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:57:21 INFO - 'NO_EM_RESTART': '1', 10:57:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:57:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:21 INFO - 'PWD': '/builds/slave/test', 10:57:21 INFO - 'SHELL': '/bin/bash', 10:57:21 INFO - 'SHLVL': '1', 10:57:21 INFO - 'TERM': 'linux', 10:57:21 INFO - 'TMOUT': '86400', 10:57:21 INFO - 'USER': 'cltbld', 10:57:21 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1482864883.280121-828086330', 10:57:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:21 INFO - '_': '/tools/buildbot/bin/python'} 10:57:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:57:21 INFO - Running setup.py (path:/tmp/pip-mMrfDk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:57:21 INFO - Running setup.py (path:/tmp/pip-0Y6ESY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:57:21 INFO - Running setup.py (path:/tmp/pip-8Kp6xC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:57:21 INFO - Running setup.py (path:/tmp/pip-_ub5Ix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:57:21 INFO - Running setup.py (path:/tmp/pip-Eu7E4f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:57:21 INFO - Running setup.py (path:/tmp/pip-Hcf1o2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:57:21 INFO - Running setup.py (path:/tmp/pip-zxhhKg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:57:21 INFO - Running setup.py (path:/tmp/pip-_Bjgv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:57:21 INFO - Running setup.py (path:/tmp/pip-IMSonc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:57:22 INFO - Running setup.py (path:/tmp/pip-vWwVR4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:57:22 INFO - Running setup.py (path:/tmp/pip-JbS6jj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:57:22 INFO - Running setup.py (path:/tmp/pip-stWEck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:57:22 INFO - Running setup.py (path:/tmp/pip-W35WBW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:57:22 INFO - Running setup.py (path:/tmp/pip-1l66tQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:57:22 INFO - Running setup.py (path:/tmp/pip-0PxBeP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:57:22 INFO - Running setup.py (path:/tmp/pip-7QnAxs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:57:22 INFO - Running setup.py (path:/tmp/pip-U5AMoH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:57:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:57:22 INFO - Running setup.py install for manifestparser 10:57:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:57:22 INFO - Running setup.py install for mozcrash 10:57:22 INFO - Running setup.py install for mozdebug 10:57:22 INFO - Running setup.py install for mozdevice 10:57:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:57:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:57:23 INFO - Running setup.py install for mozfile 10:57:23 INFO - Running setup.py install for mozhttpd 10:57:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:57:23 INFO - Running setup.py install for mozinfo 10:57:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:57:23 INFO - Running setup.py install for mozInstall 10:57:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:57:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:57:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:57:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:57:23 INFO - Running setup.py install for mozleak 10:57:23 INFO - Running setup.py install for mozlog 10:57:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:57:23 INFO - Running setup.py install for moznetwork 10:57:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:57:23 INFO - Running setup.py install for mozprocess 10:57:23 INFO - Running setup.py install for mozprofile 10:57:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:57:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:57:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:57:24 INFO - Running setup.py install for mozrunner 10:57:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:57:24 INFO - Running setup.py install for mozscreenshot 10:57:24 INFO - Running setup.py install for moztest 10:57:24 INFO - Running setup.py install for mozversion 10:57:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:57:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:57:24 INFO - Cleaning up... 10:57:24 INFO - Return code: 0 10:57:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:57:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:24 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 0x1ac31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e34c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e323f0>, '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 0x1e32cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e331a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1482864883.280121-828086330', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:57:24 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 10:57:24 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 10:57:24 INFO - Using env: {'DISPLAY': ':0', 10:57:24 INFO - 'HOME': '/home/cltbld', 10:57:24 INFO - 'LANG': 'en_US.UTF-8', 10:57:24 INFO - 'LANGUAGE': 'en_US:en', 10:57:24 INFO - 'LOGNAME': 'cltbld', 10:57:24 INFO - 'MAIL': '/var/mail/cltbld', 10:57:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:24 INFO - 'MOZ_NO_REMOTE': '1', 10:57:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:57:24 INFO - 'NO_EM_RESTART': '1', 10:57:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:57:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:24 INFO - 'PWD': '/builds/slave/test', 10:57:24 INFO - 'SHELL': '/bin/bash', 10:57:24 INFO - 'SHLVL': '1', 10:57:24 INFO - 'TERM': 'linux', 10:57:24 INFO - 'TMOUT': '86400', 10:57:24 INFO - 'USER': 'cltbld', 10:57:24 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1482864883.280121-828086330', 10:57:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:24 INFO - '_': '/tools/buildbot/bin/python'} 10:57:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:57:24 INFO - Running setup.py (path:/tmp/pip-sgZva3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:57:24 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)) 10:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:57:24 INFO - Running setup.py (path:/tmp/pip-SDCbPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 10:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:57:24 INFO - Running setup.py (path:/tmp/pip-YWEc69-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:57:24 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)) 10:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:57:24 INFO - Running setup.py (path:/tmp/pip-VpbyO_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 10:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:57:24 INFO - Running setup.py (path:/tmp/pip-j1FId1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:57:25 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)) 10:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:57:25 INFO - Running setup.py (path:/tmp/pip-lZEahW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:57:25 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)) 10:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:57:25 INFO - Running setup.py (path:/tmp/pip-x7DuJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:57:25 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)) 10:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:57:25 INFO - Running setup.py (path:/tmp/pip-KEsfxK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:57:25 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)) 10:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:57:25 INFO - Running setup.py (path:/tmp/pip-Dg628c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:57:25 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)) 10:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:57:25 INFO - Running setup.py (path:/tmp/pip-VFyW_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:57:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 10:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:57:25 INFO - Running setup.py (path:/tmp/pip-_jAdU0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:57:25 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)) 10:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:57:25 INFO - Running setup.py (path:/tmp/pip-OjKtEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:57:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 10:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:57:25 INFO - Running setup.py (path:/tmp/pip-dRa0uC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:57:25 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)) 10:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:57:25 INFO - Running setup.py (path:/tmp/pip-52pSbp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:57:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 10:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:57:25 INFO - Running setup.py (path:/tmp/pip-LtqKSo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:57:25 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)) 10:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:57:25 INFO - Running setup.py (path:/tmp/pip-moZYk0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:57:25 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)) 10:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:57:25 INFO - Running setup.py (path:/tmp/pip-r1GeLV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:57:25 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)) 10:57:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:57:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:57:25 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)) 10:57:25 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:57:25 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:57:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:57: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 10:57: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 10:57: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 10:57: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 10:57: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 10:57: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 10:57:27 INFO - Downloading blessings-1.6.tar.gz 10:57:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:57:27 INFO - Installing collected packages: blessings 10:57:27 INFO - Running setup.py install for blessings 10:57:28 INFO - Successfully installed blessings 10:57:28 INFO - Cleaning up... 10:57:28 INFO - Return code: 0 10:57:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:57:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:57:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:57:28 INFO - Reading from file tmpfile_stdout 10:57:28 INFO - Current package versions: 10:57:28 INFO - argparse == 1.2.1 10:57:28 INFO - blessings == 1.6 10:57:28 INFO - blobuploader == 1.2.4 10:57:28 INFO - docopt == 0.6.1 10:57:28 INFO - functools32 == 3.2.3-2 10:57:28 INFO - jsonschema == 2.5.1 10:57:28 INFO - manifestparser == 1.1 10:57:28 INFO - mozInstall == 1.12 10:57:28 INFO - mozcrash == 1.0 10:57:28 INFO - mozdebug == 0.1 10:57:28 INFO - mozdevice == 0.49 10:57:28 INFO - mozfile == 1.2 10:57:28 INFO - mozhttpd == 0.7 10:57:28 INFO - mozinfo == 0.9 10:57:28 INFO - mozleak == 0.1 10:57:28 INFO - mozlog == 3.4 10:57:28 INFO - moznetwork == 0.27 10:57:28 INFO - mozprocess == 0.23 10:57:28 INFO - mozprofile == 0.28 10:57:28 INFO - mozrunner == 6.13 10:57:28 INFO - mozscreenshot == 0.1 10:57:28 INFO - mozsystemmonitor == 0.3 10:57:28 INFO - moztest == 0.7 10:57:28 INFO - mozversion == 1.4 10:57:28 INFO - psutil == 3.1.1 10:57:28 INFO - requests == 1.2.3 10:57:28 INFO - wsgiref == 0.1.2 10:57:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:57:28 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:28 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:28 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 0x1ac31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e34c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e323f0>, '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 0x1e32cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e331a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1482864883.280121-828086330', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:57:28 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 10:57:28 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 10:57:28 INFO - Using env: {'DISPLAY': ':0', 10:57:28 INFO - 'HOME': '/home/cltbld', 10:57:28 INFO - 'LANG': 'en_US.UTF-8', 10:57:28 INFO - 'LANGUAGE': 'en_US:en', 10:57:28 INFO - 'LOGNAME': 'cltbld', 10:57:28 INFO - 'MAIL': '/var/mail/cltbld', 10:57:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:28 INFO - 'MOZ_NO_REMOTE': '1', 10:57:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:57:28 INFO - 'NO_EM_RESTART': '1', 10:57:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:57:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:28 INFO - 'PWD': '/builds/slave/test', 10:57:28 INFO - 'SHELL': '/bin/bash', 10:57:28 INFO - 'SHLVL': '1', 10:57:28 INFO - 'TERM': 'linux', 10:57:28 INFO - 'TMOUT': '86400', 10:57:28 INFO - 'USER': 'cltbld', 10:57:28 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1482864883.280121-828086330', 10:57:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:28 INFO - '_': '/tools/buildbot/bin/python'} 10:57:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:28 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)) 10:57:28 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)) 10:57:28 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)) 10:57:28 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)) 10:57:28 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)) 10:57:28 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)) 10:57:28 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)) 10:57:28 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)) 10:57:28 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)) 10:57:28 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)) 10:57:28 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)) 10:57:28 INFO - Cleaning up... 10:57:28 INFO - Return code: 0 10:57:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:57:28 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:57:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:28 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:57:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:57:28 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 0x1ac31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e34c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e323f0>, '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 0x1e32cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e331a0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1482864883.280121-828086330', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 10:57:28 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 10:57:28 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 10:57:28 INFO - Using env: {'DISPLAY': ':0', 10:57:28 INFO - 'HOME': '/home/cltbld', 10:57:28 INFO - 'LANG': 'en_US.UTF-8', 10:57:28 INFO - 'LANGUAGE': 'en_US:en', 10:57:28 INFO - 'LOGNAME': 'cltbld', 10:57:28 INFO - 'MAIL': '/var/mail/cltbld', 10:57:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:28 INFO - 'MOZ_NO_REMOTE': '1', 10:57:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:57:28 INFO - 'NO_EM_RESTART': '1', 10:57:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:57:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:28 INFO - 'PWD': '/builds/slave/test', 10:57:28 INFO - 'SHELL': '/bin/bash', 10:57:28 INFO - 'SHLVL': '1', 10:57:28 INFO - 'TERM': 'linux', 10:57:28 INFO - 'TMOUT': '86400', 10:57:28 INFO - 'USER': 'cltbld', 10:57:28 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1482864883.280121-828086330', 10:57:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:28 INFO - '_': '/tools/buildbot/bin/python'} 10:57:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:57:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:57:28 INFO - Cleaning up... 10:57:28 INFO - Return code: 0 10:57:28 INFO - Running post-action listener: _resource_record_post_action 10:57:28 INFO - Running post-action listener: _start_resource_monitoring 10:57:28 INFO - Starting resource monitoring. 10:57:28 INFO - [mozharness: 2016-12-27 18:57:28.869840Z] Finished create-virtualenv step (success) 10:57:28 INFO - [mozharness: 2016-12-27 18:57:28.870370Z] Running install step. 10:57:28 INFO - Running pre-action listener: _resource_record_pre_action 10:57:28 INFO - Running main action method: install 10:57:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:57:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:57:29 INFO - Reading from file tmpfile_stdout 10:57:29 INFO - Detecting whether we're running mozinstall >=1.0... 10:57:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:57:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:57:29 INFO - Reading from file tmpfile_stdout 10:57:29 INFO - Output received: 10:57:29 INFO - Usage: mozinstall [options] installer 10:57:29 INFO - Options: 10:57:29 INFO - -h, --help show this help message and exit 10:57:29 INFO - -d DEST, --destination=DEST 10:57:29 INFO - Directory to install application into. [default: 10:57:29 INFO - "/builds/slave/test"] 10:57:29 INFO - --app=APP Application being installed. [default: firefox] 10:57:29 INFO - mkdir: /builds/slave/test/build/application 10:57:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 10:57:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 10:57:45 INFO - Reading from file tmpfile_stdout 10:57:45 INFO - Output received: 10:57:45 INFO - /builds/slave/test/build/application/firefox/firefox 10:57:45 INFO - Running post-action listener: _resource_record_post_action 10:57:45 INFO - [mozharness: 2016-12-27 18:57:45.243438Z] Finished install step (success) 10:57:45 INFO - [mozharness: 2016-12-27 18:57:45.243683Z] Running run-tests step. 10:57:45 INFO - Running pre-action listener: _resource_record_pre_action 10:57:45 INFO - Running main action method: run_tests 10:57:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:57:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:57:45 INFO - Python 2.7.3 10:57:45 INFO - Return code: 0 10:57:45 INFO - grabbing minidump binary from tooltool 10:57:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:57:45 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e323f0>, '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 0x1e32cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e331a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:57:45 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:57:45 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/linux64/releng.manifest -o -c /builds/tooltool_cache 10:57:45 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:57:45 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:57:45 INFO - Return code: 0 10:57:45 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:57:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:57:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:57:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:57:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:57:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AtSqGWIzSKSlNBraD7c1Xg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 10:57:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AtSqGWIzSKSlNBraD7c1Xg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-018 --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 10:57:45 INFO - Using env: {'DISPLAY': ':0', 10:57:45 INFO - 'HOME': '/home/cltbld', 10:57:45 INFO - 'LANG': 'en_US.UTF-8', 10:57:45 INFO - 'LANGUAGE': 'en_US:en', 10:57:45 INFO - 'LOGNAME': 'cltbld', 10:57:45 INFO - 'MAIL': '/var/mail/cltbld', 10:57:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:57:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:57:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:57:45 INFO - 'MOZ_NO_REMOTE': '1', 10:57:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:57:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:57:45 INFO - 'NO_EM_RESTART': '1', 10:57:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:57:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:57:45 INFO - 'PWD': '/builds/slave/test', 10:57:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:57:45 INFO - 'SHELL': '/bin/bash', 10:57:45 INFO - 'SHLVL': '1', 10:57:45 INFO - 'TERM': 'linux', 10:57:45 INFO - 'TMOUT': '86400', 10:57:45 INFO - 'USER': 'cltbld', 10:57:45 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1482864883.280121-828086330', 10:57:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:57:45 INFO - '_': '/tools/buildbot/bin/python'} 10:57:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AtSqGWIzSKSlNBraD7c1Xg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 10:57:45 INFO - mozversion application_buildid: 20161227104237 10:57:45 INFO - mozversion application_changeset: 545975b950e85f2acf1db2ee9b02a432829bc980 10:57:45 INFO - mozversion application_display_name: Nightly 10:57:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:57:45 INFO - mozversion application_name: Firefox 10:57:45 INFO - mozversion application_remotingname: firefox 10:57:45 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 10:57:45 INFO - mozversion application_vendor: Mozilla 10:57:45 INFO - mozversion application_version: 53.0a1 10:57:45 INFO - mozversion platform_buildid: 20161227104237 10:57:45 INFO - mozversion platform_changeset: 545975b950e85f2acf1db2ee9b02a432829bc980 10:57:45 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 10:57:45 INFO - mozversion platform_version: 53.0a1 10:57:45 INFO - using testdate: 1482865065 10:57:45 INFO - actual date: 1482865065 10:57:45 INFO - starting webserver on 'localhost:53724' 10:57:45 INFO - SUITE-START | Running 2 tests 10:57:45 INFO - TEST-START | dromaeo_css 10:57:45 INFO - operating with platform_type : linux_ 10:57:45 INFO - Initialising browser for dromaeo_css test... 10:57:45 INFO - TEST-INFO | started process 24741 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgf0atd/profile http://localhost:53724/getInfo.html) 10:57:46 INFO - PROCESS | 24741 | 1482865066036 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 10:57:49 INFO - PROCESS | 24741 | __metrics Screen width/height:1600/1200 10:57:49 INFO - PROCESS | 24741 | colorDepth:24 10:57:49 INFO - PROCESS | 24741 | Browser inner width/height: 1024/697 10:57:49 INFO - PROCESS | 24741 | __metrics 10:57:49 INFO - PROCESS | 24741 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:57:49 INFO - PROCESS | 24741 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:57:50 INFO - PROCESS | 24741 | console.error: PushService: 10:57:50 INFO - PROCESS | 24741 | stateChangeProcessEnqueue: Error transitioning state 10:57:50 INFO - PROCESS | 24741 | UnknownError 10:57:51 INFO - TEST-INFO | 24741: exit 0 10:57:51 INFO - Browser initialized. 10:57:51 INFO - Running cycle 1/1 for dromaeo_css test... 10:57:51 INFO - TEST-INFO | started process 24821 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgf0atd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:58:03 INFO - PROCESS | 24821 | RSS: Main: 151289856 10:58:03 INFO - PROCESS | 24821 | 11:01:07 INFO - PROCESS | 24821 | Cycle 1(1): loaded http://localhost:53724/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53724/tests/dromaeo/cssquery-ext.html) 11:01:09 INFO - PROCESS | 24821 | RSS: Main: 251031552 11:01:09 INFO - PROCESS | 24821 | 11:02:52 INFO - PROCESS | 24821 | ************************* 11:02:52 INFO - PROCESS | 24821 | A coding exception was thrown and uncaught in a Task. 11:02:52 INFO - PROCESS | 24821 | 11:02:52 INFO - PROCESS | 24821 | Full message: ReferenceError: fetch is not defined 11:02:52 INFO - PROCESS | 24821 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 11:02:52 INFO - PROCESS | 24821 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 11:02:52 INFO - PROCESS | 24821 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 11:02:52 INFO - PROCESS | 24821 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 11:02:52 INFO - PROCESS | 24821 | TaskImpl@resource://gre/modules/Task.jsm:277:3 11:02:52 INFO - PROCESS | 24821 | asyncFunction@resource://gre/modules/Task.jsm:252:14 11:02:52 INFO - PROCESS | 24821 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 11:02:52 INFO - PROCESS | 24821 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 11:02:52 INFO - PROCESS | 24821 | TaskImpl@resource://gre/modules/Task.jsm:277:3 11:02:52 INFO - PROCESS | 24821 | asyncFunction@resource://gre/modules/Task.jsm:252:14 11:02:52 INFO - PROCESS | 24821 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 11:02:52 INFO - PROCESS | 24821 | 11:02:52 INFO - PROCESS | 24821 | ************************* 11:04:12 INFO - PROCESS | 24821 | Cycle 1(1): loaded http://localhost:53724/tests/dromaeo/cssquery-ext.html (next: http://localhost:53724/tests/dromaeo/cssquery-jquery.html) 11:04:12 INFO - PROCESS | 24821 | RSS: Main: 233156608 11:04:12 INFO - PROCESS | 24821 | 11:07:15 INFO - PROCESS | 24821 | Cycle 1(1): loaded http://localhost:53724/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53724/tests/dromaeo/cssquery-mootools.html) 11:07:15 INFO - PROCESS | 24821 | RSS: Main: 183783424 11:07:15 INFO - PROCESS | 24821 | 11:10:18 INFO - PROCESS | 24821 | Cycle 1(1): loaded http://localhost:53724/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53724/tests/dromaeo/cssquery-prototype.html) 11:10:18 INFO - PROCESS | 24821 | RSS: Main: 185151488 11:10:18 INFO - PROCESS | 24821 | 11:13:21 INFO - PROCESS | 24821 | Cycle 1(1): loaded http://localhost:53724/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53724/tests/dromaeo/cssquery-yui.html) 11:13:22 INFO - PROCESS | 24821 | RSS: Main: 185372672 11:13:22 INFO - PROCESS | 24821 | 11:16:25 INFO - PROCESS | 24821 | Cycle 1(1): loaded http://localhost:53724/tests/dromaeo/cssquery-yui.html (next: http://localhost:53724/tests/dromaeo/cssquery-dojo.html) 11:16:25 INFO - PROCESS | 24821 | RSS: Main: 278953984 11:16:25 INFO - PROCESS | 24821 | 11:16:25 INFO - PROCESS | 24821 | __start_tp_report 11:16:25 INFO - PROCESS | 24821 | _x_x_mozilla_page_load 11:16:25 INFO - PROCESS | 24821 | _x_x_mozilla_page_load_details 11:16:25 INFO - PROCESS | 24821 | |i|pagename|runs| 11:16:25 INFO - PROCESS | 24821 | |0;dojo.html;2579.3164811324996;2608.008956596493;2627.763501284885;2641.696204936433;2648.1261183809343;4650.6744527882975;4687.390639216906;4794.280857871317;4794.784234709442;4802.711837289765;4752.738599377033;4788;4837.056773929076;4882.340883980662;4932.087563800699;4413.646908247332;4451.020775429401;4481.350204220403;4511.548845115479;4521.728696278217;4492.438445194351;4515.571020753023;4533.750643714594;4560.817567297304;4567.566081222279;4298.828046878152;4444.73331600105;4484.41702578666;4511.14288285228;4521.253993091153;4304.461942257218;4327.935080973788;4373.300271956504;4373.584509471595;4379.146066517031;4347.0653809430805;4350.238708226079;4351.956480435187;4352.847650332224;4353.434053573015;4766.30888521166;4800.807967681289;4833.73414462208;4849.684770489906;4870.050340183666;5185.170372740344;5194.92207616886;5201.765920533585;5202.167653175512;5251.947480525184;4982.701037937699;5224.39919409269;5227.032998895216;5241.056609810233;5245.423003469616;4679.485256621769;4803.543663351976;4865.878353041131;4871.3667223261455;4927.852164435073;5280.155175171954;5322;5331.120365139708;5332.813351532678;5333.173358129418;5065.138926382524;5121.4622464641425;5125.948740512547;5127.410347809975;5138.614603904647;5005.699658020458;5070.7464626768515;5083.110455670243;5085.160948443539;5170.224466330081;5084.186530155157;5086.694798312114;5106.591472682177;5109.565686916654;5126.6923984561045;5088.949110508922;5094.439611642716;5096.980603879239;5097.235414687752;5138.152204886152;4369.994901172685;4403.471583410012;4445.666575006822;4453.576910193527;4483.327500874895;3877.0695033192396;3885.2229554089295;3907.5310962684093;3917.334053210961;3923;4441.467023957146;4443.600075993111;4449.221386257392;4450.310201918708;4485.506594274627;5069.03688299222;5085.186370180753;5133.614978876524;5219.5041471060185;5289.9471005290225;4361.280388735887;4384.364267181305;4397.758123303249;4414.359917811964;4425.070735145656;4614.538546145359;4616.838410655611;4617.907641847211;4650.5349465053905;4651.418572678415;4741.905161896674;4794.760261986817;4801.975990120027;4804.8798780030775;4809;5132.486751324837;5163.070647283525;5194.662346947436;5197.8440646780655;5257.710825904612;4816.373871396696;4849.636277279147;4860.3681521401995;4864.537868902448;4915.016996600622;5005.899882002413;5011.173156429149;5036.899262014813;5058.1654027085115;5058.544730974083;5173.4826517347965;5176.870578235424;5187.870303242449;5193.558547523503;5198.272241886063;4985.700857948535;4986;4987.426445958761;4987.426445958761;5017.222330538772;4980.153373926369;4981.2030075187795;4981.476944920876;4984.70091794479;5015.874603134951;4798.568128868279;4833.2025215840395;4835.492273311393;4917.164082105979;4972.477889821516;5065.822696205615;5097.541221289954;5101.591872650105;5142.123125059401;5142.64001519905;4942.4316203637045;4963.329950456787;4964.4042714873;4965.453800081916;5008.474110218521 11:16:25 INFO - PROCESS | 24821 | |1;ext.html;517.4954419441045;557.5595279729014;566.5552541255115;566.6883214232171;584.1733946465748;29657.555136672967;39304.01739956499;41037.58962410375;41039.97400064996;44527.55472445283;41627.16745665085;41871;41910.82738865922;41912.37131443031;41988.37017444741;7588.58262795546;7790.9610451947665;7798.103218129902;7803.219678032208;7812.062552493709;7485.925140748577;7692.923070769277;9657.855132173023;9923.751906202331;9928.602855885792;10043.19654427644;10097.293189476739;10100.343477673965;10100.343477673965;10106.696799096038;10120.949395253014;10177.796444071115;10181.236407269484;10188.592456301812;10219.693409197736;9760.072793084717;9823.852642210373;9825.705228843075;9830;9835.45904975226;38898;39197.41203881916;39233.803830980665;39261;39316.21367572661;3706.4069748840056;3795.5445346558313;3812.4940648443708;3843.4811300474357;3852.57621661617;29257.561136582968;29413.852930735422;29565.408691826044;29836.104916852528;30000.249993750112;3453.5165076889257;3502.859885604573;3519.982400088009;3546.5034895114704;3573.26748016656;250201.7489912557;250393;250667;251297;251903.99999999817;55853.72073139608;56239.43760562342;56279.71860140755;56305.71847140738;56337.71831140818;59553.40446595565;59614.70192649009;59720;59720.40279597235;59728;12708.93645531785;12994.610161695164;13004.609861704164;13005.674858128434;13017.609471715863;9011.549422528979;9310.906890931139;9844.753881152885;9932.255080868963;9934.254930880214;17172.742408863876;17596.736048959276;17805.376811811784;18193.545161371072;18387.54031149232;6110.847228819315;6113.5414843886;6208.286047104551;6301.842453938597;6325.335839736854;784.5175217241356;794.5391672829745;810.3112354498746;828.5070383122153;837.1001173738256;28048.579271310948;28069.578956315672;28431.862725491163;28556.857215714204;28593.857030714713;881.5107615273456;959.7120863740989;960.9615615375446;965.1554889471712;983.7934033852971;14363.856361436252;14957.476488322856;15189.240537973057;15308.540743777705;15495.225238738243;340676;344353;344531;344584;344840;51014.48985510172;51161.744191278805;51389.7430512845;51409.48590514047;51513;1159.1538177130742;1204.747003129335;1208.3112625803208;1215.8358621585974;1218.1716432825763;25188.244352669448;26243.4751304969;27022;27461.588076179272;27486.450270994068;42545.57454425416;42927.785361072994;42968.78515607402;42971.3554296692;43008;282012;283850;284694;284830;285068;32627.18432039218;34190.82904585461;34617.480737789454;37173.25653486861;37529.24941501209;41732.16535669209;41886.581134188265;41998.1600367997;41999.370009449274;42228.36657450202;35050.474242885866;35766.105847354025;35863.10342241465;35979;36526.26947461089;35813.46279805753;35874.641253588175;36121.27757444889;36534.45198321974;36755.44866827053;34591.308173836886;34814;34831.30337393289;35006.47490287699;35057.64942350646;30350.84824575863;31587;31838.681613183573;31983.200419988756;32047.519287210245;23570.528589428457;24314.270571882873;24367;24444.755552444247;24554.140605078737 11:16:25 INFO - PROCESS | 24821 | |2;jquery.html;371.448399127296;373.0859394483515;374.4832131658311;377.6469001483611;387.4324115171274;15719.449819256313;16290.18549072546;16766.748498772515;16873.578160545992;16908.661826763488;14784;15954.680906381895;16218.7567186492;16244.187790610451;16271.51185464438;4780.378550788401;4995.125852975733;4998.400191976968;5015.348004759376;5085.389753229618;3828.5214348206473;4020.155767288873;4979.203327467606;5579.581531385142;5838.41615838417;5889.941100588982;5895;5903.586748927577;5905.055191169414;5907.793227237047;5700.657960522382;5866.76532938682;5902.793402230901;5903.704814759245;5914.349421563625;5119.8208062717995;5229.08491014071;5231.293775340357;5240.371155461369;5241.344831896013;19744.617876748634;20147.39557813268;20306.69539956902;20407.693884591743;20459.977001149884;2102.526931440429;2174.4889950861534;2213.2806837777784;2237.2393386248755;2313.5655893346066;9015.098490150933;9402.38884472507;9543.284253680933;10161.644342448053;12732.554360597334;1696.6521863018174;1749.518882307363;1754.2193723792918;1765.5056584156328;1768.7088425449365;65029;65372;65853.67073164652;66104.3389566103;66656.66671666659;9700.514974251259;9923.65267215644;9979.201663866874;10133.138683211862;10229.795404091876;13811;13826.585202443943;13831.30843457823;13838.792418113737;13850.792238116437;6622.642358316533;6810.965945170243;6869.107016087876;7032.402245809061;7039.648017599099;6355.777547785805;6370.77702280418;6559.409653131145;6587.209534855752;6605.042268871083;4228.873133805991;4233.936490952638;4343.04453020335;4383.693141480066;4401.383806267125;1691.1713260502265;1702.421176799894;1786.1069465267367;1813.8004819469847;1831.102759647763;767.5970115689307;772.9884051739228;782.3741007194221;792.4492477728039;793.5238856685942;14560;14802.111873287637;14886.181260030802;14980.625484362978;15686.137262450675;804.6137853830078;812.3338862133112;814.233717805042;817.464560712734;827.5407149032225;8400.621972011275;8436.409451338348;8670.393072484865;8740.388172827841;8755.649774008969;345350;345945;346885;349847;353634;66459.66770166215;66628;67593.66203168953;68068;69102.65448672725;884.1291328041874;894.458852394305;910.5629297937028;914.1133100892124;914.7987442762582;12634.305113218494;12997.480100795863;13402.0618556704;13792.724145517233;13859.30703464843;32445.35109297848;33210;33895.42411774379;34071;34459.310813783086;42615.7869210652;43748.12503749971;43767;46486.76756616195;46751.766241169935;6601.735930562723;7055.506114571931;7102.964485177541;7269.200387957426;7331.083614548182;10683.732906677395;11754.823677645014;11847.466863990969;12045.217060891015;12301.200421972542;9807.55865986018;10074.647387341702;10309.432981185806;10327.225458090523;10338.534765935401;9936.701898943043;10458.26792124544;10497.265191436527;10547.736306592089;10575.206859485415;9836.458994755358;10317.380957142595;10514.894851051391;10553.261271711213;10652.467376630982;7423.294786995227;7611.543307401574;7658.961705191438;7701.691932322644;7947.284744372803;9970;10003.199744020607;10190.694279171637;10195.643152489627;10205.132563732166 11:16:25 INFO - PROCESS | 24821 | |3;mootools.html;1160.7852547278753;1176.7469993951308;1183.7573297474014;1192.0404074719847;1210.7699537087954;6939.340762627548;7408.296211859864;7413.925860741391;7437.739679111225;7439.925600743991;6608.867822643544;6835.794926152211;7055.294470552955;7276.599787011713;7277.126744790633;2125.670521069233;2161.91352345906;2162.2107930605293;2164.588728141656;2169.8915054247304;2158.6006588781047;2198.56028794241;2213.5904857601313;2217.1463986365206;2224.7219097612797;1783.179737320834;1859.1355019915736;2087.9478013049643;2102.4323432673245;2170.511634882155;2146.796054374832;2150.1721837092678;2152.6878602602533;2155.9353219403365;2156.223759446598;1998.3105828489292;2014.4762361785895;2049.3851844446754;2085.436932028344;2090.6550419180817;7374.889376659355;7449.88825167623;7457.813554661123;7461.440391970569;7462.7388041418835;4237.6821738369745;4349.108432771274;4349.151915376503;4354.521002689701;4355.216061108999;2817.7323154300516;2980.3294258791816;3005.5341422079614;3009.428208640373;3048.908532744021;1058.4125810175349;1107.6012635451232;1124.4434005167413;1133.5295852221318;1144.3248483394837;9857.704268871945;9860.950695246478;9862.556184971694;9895.554700038587;9928.751781205456;3859.768413895119;4091.0590564170407;4153.605407486284;4157.189348077096;4173.207090652766;8322.542260175627;8325.83348333042;8343.249107580346;8356.331493480508;8357;4487.573680500347;4490.842820501267;4571.588557029883;4578.427696537933;4586.151561961048;3788.8295026723863;3873.748206366577;4040.878773636796;4181.958180418218;4183.6653067754505;3512.227309991798;3515.209077957439;3621.515178776689;3663.6519530644546;3667.94498082529;1177.935213563245;1207.3238986167773;1221.1757063981777;1229.2071613809044;1233.7039110613568;713.653877869233;723.7322190789441;738.1548127394103;751.8082888863305;755.5353457623522;4504.049847349991;4663.696859704109;4677.204875171229;4696.88257793551;4738.597219236334;490.31845734429174;504.4703061785111;506.25074889163506;856.5759948825247;893.1247377570018;2925.7366836984347;2966.881324746986;3014.653314868818;3040.969590304069;3095.5356696495705;8287.544185069879;8329;8466.492010479391;8571.485710857369;8574.356923230658;8638.611262493203;8852.822943541094;8861.379703420698;8862.512561809162;8901.643934242558;6623.437007854386;6785.762498312634;6820.113385259983;6833.145856767904;6840.3501667341525;4842.128416884994;4948.084604348207;5026.617680137992;5036.974815125901;5116.053530096795;8220.63007164667;8280.089190189196;8294.460860044077;8347.290480309242;8368.832623347376;7589.848203036019;7904.249096335839;7919.524828510308;8151.388645851704;8367.539785311867;5913.46778789911;6128.448439640454;6168.0439531872635;6321.715522801393;6327.936720632919;6721.327867213239;6923.342282483156;6999.0551275577145;7168.462365322726;7169.426445884213;6297.149884765498;6420.229572451336;6452.419282264619;6593;6655.334466553306;6191.721372538336;6204.3795620439405;6235.937640623717;6238.438540531374;6266.154069200599;5528.226048353154;5612.186232996111;5631.63394379364;5635.690037048002;5646.237757902795;3924.941125883171;3945.822437990355;3956.169204467094;3982.9885907617627;4086.468759061303;3820.7325487215626;3853.1908299256347;3875.4768106305287;3910.0811309342853;4004.2391945530258 11:16:25 INFO - PROCESS | 24821 | |4;prototype.html;352.8800207929304;354.6152424619288;355.3993750561552;364.3860095738679;371.8085186129142;4774.952250477499;4827.541383568564;4836.15367310721;4858.295547145662;4877.292947468384;4540.7502587357685;4577.702449340799;4679.391679081723;4713.457952335482;4773.260144677572;3796.7721936683747;3827.9617203827884;3837.5247965062517;3838.2899163654815;3844.5194350706092;3786.8674596389133;3792.1657235408165;3801.2397520495874;3857.903552411184;3864.942025869614;3674.797886116276;3679.3009328227686;3762.774233545996;3808.6762625176752;3832.55925568559;3800.809959502014;3803.4675145479655;3816.4084566891897;3828.889622009614;3851.788151651658;3435.209901722618;3487.110786749363;3493.1616412061176;3495.143689796016;3496.947545786815;4441.869061772582;4619.2284629222195;4653.185692503832;4667.136579732726;4679.883002924954;4326.372675961968;4327.480702315711;4327.567243275647;4336.588024137702;4343.2399330117105;4938.185199442088;4941.184704523749;4946.01079784041;4957;4979.427365852937;3773.3585290500682;3774.9245015099546;3779.6976241900584;3781;3793.3172029034763;5078.709522954459;5106.5659418949435;5128.769205385766;5134.409542902539;5173.896522069575;3825.9043523912123;3828.0238539172333;3833.7124715646437;3858.479105320802;3871.6709079727975;3907.433422153772;3923.8032400118236;3932.2135572884963;3935.5080614923136;3935.6225321137945;3973.324534829086;4214.651311580326;4352.608265256079;4355.978220108879;4361;4979.701217926936;4999.800007999639;5005.599552035828;5008.749562521859;5035.600969345677;1240.503798480615;1248.2572869142846;1254.9184303020274;1257.9056221087712;1258.6475786779715;818.1981657975217;839.131498898637;841.2008591837779;848.0501837941545;856.4775486953079;4178.7283826551175;4197.8320867165585;4199.223143718361;4203.708978606666;4208.242516347087;4842.685225460334;4858.44127925286;4892.437369702458;4898.456986049383;4901.411830580353;4104.523664943681;4154.608206250879;4166.395872598457;4184.602462766033;4209.158168366339;2538.6065159900245;2579.5614745493313;2609.8173127880864;2624.3439140214946;2626.649149011624;4548.795304211319;4565.931511027337;4588.38056862326;4599.919019030525;4631.305304204397;4867.245576935581;4986.775595098156;4991.702157439092;5007.674501157413;5053.343065401475;4582.702124361906;4603.424571928576;4610.124076425468;4613.308003799457;4617.607503362252;4889.87775305606;4910.21436570147;4913.091078150554;4921.286413469955;4925.261210818263;4644.0944015916575;4805.927911081407;4845.9757701211265;4859.2954021667365;4886.973735515581;4477.440319960005;4485.955140448685;4568.611668008123;4589.655775816894;4621.468531118964;3466.3240668069484;3484.390231709491;3489.7382696297373;3502.9649703502637;3537.0980399997834;4921.458639549575;4933.802647894187;4938.234573641092;4941.209406494943;4948.430930443045;4509.323601459807;4716.716996980193;4720.884216417286;4741.032471524417;4762.809487620457;4704.506026867267;4712.882177945578;4729.43246810385;4729.479757226771;4740.051989602024;4678.742669153093;4678.953210467988;4685.5782979532005;4694.178518759271;4712.62299016071;4775.71345719258;4782.497837726988;4816.301636262652;5023.342297042041;5052.8989420210655;4896.363472748662;4911.582515486224;4912.312276281395;4915.311856340187;4934.7416408815525 11:16:25 INFO - PROCESS | 24821 | |5;yui.html;654.5712558274332;675.5845155229536;727.0584592952126;749.5203070035177;768.224093665398;12237.326947017922;12935;13013.284269365182;13013.47946082158;13068.542601008967;12498.812517812217;12649.17780344277;12669.113162078658;12669.429875655595;12675.809862852064;649.1398896462183;667.9098321726577;767.2787579675088;782.6517199846056;801.0387534958045;791.3313250303513;807.0879905706544;807.422692774666;807.7536351412816;810.8054067023901;848.817504236589;849.2738708404318;849.3247867944998;849.5921957460423;849.6771226933756;679.8878185099453;700.6916956539106;801.6312496251732;847.9194476524751;849.961751721174;613.9416755408229;616.4051690119056;619.566303587486;620.1194304088206;622.2160078301328;12491.625251242478;12544;12672.936635316764;12866.292353920531;12896.80654790179;12348;12689.492420303177;12749.745005099847;12752.681182970407;13053.477860885554;12145.271283722917;12171.411566312883;12421.689457763538;12465.252084874937;12493.125481216319;4077.5922407758985;4174.686898482626;4196.307609244471;4196.832126714928;4302.397664326997;67515;68320.31679683238;68335.72460544005;68782.3121768786;69514.3048569518;10165.28842980999;10262.127719143957;10274.434906080238;10476.161907047417;10529.684109476728;17135.828641713422;18998.81001189998;19073.904630476758;19138.617227655646;19238.71141932872;6606.339366063451;6757.493188010919;6762.289959554274;6845.281245469254;6984.057152284478;3382.1713680148523;3493.371193185251;3513.1744040150543;3559.6440355964714;3618.638136186413;2867.885284588635;2879.1506505580905;2929.8242105473314;2940.7647388208893;2967;498.95010498949813;499.9925001124986;501.75664802570725;513.081583964699;514.326232635249;572.4418691775486;595.8152972578514;596.9074793406942;603.6348009454305;610.188449362347;7666.699990002977;7856.214378562212;8226.614281858314;8278.586070696441;8368.623411946477;463.31429484362656;474.3051429655502;476.5520410813824;488.7214287855888;510.74973263101555;3710.5510363160183;4154.584541545882;4277.080427708058;4293.4203882476095;4409.691321607521;67296;69340.30659693439;70384.6480767603;70812.64593677102;73234.63382683159;58995;59252.703736481904;59321.70339148277;63337.36662633407;63387.04919426212;54491.72754136283;55542;55766;55829.7208513963;55906.44093559094;7596.468247222641;7927.841443170989;7948.841023179389;8100.594970251346;8178.100408955129;33734.156646083065;34009.48985765166;34039.149021274665;34480.137996550286;34881.8255908729;40925.181496370504;43293;43632.34551481667;43983.340249895635;44064.33903491386;10675.679729608124;10712.89287107119;10717.057734790113;10945.343279403261;11025.062869655849;12574.772691019563;12627.052971027026;12710.936445317715;12980.935095324463;13277.933610331887;11706.941465292619;12049.457774400349;12170.14808963364;12454.564090256797;12874.742505149658;10807.513661885077;10909.454527273763;11445.828312575151;11579.478923448296;11657;11010.944945275543;11766.82349764737;11781.175317727677;11891.167618266638;11909.106816988933;8909.242714369351;8919.375643704878;9460;9499;9646.1521654932;8493.278071364004;8495.277901378206;9195.724128276162;9283.582238799136;9332.860007099764 11:16:25 INFO - PROCESS | 24821 | __end_tp_report 11:16:25 INFO - PROCESS | 24821 | __start_cc_report 11:16:25 INFO - PROCESS | 24821 | _x_x_mozilla_cycle_collect,1649 11:16:25 INFO - PROCESS | 24821 | __end_cc_report 11:16:25 INFO - PROCESS | 24821 | __startTimestamp1482866185471__endTimestamp 11:16:25 INFO - PROCESS | 24821 | 11:16:25 INFO - PROCESS | 24821 | ------- Summary: start ------- 11:16:25 INFO - PROCESS | 24821 | Number of tests: 6 11:16:25 INFO - PROCESS | 24821 | 11:16:25 INFO - PROCESS | 24821 | [#0] dojo.html Cycles:165 Average:4760.78 Median:4870.05 stddev:504.73 (10.4%) stddev-sans-first:476.38 11:16:25 INFO - PROCESS | 24821 | Values: 2579.3 2608.0 2627.8 2641.7 2648.1 4650.7 4687.4 4794.3 4794.8 4802.7 4752.7 4788.0 4837.1 4882.3 4932.1 4413.6 4451.0 4481.4 4511.5 4521.7 4492.4 4515.6 4533.8 4560.8 4567.6 4298.8 4444.7 4484.4 4511.1 4521.3 4304.5 4327.9 4373.3 4373.6 4379.1 4347.1 4350.2 4352.0 4352.8 4353.4 4766.3 4800.8 4833.7 4849.7 4870.1 5185.2 5194.9 5201.8 5202.2 5251.9 4982.7 5224.4 5227.0 5241.1 5245.4 4679.5 4803.5 4865.9 4871.4 4927.9 5280.2 5322.0 5331.1 5332.8 5333.2 5065.1 5121.5 5125.9 5127.4 5138.6 5005.7 5070.7 5083.1 5085.2 5170.2 5084.2 5086.7 5106.6 5109.6 5126.7 5088.9 5094.4 5097.0 5097.2 5138.2 4370.0 4403.5 4445.7 4453.6 4483.3 3877.1 3885.2 3907.5 3917.3 3923.0 4441.5 4443.6 4449.2 4450.3 4485.5 5069.0 5085.2 5133.6 5219.5 5289.9 4361.3 4384.4 4397.8 4414.4 4425.1 4614.5 4616.8 4617.9 4650.5 4651.4 4741.9 4794.8 4802.0 4804.9 4809.0 5132.5 5163.1 5194.7 5197.8 5257.7 4816.4 4849.6 4860.4 4864.5 4915.0 5005.9 5011.2 5036.9 5058.2 5058.5 5173.5 5176.9 5187.9 5193.6 5198.3 4985.7 4986.0 4987.4 4987.4 5017.2 4980.2 4981.2 4981.5 4984.7 5015.9 4798.6 4833.2 4835.5 4917.2 4972.5 5065.8 5097.5 5101.6 5142.1 5142.6 4942.4 4963.3 4964.4 4965.5 5008.5 11:16:25 INFO - PROCESS | 24821 | 11:16:25 INFO - PROCESS | 24821 | [#1] ext.html Cycles:180 Average:45911.93 Median:36323.77 stddev:77336.06 (212.9%) stddev-sans-first:77477.90 11:16:25 INFO - PROCESS | 24821 | Values: 517.5 557.6 566.6 566.7 584.2 29657.6 39304.0 41037.6 41040.0 44527.6 41627.2 41871.0 41910.8 41912.4 41988.4 7588.6 7791.0 7798.1 7803.2 7812.1 7485.9 7692.9 9657.9 9923.8 9928.6 10043.2 10097.3 10100.3 10100.3 10106.7 10120.9 10177.8 10181.2 10188.6 10219.7 9760.1 9823.9 9825.7 9830.0 9835.5 38898.0 39197.4 39233.8 39261.0 39316.2 3706.4 3795.5 3812.5 3843.5 3852.6 29257.6 29413.9 29565.4 29836.1 30000.2 3453.5 3502.9 3520.0 3546.5 3573.3 250201.7 250393.0 250667.0 251297.0 251904.0 55853.7 56239.4 56279.7 56305.7 56337.7 59553.4 59614.7 59720.0 59720.4 59728.0 12708.9 12994.6 13004.6 13005.7 13017.6 9011.5 9310.9 9844.8 9932.3 9934.3 17172.7 17596.7 17805.4 18193.5 18387.5 6110.8 6113.5 6208.3 6301.8 6325.3 784.5 794.5 810.3 828.5 837.1 28048.6 28069.6 28431.9 28556.9 28593.9 881.5 959.7 961.0 965.2 983.8 14363.9 14957.5 15189.2 15308.5 15495.2 340676.0 344353.0 344531.0 344584.0 344840.0 51014.5 51161.7 51389.7 51409.5 51513.0 1159.2 1204.7 1208.3 1215.8 1218.2 25188.2 26243.5 27022.0 27461.6 27486.5 42545.6 42927.8 42968.8 42971.4 43008.0 282012.0 283850.0 284694.0 284830.0 285068.0 32627.2 34190.8 34617.5 37173.3 37529.2 41732.2 41886.6 41998.2 41999.4 42228.4 35050.5 35766.1 35863.1 35979.0 36526.3 35813.5 35874.6 36121.3 36534.5 36755.4 34591.3 34814.0 34831.3 35006.5 35057.6 30350.8 31587.0 31838.7 31983.2 32047.5 23570.5 24314.3 24367.0 24444.8 24554.1 11:16:25 INFO - PROCESS | 24821 | 11:16:25 INFO - PROCESS | 24821 | [#2] jquery.html Cycles:180 Average:22337.29 Median:24045.58 stddev:57448.36 (238.9%) stddev-sans-first:57585.85 11:16:25 INFO - PROCESS | 24821 | Values: 371.4 373.1 374.5 377.6 387.4 15719.4 16290.2 16766.7 16873.6 16908.7 14784.0 15954.7 16218.8 16244.2 16271.5 4780.4 4995.1 4998.4 5015.3 5085.4 3828.5 4020.2 4979.2 5579.6 5838.4 5889.9 5895.0 5903.6 5905.1 5907.8 5700.7 5866.8 5902.8 5903.7 5914.3 5119.8 5229.1 5231.3 5240.4 5241.3 19744.6 20147.4 20306.7 20407.7 20460.0 2102.5 2174.5 2213.3 2237.2 2313.6 9015.1 9402.4 9543.3 10161.6 12732.6 1696.7 1749.5 1754.2 1765.5 1768.7 65029.0 65372.0 65853.7 66104.3 66656.7 9700.5 9923.7 9979.2 10133.1 10229.8 13811.0 13826.6 13831.3 13838.8 13850.8 6622.6 6811.0 6869.1 7032.4 7039.6 6355.8 6370.8 6559.4 6587.2 6605.0 4228.9 4233.9 4343.0 4383.7 4401.4 1691.2 1702.4 1786.1 1813.8 1831.1 767.6 773.0 782.4 792.4 793.5 14560.0 14802.1 14886.2 14980.6 15686.1 804.6 812.3 814.2 817.5 827.5 8400.6 8436.4 8670.4 8740.4 8755.6 345350.0 345945.0 346885.0 349847.0 353634.0 66459.7 66628.0 67593.7 68068.0 69102.7 884.1 894.5 910.6 914.1 914.8 12634.3 12997.5 13402.1 13792.7 13859.3 32445.4 33210.0 33895.4 34071.0 34459.3 42615.8 43748.1 43767.0 46486.8 46751.8 6601.7 7055.5 7103.0 7269.2 7331.1 10683.7 11754.8 11847.5 12045.2 12301.2 9807.6 10074.6 10309.4 10327.2 10338.5 9936.7 10458.3 10497.3 10547.7 10575.2 9836.5 10317.4 10514.9 10553.3 10652.5 7423.3 7611.5 7659.0 7701.7 7947.3 9970.0 10003.2 10190.7 10195.6 10205.1 11:16:25 INFO - PROCESS | 24821 | 11:16:25 INFO - PROCESS | 24821 | [#3] mootools.html Cycles:180 Average:4760.05 Median:2666.22 stddev:2618.17 (98.2%) stddev-sans-first:2611.54 11:16:25 INFO - PROCESS | 24821 | Values: 1160.8 1176.7 1183.8 1192.0 1210.8 6939.3 7408.3 7413.9 7437.7 7439.9 6608.9 6835.8 7055.3 7276.6 7277.1 2125.7 2161.9 2162.2 2164.6 2169.9 2158.6 2198.6 2213.6 2217.1 2224.7 1783.2 1859.1 2087.9 2102.4 2170.5 2146.8 2150.2 2152.7 2155.9 2156.2 1998.3 2014.5 2049.4 2085.4 2090.7 7374.9 7449.9 7457.8 7461.4 7462.7 4237.7 4349.1 4349.2 4354.5 4355.2 2817.7 2980.3 3005.5 3009.4 3048.9 1058.4 1107.6 1124.4 1133.5 1144.3 9857.7 9861.0 9862.6 9895.6 9928.8 3859.8 4091.1 4153.6 4157.2 4173.2 8322.5 8325.8 8343.2 8356.3 8357.0 4487.6 4490.8 4571.6 4578.4 4586.2 3788.8 3873.7 4040.9 4182.0 4183.7 3512.2 3515.2 3621.5 3663.7 3667.9 1177.9 1207.3 1221.2 1229.2 1233.7 713.7 723.7 738.2 751.8 755.5 4504.0 4663.7 4677.2 4696.9 4738.6 490.3 504.5 506.3 856.6 893.1 2925.7 2966.9 3014.7 3041.0 3095.5 8287.5 8329.0 8466.5 8571.5 8574.4 8638.6 8852.8 8861.4 8862.5 8901.6 6623.4 6785.8 6820.1 6833.1 6840.4 4842.1 4948.1 5026.6 5037.0 5116.1 8220.6 8280.1 8294.5 8347.3 8368.8 7589.8 7904.2 7919.5 8151.4 8367.5 5913.5 6128.4 6168.0 6321.7 6327.9 6721.3 6923.3 6999.1 7168.5 7169.4 6297.1 6420.2 6452.4 6593.0 6655.3 6191.7 6204.4 6235.9 6238.4 6266.2 5528.2 5612.2 5631.6 5635.7 5646.2 3924.9 3945.8 3956.2 3983.0 4086.5 3820.7 3853.2 3875.5 3910.1 4004.2 11:16:25 INFO - PROCESS | 24821 | 11:16:25 INFO - PROCESS | 24821 | [#4] prototype.html Cycles:180 Average:4086.00 Median:4589.02 stddev:1139.38 (24.8%) stddev-sans-first:1107.59 11:16:25 INFO - PROCESS | 24821 | Values: 352.9 354.6 355.4 364.4 371.8 4775.0 4827.5 4836.2 4858.3 4877.3 4540.8 4577.7 4679.4 4713.5 4773.3 3796.8 3828.0 3837.5 3838.3 3844.5 3786.9 3792.2 3801.2 3857.9 3864.9 3674.8 3679.3 3762.8 3808.7 3832.6 3800.8 3803.5 3816.4 3828.9 3851.8 3435.2 3487.1 3493.2 3495.1 3496.9 4441.9 4619.2 4653.2 4667.1 4679.9 4326.4 4327.5 4327.6 4336.6 4343.2 4938.2 4941.2 4946.0 4957.0 4979.4 3773.4 3774.9 3779.7 3781.0 3793.3 5078.7 5106.6 5128.8 5134.4 5173.9 3825.9 3828.0 3833.7 3858.5 3871.7 3907.4 3923.8 3932.2 3935.5 3935.6 3973.3 4214.7 4352.6 4356.0 4361.0 4979.7 4999.8 5005.6 5008.7 5035.6 1240.5 1248.3 1254.9 1257.9 1258.6 818.2 839.1 841.2 848.1 856.5 4178.7 4197.8 4199.2 4203.7 4208.2 4842.7 4858.4 4892.4 4898.5 4901.4 4104.5 4154.6 4166.4 4184.6 4209.2 2538.6 2579.6 2609.8 2624.3 2626.6 4548.8 4565.9 4588.4 4599.9 4631.3 4867.2 4986.8 4991.7 5007.7 5053.3 4582.7 4603.4 4610.1 4613.3 4617.6 4889.9 4910.2 4913.1 4921.3 4925.3 4644.1 4805.9 4846.0 4859.3 4887.0 4477.4 4486.0 4568.6 4589.7 4621.5 3466.3 3484.4 3489.7 3503.0 3537.1 4921.5 4933.8 4938.2 4941.2 4948.4 4509.3 4716.7 4720.9 4741.0 4762.8 4704.5 4712.9 4729.4 4729.5 4740.1 4678.7 4679.0 4685.6 4694.2 4712.6 4775.7 4782.5 4816.3 5023.3 5052.9 4896.4 4911.6 4912.3 4915.3 4934.7 11:16:25 INFO - PROCESS | 24821 | 11:16:25 INFO - PROCESS | 24821 | [#5] yui.html Cycles:180 Average:15154.70 Median:468.81 stddev:19447.62 (4148.3%) stddev-sans-first:19471.70 11:16:25 INFO - PROCESS | 24821 | Values: 654.6 675.6 727.1 749.5 768.2 12237.3 12935.0 13013.3 13013.5 13068.5 12498.8 12649.2 12669.1 12669.4 12675.8 649.1 667.9 767.3 782.7 801.0 791.3 807.1 807.4 807.8 810.8 848.8 849.3 849.3 849.6 849.7 679.9 700.7 801.6 847.9 850.0 613.9 616.4 619.6 620.1 622.2 12491.6 12544.0 12672.9 12866.3 12896.8 12348.0 12689.5 12749.7 12752.7 13053.5 12145.3 12171.4 12421.7 12465.3 12493.1 4077.6 4174.7 4196.3 4196.8 4302.4 67515.0 68320.3 68335.7 68782.3 69514.3 10165.3 10262.1 10274.4 10476.2 10529.7 17135.8 18998.8 19073.9 19138.6 19238.7 6606.3 6757.5 6762.3 6845.3 6984.1 3382.2 3493.4 3513.2 3559.6 3618.6 2867.9 2879.2 2929.8 2940.8 2967.0 499.0 500.0 501.8 513.1 514.3 572.4 595.8 596.9 603.6 610.2 7666.7 7856.2 8226.6 8278.6 8368.6 463.3 474.3 476.6 488.7 510.7 3710.6 4154.6 4277.1 4293.4 4409.7 67296.0 69340.3 70384.6 70812.6 73234.6 58995.0 59252.7 59321.7 63337.4 63387.0 54491.7 55542.0 55766.0 55829.7 55906.4 7596.5 7927.8 7948.8 8100.6 8178.1 33734.2 34009.5 34039.1 34480.1 34881.8 40925.2 43293.0 43632.3 43983.3 44064.3 10675.7 10712.9 10717.1 10945.3 11025.1 12574.8 12627.1 12710.9 12980.9 13277.9 11706.9 12049.5 12170.1 12454.6 12874.7 10807.5 10909.5 11445.8 11579.5 11657.0 11010.9 11766.8 11781.2 11891.2 11909.1 8909.2 8919.4 9460.0 9499.0 9646.2 8493.3 8495.3 9195.7 9283.6 9332.9 11:16:25 INFO - PROCESS | 24821 | -------- Summary: end -------- 11:16:25 INFO - PROCESS | 24821 | 11:16:26 INFO - TEST-INFO | 24821: exit 0 11:16:26 INFO - TEST-OK | dromaeo_css | took 1120802ms 11:16:26 INFO - TEST-START | kraken 11:16:26 INFO - operating with platform_type : linux_ 11:16:26 INFO - Initialising browser for kraken test... 11:16:26 INFO - TEST-INFO | started process 25119 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpx0Kz1g/profile http://localhost:53724/getInfo.html) 11:16:26 INFO - PROCESS | 25119 | 1482866186806 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 11:16:30 INFO - PROCESS | 25119 | __metrics Screen width/height:1600/1200 11:16:30 INFO - PROCESS | 25119 | colorDepth:24 11:16:30 INFO - PROCESS | 25119 | Browser inner width/height: 1024/697 11:16:30 INFO - PROCESS | 25119 | __metrics 11:16:30 INFO - PROCESS | 25119 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:16:30 INFO - PROCESS | 25119 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:16:31 INFO - PROCESS | 25119 | console.error: PushService: 11:16:31 INFO - PROCESS | 25119 | stateChangeProcessEnqueue: Error transitioning state 11:16:31 INFO - PROCESS | 25119 | UnknownError 11:16:31 INFO - TEST-INFO | 25119: exit 0 11:16:31 INFO - Browser initialized. 11:16:31 INFO - Running cycle 1/1 for kraken test... 11:16:31 INFO - TEST-INFO | started process 25203 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpx0Kz1g/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:16:43 INFO - PROCESS | 25203 | RSS: Main: 150892544 11:16:43 INFO - PROCESS | 25203 | 11:16:46 INFO - PROCESS | 25203 | Cycle 1(1): loaded http://localhost:53724/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53724/tests/kraken/driver.html?testName=audio-beat-detection) 11:16:47 INFO - PROCESS | 25203 | RSS: Main: 205045760 11:16:47 INFO - PROCESS | 25203 | 11:16:50 INFO - PROCESS | 25203 | Cycle 1(1): loaded http://localhost:53724/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53724/tests/kraken/driver.html?testName=audio-dft) 11:16:50 INFO - PROCESS | 25203 | RSS: Main: 185421824 11:16:50 INFO - PROCESS | 25203 | 11:16:53 INFO - PROCESS | 25203 | Cycle 1(1): loaded http://localhost:53724/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53724/tests/kraken/driver.html?testName=audio-fft) 11:16:54 INFO - PROCESS | 25203 | RSS: Main: 267702272 11:16:54 INFO - PROCESS | 25203 | 11:16:56 INFO - PROCESS | 25203 | Cycle 1(1): loaded http://localhost:53724/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53724/tests/kraken/driver.html?testName=audio-oscillator) 11:16:57 INFO - PROCESS | 25203 | RSS: Main: 178393088 11:16:57 INFO - PROCESS | 25203 | 11:16:59 INFO - PROCESS | 25203 | Cycle 1(1): loaded http://localhost:53724/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53724/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:17:00 INFO - PROCESS | 25203 | RSS: Main: 181620736 11:17:00 INFO - PROCESS | 25203 | 11:17:03 INFO - PROCESS | 25203 | Cycle 1(1): loaded http://localhost:53724/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53724/tests/kraken/driver.html?testName=imaging-darkroom) 11:17:03 INFO - PROCESS | 25203 | RSS: Main: 222019584 11:17:03 INFO - PROCESS | 25203 | 11:17:07 INFO - PROCESS | 25203 | Cycle 1(1): loaded http://localhost:53724/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53724/tests/kraken/driver.html?testName=imaging-desaturate) 11:17:07 INFO - PROCESS | 25203 | RSS: Main: 215920640 11:17:07 INFO - PROCESS | 25203 | 11:17:10 INFO - PROCESS | 25203 | Cycle 1(1): loaded http://localhost:53724/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53724/tests/kraken/driver.html?testName=json-parse-financial) 11:17:11 INFO - PROCESS | 25203 | RSS: Main: 216756224 11:17:11 INFO - PROCESS | 25203 | 11:17:13 INFO - PROCESS | 25203 | Cycle 1(1): loaded http://localhost:53724/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53724/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:17:13 INFO - PROCESS | 25203 | RSS: Main: 180523008 11:17:13 INFO - PROCESS | 25203 | 11:17:15 INFO - PROCESS | 25203 | Cycle 1(1): loaded http://localhost:53724/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53724/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:17:16 INFO - PROCESS | 25203 | RSS: Main: 188043264 11:17:16 INFO - PROCESS | 25203 | 11:17:18 INFO - PROCESS | 25203 | Cycle 1(1): loaded http://localhost:53724/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53724/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:17:18 INFO - PROCESS | 25203 | RSS: Main: 196608000 11:17:18 INFO - PROCESS | 25203 | 11:17:21 INFO - PROCESS | 25203 | Cycle 1(1): loaded http://localhost:53724/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53724/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:17:22 INFO - PROCESS | 25203 | RSS: Main: 205524992 11:17:22 INFO - PROCESS | 25203 | 11:17:25 INFO - PROCESS | 25203 | Cycle 1(1): loaded http://localhost:53724/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53724/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:17:26 INFO - PROCESS | 25203 | RSS: Main: 201850880 11:17:26 INFO - PROCESS | 25203 | 11:17:27 INFO - PROCESS | 25203 | Cycle 1(1): loaded http://localhost:53724/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53724/tests/kraken/driver.html?testName=ai-astar) 11:17:28 INFO - PROCESS | 25203 | RSS: Main: 253837312 11:17:28 INFO - PROCESS | 25203 | 11:17:28 INFO - PROCESS | 25203 | __start_tp_report 11:17:28 INFO - PROCESS | 25203 | _x_x_mozilla_page_load 11:17:28 INFO - PROCESS | 25203 | _x_x_mozilla_page_load_details 11:17:28 INFO - PROCESS | 25203 | |i|pagename|runs| 11:17:28 INFO - PROCESS | 25203 | |0;ai-astar;113;117;115;116;115;116;113;115;116;114 11:17:28 INFO - PROCESS | 25203 | |1;audio-beat-detection;155;126;124;164;127;129;125;127;162;132 11:17:28 INFO - PROCESS | 25203 | |2;audio-dft;161;160;162;161;162;162;163;162;160;162 11:17:28 INFO - PROCESS | 25203 | |3;audio-fft;77;76;76;105;76;76;78;77;91;76 11:17:28 INFO - PROCESS | 25203 | |4;audio-oscillator;86;85;112;84;84;84;100;84;85;108 11:17:28 INFO - PROCESS | 25203 | |5;imaging-gaussian-blur;117;119;117;117;117;117;117;117;117;119 11:17:28 INFO - PROCESS | 25203 | |6;imaging-darkroom;150;149;150;150;150;150;149;149;149;149 11:17:28 INFO - PROCESS | 25203 | |7;imaging-desaturate;95;95;95;96;96;96;96;97;95;96 11:17:28 INFO - PROCESS | 25203 | |8;json-parse-financial;66;66;67;66;65;68;68;66;67;67 11:17:28 INFO - PROCESS | 25203 | |9;json-stringify-tinderbox;67;69;68;68;67;68;68;69;69;67 11:17:28 INFO - PROCESS | 25203 | |10;stanford-crypto-aes;85;85;84;85;85;85;84;86;85;84 11:17:28 INFO - PROCESS | 25203 | |11;stanford-crypto-ccm;148;144;133;137;140;133;144;141;179;136 11:17:28 INFO - PROCESS | 25203 | |12;stanford-crypto-pbkdf2;175;178;182;173;178;173;177;176;178;182 11:17:28 INFO - PROCESS | 25203 | |13;stanford-crypto-sha256-iterative;68;68;68;67;68;68;67;68;66;66 11:17:28 INFO - PROCESS | 25203 | __end_tp_report 11:17:28 INFO - PROCESS | 25203 | __start_cc_report 11:17:28 INFO - PROCESS | 25203 | _x_x_mozilla_cycle_collect,1807 11:17:28 INFO - PROCESS | 25203 | __end_cc_report 11:17:28 INFO - PROCESS | 25203 | __startTimestamp1482866248201__endTimestamp 11:17:28 INFO - PROCESS | 25203 | 11:17:28 INFO - PROCESS | 25203 | ------- Summary: start ------- 11:17:28 INFO - PROCESS | 25203 | Number of tests: 14 11:17:28 INFO - PROCESS | 25203 | 11:17:28 INFO - PROCESS | 25203 | [#0] ai-astar Cycles:10 Average:115.00 Median:115.50 stddev:1.33 (1.2%) stddev-sans-first:1.20 11:17:28 INFO - PROCESS | 25203 | Values: 113.0 117.0 115.0 116.0 115.0 116.0 113.0 115.0 116.0 114.0 11:17:28 INFO - PROCESS | 25203 | 11:17:28 INFO - PROCESS | 25203 | [#1] audio-beat-detection Cycles:10 Average:137.10 Median:130.50 stddev:16.33 (12.5%) stddev-sans-first:15.99 11:17:28 INFO - PROCESS | 25203 | Values: 155.0 126.0 124.0 164.0 127.0 129.0 125.0 127.0 162.0 132.0 11:17:28 INFO - PROCESS | 25203 | 11:17:28 INFO - PROCESS | 25203 | [#2] audio-dft Cycles:10 Average:161.50 Median:162.00 stddev:0.97 (0.6%) stddev-sans-first:1.01 11:17:28 INFO - PROCESS | 25203 | Values: 161.0 160.0 162.0 161.0 162.0 162.0 163.0 162.0 160.0 162.0 11:17:28 INFO - PROCESS | 25203 | 11:17:28 INFO - PROCESS | 25203 | [#3] audio-fft Cycles:10 Average:80.80 Median:76.50 stddev:9.67 (12.6%) stddev-sans-first:10.16 11:17:28 INFO - PROCESS | 25203 | Values: 77.0 76.0 76.0 105.0 76.0 76.0 78.0 77.0 91.0 76.0 11:17:28 INFO - PROCESS | 25203 | 11:17:28 INFO - PROCESS | 25203 | [#4] audio-oscillator Cycles:10 Average:91.20 Median:84.00 stddev:11.07 (13.2%) stddev-sans-first:11.58 11:17:28 INFO - PROCESS | 25203 | Values: 86.0 85.0 112.0 84.0 84.0 84.0 100.0 84.0 85.0 108.0 11:17:28 INFO - PROCESS | 25203 | 11:17:28 INFO - PROCESS | 25203 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.00 stddev:0.84 (0.7%) stddev-sans-first:0.88 11:17:28 INFO - PROCESS | 25203 | Values: 117.0 119.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 119.0 11:17:28 INFO - PROCESS | 25203 | 11:17:28 INFO - PROCESS | 25203 | [#6] imaging-darkroom Cycles:10 Average:149.50 Median:150.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 11:17:28 INFO - PROCESS | 25203 | Values: 150.0 149.0 150.0 150.0 150.0 150.0 149.0 149.0 149.0 149.0 11:17:28 INFO - PROCESS | 25203 | 11:17:28 INFO - PROCESS | 25203 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.67 11:17:28 INFO - PROCESS | 25203 | Values: 95.0 95.0 95.0 96.0 96.0 96.0 96.0 97.0 95.0 96.0 11:17:28 INFO - PROCESS | 25203 | 11:17:28 INFO - PROCESS | 25203 | [#8] json-parse-financial Cycles:10 Average:66.60 Median:67.00 stddev:0.97 (1.4%) stddev-sans-first:1.00 11:17:28 INFO - PROCESS | 25203 | Values: 66.0 66.0 67.0 66.0 65.0 68.0 68.0 66.0 67.0 67.0 11:17:28 INFO - PROCESS | 25203 | 11:17:28 INFO - PROCESS | 25203 | [#9] json-stringify-tinderbox Cycles:10 Average:68.00 Median:68.00 stddev:0.82 (1.2%) stddev-sans-first:0.78 11:17:28 INFO - PROCESS | 25203 | Values: 67.0 69.0 68.0 68.0 67.0 68.0 68.0 69.0 69.0 67.0 11:17:28 INFO - PROCESS | 25203 | 11:17:28 INFO - PROCESS | 25203 | [#10] stanford-crypto-aes Cycles:10 Average:84.80 Median:85.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 11:17:28 INFO - PROCESS | 25203 | Values: 85.0 85.0 84.0 85.0 85.0 85.0 84.0 86.0 85.0 84.0 11:17:28 INFO - PROCESS | 25203 | 11:17:28 INFO - PROCESS | 25203 | [#11] stanford-crypto-ccm Cycles:10 Average:143.50 Median:142.50 stddev:13.41 (9.4%) stddev-sans-first:14.12 11:17:28 INFO - PROCESS | 25203 | Values: 148.0 144.0 133.0 137.0 140.0 133.0 144.0 141.0 179.0 136.0 11:17:28 INFO - PROCESS | 25203 | 11:17:28 INFO - PROCESS | 25203 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.20 Median:178.00 stddev:3.16 (1.8%) stddev-sans-first:3.24 11:17:28 INFO - PROCESS | 25203 | Values: 175.0 178.0 182.0 173.0 178.0 173.0 177.0 176.0 178.0 182.0 11:17:28 INFO - PROCESS | 25203 | 11:17:28 INFO - PROCESS | 25203 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.00 stddev:0.84 (1.2%) stddev-sans-first:0.87 11:17:28 INFO - PROCESS | 25203 | Values: 68.0 68.0 68.0 67.0 68.0 68.0 67.0 68.0 66.0 66.0 11:17:28 INFO - PROCESS | 25203 | -------- Summary: end -------- 11:17:28 INFO - PROCESS | 25203 | 11:17:29 INFO - TEST-INFO | 25203: exit 0 11:17:29 INFO - TEST-OK | kraken | took 63045ms 11:17:29 INFO - SUITE-END | took 1183s 11:17:29 INFO - Completed test suite (00:19:44) 11:17:29 INFO - javascript benchmark 11:17:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2579.3164811324996, 2608.008956596493, 2627.763501284885, 2641.696204936433, 2648.1261183809343, 4650.6744527882975, 4687.390639216906, 4794.280857871317, 4794.784234709442, 4802.711837289765, 4752.738599377033, 4788.0, 4837.056773929076, 4882.340883980662, 4932.087563800699, 4413.646908247332, 4451.020775429401, 4481.350204220403, 4511.548845115479, 4521.728696278217, 4492.438445194351, 4515.571020753023, 4533.750643714594, 4560.817567297304, 4567.566081222279, 4298.828046878152, 4444.73331600105, 4484.41702578666, 4511.14288285228, 4521.253993091153, 4304.461942257218, 4327.935080973788, 4373.300271956504, 4373.584509471595, 4379.146066517031, 4347.0653809430805, 4350.238708226079, 4351.956480435187, 4352.847650332224, 4353.434053573015, 4766.30888521166, 4800.807967681289, 4833.73414462208, 4849.684770489906, 4870.050340183666, 5185.170372740344, 5194.92207616886, 5201.765920533585, 5202.167653175512, 5251.947480525184, 4982.701037937699, 5224.39919409269, 5227.032998895216, 5241.056609810233, 5245.423003469616, 4679.485256621769, 4803.543663351976, 4865.878353041131, 4871.3667223261455, 4927.852164435073, 5280.155175171954, 5322.0, 5331.120365139708, 5332.813351532678, 5333.173358129418, 5065.138926382524, 5121.4622464641425, 5125.948740512547, 5127.410347809975, 5138.614603904647, 5005.699658020458, 5070.7464626768515, 5083.110455670243, 5085.160948443539, 5170.224466330081, 5084.186530155157, 5086.694798312114, 5106.591472682177, 5109.565686916654, 5126.6923984561045, 5088.949110508922, 5094.439611642716, 5096.980603879239, 5097.235414687752, 5138.152204886152, 4369.994901172685, 4403.471583410012, 4445.666575006822, 4453.576910193527, 4483.327500874895, 3877.0695033192396, 3885.2229554089295, 3907.5310962684093, 3917.334053210961, 3923.0, 4441.467023957146, 4443.600075993111, 4449.221386257392, 4450.310201918708, 4485.506594274627, 5069.03688299222, 5085.186370180753, 5133.614978876524, 5219.5041471060185, 5289.9471005290225, 4361.280388735887, 4384.364267181305, 4397.758123303249, 4414.359917811964, 4425.070735145656, 4614.538546145359, 4616.838410655611, 4617.907641847211, 4650.5349465053905, 4651.418572678415, 4741.905161896674, 4794.760261986817, 4801.975990120027, 4804.8798780030775, 4809.0, 5132.486751324837, 5163.070647283525, 5194.662346947436, 5197.8440646780655, 5257.710825904612, 4816.373871396696, 4849.636277279147, 4860.3681521401995, 4864.537868902448, 4915.016996600622, 5005.899882002413, 5011.173156429149, 5036.899262014813, 5058.1654027085115, 5058.544730974083, 5173.4826517347965, 5176.870578235424, 5187.870303242449, 5193.558547523503, 5198.272241886063, 4985.700857948535, 4986.0, 4987.426445958761, 4987.426445958761, 5017.222330538772, 4980.153373926369, 4981.2030075187795, 4981.476944920876, 4984.70091794479, 5015.874603134951, 4798.568128868279, 4833.2025215840395, 4835.492273311393, 4917.164082105979, 4972.477889821516, 5065.822696205615, 5097.541221289954, 5101.591872650105, 5142.123125059401, 5142.64001519905, 4942.4316203637045, 4963.329950456787, 4964.4042714873, 4965.453800081916, 5008.474110218521], "value": 4727.682454672095, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [517.4954419441045, 557.5595279729014, 566.5552541255115, 566.6883214232171, 584.1733946465748, 29657.555136672967, 39304.01739956499, 41037.58962410375, 41039.97400064996, 44527.55472445283, 41627.16745665085, 41871.0, 41910.82738865922, 41912.37131443031, 41988.37017444741, 7588.58262795546, 7790.9610451947665, 7798.103218129902, 7803.219678032208, 7812.062552493709, 7485.925140748577, 7692.923070769277, 9657.855132173023, 9923.751906202331, 9928.602855885792, 10043.19654427644, 10097.293189476739, 10100.343477673965, 10100.343477673965, 10106.696799096038, 10120.949395253014, 10177.796444071115, 10181.236407269484, 10188.592456301812, 10219.693409197736, 9760.072793084717, 9823.852642210373, 9825.705228843075, 9830.0, 9835.45904975226, 38898.0, 39197.41203881916, 39233.803830980665, 39261.0, 39316.21367572661, 3706.4069748840056, 3795.5445346558313, 3812.4940648443708, 3843.4811300474357, 3852.57621661617, 29257.561136582968, 29413.852930735422, 29565.408691826044, 29836.104916852528, 30000.249993750112, 3453.5165076889257, 3502.859885604573, 3519.982400088009, 3546.5034895114704, 3573.26748016656, 250201.7489912557, 250393.0, 250667.0, 251297.0, 251903.99999999817, 55853.72073139608, 56239.43760562342, 56279.71860140755, 56305.71847140738, 56337.71831140818, 59553.40446595565, 59614.70192649009, 59720.0, 59720.40279597235, 59728.0, 12708.93645531785, 12994.610161695164, 13004.609861704164, 13005.674858128434, 13017.609471715863, 9011.549422528979, 9310.906890931139, 9844.753881152885, 9932.255080868963, 9934.254930880214, 17172.742408863876, 17596.736048959276, 17805.376811811784, 18193.545161371072, 18387.54031149232, 6110.847228819315, 6113.5414843886, 6208.286047104551, 6301.842453938597, 6325.335839736854, 784.5175217241356, 794.5391672829745, 810.3112354498746, 828.5070383122153, 837.1001173738256, 28048.579271310948, 28069.578956315672, 28431.862725491163, 28556.857215714204, 28593.857030714713, 881.5107615273456, 959.7120863740989, 960.9615615375446, 965.1554889471712, 983.7934033852971, 14363.856361436252, 14957.476488322856, 15189.240537973057, 15308.540743777705, 15495.225238738243, 340676.0, 344353.0, 344531.0, 344584.0, 344840.0, 51014.48985510172, 51161.744191278805, 51389.7430512845, 51409.48590514047, 51513.0, 1159.1538177130742, 1204.747003129335, 1208.3112625803208, 1215.8358621585974, 1218.1716432825763, 25188.244352669448, 26243.4751304969, 27022.0, 27461.588076179272, 27486.450270994068, 42545.57454425416, 42927.785361072994, 42968.78515607402, 42971.3554296692, 43008.0, 282012.0, 283850.0, 284694.0, 284830.0, 285068.0, 32627.18432039218, 34190.82904585461, 34617.480737789454, 37173.25653486861, 37529.24941501209, 41732.16535669209, 41886.581134188265, 41998.1600367997, 41999.370009449274, 42228.36657450202, 35050.474242885866, 35766.105847354025, 35863.10342241465, 35979.0, 36526.26947461089, 35813.46279805753, 35874.641253588175, 36121.27757444889, 36534.45198321974, 36755.44866827053, 34591.308173836886, 34814.0, 34831.30337393289, 35006.47490287699, 35057.64942350646, 30350.84824575863, 31587.0, 31838.681613183573, 31983.200419988756, 32047.519287210245, 23570.528589428457, 24314.270571882873, 24367.0, 24444.755552444247, 24554.140605078737], "value": 17928.20360767586, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.448399127296, 373.0859394483515, 374.4832131658311, 377.6469001483611, 387.4324115171274, 15719.449819256313, 16290.18549072546, 16766.748498772515, 16873.578160545992, 16908.661826763488, 14784.0, 15954.680906381895, 16218.7567186492, 16244.187790610451, 16271.51185464438, 4780.378550788401, 4995.125852975733, 4998.400191976968, 5015.348004759376, 5085.389753229618, 3828.5214348206473, 4020.155767288873, 4979.203327467606, 5579.581531385142, 5838.41615838417, 5889.941100588982, 5895.0, 5903.586748927577, 5905.055191169414, 5907.793227237047, 5700.657960522382, 5866.76532938682, 5902.793402230901, 5903.704814759245, 5914.349421563625, 5119.8208062717995, 5229.08491014071, 5231.293775340357, 5240.371155461369, 5241.344831896013, 19744.617876748634, 20147.39557813268, 20306.69539956902, 20407.693884591743, 20459.977001149884, 2102.526931440429, 2174.4889950861534, 2213.2806837777784, 2237.2393386248755, 2313.5655893346066, 9015.098490150933, 9402.38884472507, 9543.284253680933, 10161.644342448053, 12732.554360597334, 1696.6521863018174, 1749.518882307363, 1754.2193723792918, 1765.5056584156328, 1768.7088425449365, 65029.0, 65372.0, 65853.67073164652, 66104.3389566103, 66656.66671666659, 9700.514974251259, 9923.65267215644, 9979.201663866874, 10133.138683211862, 10229.795404091876, 13811.0, 13826.585202443943, 13831.30843457823, 13838.792418113737, 13850.792238116437, 6622.642358316533, 6810.965945170243, 6869.107016087876, 7032.402245809061, 7039.648017599099, 6355.777547785805, 6370.77702280418, 6559.409653131145, 6587.209534855752, 6605.042268871083, 4228.873133805991, 4233.936490952638, 4343.04453020335, 4383.693141480066, 4401.383806267125, 1691.1713260502265, 1702.421176799894, 1786.1069465267367, 1813.8004819469847, 1831.102759647763, 767.5970115689307, 772.9884051739228, 782.3741007194221, 792.4492477728039, 793.5238856685942, 14560.0, 14802.111873287637, 14886.181260030802, 14980.625484362978, 15686.137262450675, 804.6137853830078, 812.3338862133112, 814.233717805042, 817.464560712734, 827.5407149032225, 8400.621972011275, 8436.409451338348, 8670.393072484865, 8740.388172827841, 8755.649774008969, 345350.0, 345945.0, 346885.0, 349847.0, 353634.0, 66459.66770166215, 66628.0, 67593.66203168953, 68068.0, 69102.65448672725, 884.1291328041874, 894.458852394305, 910.5629297937028, 914.1133100892124, 914.7987442762582, 12634.305113218494, 12997.480100795863, 13402.0618556704, 13792.724145517233, 13859.30703464843, 32445.35109297848, 33210.0, 33895.42411774379, 34071.0, 34459.310813783086, 42615.7869210652, 43748.12503749971, 43767.0, 46486.76756616195, 46751.766241169935, 6601.735930562723, 7055.506114571931, 7102.964485177541, 7269.200387957426, 7331.083614548182, 10683.732906677395, 11754.823677645014, 11847.466863990969, 12045.217060891015, 12301.200421972542, 9807.55865986018, 10074.647387341702, 10309.432981185806, 10327.225458090523, 10338.534765935401, 9936.701898943043, 10458.26792124544, 10497.265191436527, 10547.736306592089, 10575.206859485415, 9836.458994755358, 10317.380957142595, 10514.894851051391, 10553.261271711213, 10652.467376630982, 7423.294786995227, 7611.543307401574, 7658.961705191438, 7701.691932322644, 7947.284744372803, 9970.0, 10003.199744020607, 10190.694279171637, 10195.643152489627, 10205.132563732166], "value": 7952.846507476819, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1160.7852547278753, 1176.7469993951308, 1183.7573297474014, 1192.0404074719847, 1210.7699537087954, 6939.340762627548, 7408.296211859864, 7413.925860741391, 7437.739679111225, 7439.925600743991, 6608.867822643544, 6835.794926152211, 7055.294470552955, 7276.599787011713, 7277.126744790633, 2125.670521069233, 2161.91352345906, 2162.2107930605293, 2164.588728141656, 2169.8915054247304, 2158.6006588781047, 2198.56028794241, 2213.5904857601313, 2217.1463986365206, 2224.7219097612797, 1783.179737320834, 1859.1355019915736, 2087.9478013049643, 2102.4323432673245, 2170.511634882155, 2146.796054374832, 2150.1721837092678, 2152.6878602602533, 2155.9353219403365, 2156.223759446598, 1998.3105828489292, 2014.4762361785895, 2049.3851844446754, 2085.436932028344, 2090.6550419180817, 7374.889376659355, 7449.88825167623, 7457.813554661123, 7461.440391970569, 7462.7388041418835, 4237.6821738369745, 4349.108432771274, 4349.151915376503, 4354.521002689701, 4355.216061108999, 2817.7323154300516, 2980.3294258791816, 3005.5341422079614, 3009.428208640373, 3048.908532744021, 1058.4125810175349, 1107.6012635451232, 1124.4434005167413, 1133.5295852221318, 1144.3248483394837, 9857.704268871945, 9860.950695246478, 9862.556184971694, 9895.554700038587, 9928.751781205456, 3859.768413895119, 4091.0590564170407, 4153.605407486284, 4157.189348077096, 4173.207090652766, 8322.542260175627, 8325.83348333042, 8343.249107580346, 8356.331493480508, 8357.0, 4487.573680500347, 4490.842820501267, 4571.588557029883, 4578.427696537933, 4586.151561961048, 3788.8295026723863, 3873.748206366577, 4040.878773636796, 4181.958180418218, 4183.6653067754505, 3512.227309991798, 3515.209077957439, 3621.515178776689, 3663.6519530644546, 3667.94498082529, 1177.935213563245, 1207.3238986167773, 1221.1757063981777, 1229.2071613809044, 1233.7039110613568, 713.653877869233, 723.7322190789441, 738.1548127394103, 751.8082888863305, 755.5353457623522, 4504.049847349991, 4663.696859704109, 4677.204875171229, 4696.88257793551, 4738.597219236334, 490.31845734429174, 504.4703061785111, 506.25074889163506, 856.5759948825247, 893.1247377570018, 2925.7366836984347, 2966.881324746986, 3014.653314868818, 3040.969590304069, 3095.5356696495705, 8287.544185069879, 8329.0, 8466.492010479391, 8571.485710857369, 8574.356923230658, 8638.611262493203, 8852.822943541094, 8861.379703420698, 8862.512561809162, 8901.643934242558, 6623.437007854386, 6785.762498312634, 6820.113385259983, 6833.145856767904, 6840.3501667341525, 4842.128416884994, 4948.084604348207, 5026.617680137992, 5036.974815125901, 5116.053530096795, 8220.63007164667, 8280.089190189196, 8294.460860044077, 8347.290480309242, 8368.832623347376, 7589.848203036019, 7904.249096335839, 7919.524828510308, 8151.388645851704, 8367.539785311867, 5913.46778789911, 6128.448439640454, 6168.0439531872635, 6321.715522801393, 6327.936720632919, 6721.327867213239, 6923.342282483156, 6999.0551275577145, 7168.462365322726, 7169.426445884213, 6297.149884765498, 6420.229572451336, 6452.419282264619, 6593.0, 6655.334466553306, 6191.721372538336, 6204.3795620439405, 6235.937640623717, 6238.438540531374, 6266.154069200599, 5528.226048353154, 5612.186232996111, 5631.63394379364, 5635.690037048002, 5646.237757902795, 3924.941125883171, 3945.822437990355, 3956.169204467094, 3982.9885907617627, 4086.468759061303, 3820.7325487215626, 3853.1908299256347, 3875.4768106305287, 3910.0811309342853, 4004.2391945530258], "value": 3854.4978960778562, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.8800207929304, 354.6152424619288, 355.3993750561552, 364.3860095738679, 371.8085186129142, 4774.952250477499, 4827.541383568564, 4836.15367310721, 4858.295547145662, 4877.292947468384, 4540.7502587357685, 4577.702449340799, 4679.391679081723, 4713.457952335482, 4773.260144677572, 3796.7721936683747, 3827.9617203827884, 3837.5247965062517, 3838.2899163654815, 3844.5194350706092, 3786.8674596389133, 3792.1657235408165, 3801.2397520495874, 3857.903552411184, 3864.942025869614, 3674.797886116276, 3679.3009328227686, 3762.774233545996, 3808.6762625176752, 3832.55925568559, 3800.809959502014, 3803.4675145479655, 3816.4084566891897, 3828.889622009614, 3851.788151651658, 3435.209901722618, 3487.110786749363, 3493.1616412061176, 3495.143689796016, 3496.947545786815, 4441.869061772582, 4619.2284629222195, 4653.185692503832, 4667.136579732726, 4679.883002924954, 4326.372675961968, 4327.480702315711, 4327.567243275647, 4336.588024137702, 4343.2399330117105, 4938.185199442088, 4941.184704523749, 4946.01079784041, 4957.0, 4979.427365852937, 3773.3585290500682, 3774.9245015099546, 3779.6976241900584, 3781.0, 3793.3172029034763, 5078.709522954459, 5106.5659418949435, 5128.769205385766, 5134.409542902539, 5173.896522069575, 3825.9043523912123, 3828.0238539172333, 3833.7124715646437, 3858.479105320802, 3871.6709079727975, 3907.433422153772, 3923.8032400118236, 3932.2135572884963, 3935.5080614923136, 3935.6225321137945, 3973.324534829086, 4214.651311580326, 4352.608265256079, 4355.978220108879, 4361.0, 4979.701217926936, 4999.800007999639, 5005.599552035828, 5008.749562521859, 5035.600969345677, 1240.503798480615, 1248.2572869142846, 1254.9184303020274, 1257.9056221087712, 1258.6475786779715, 818.1981657975217, 839.131498898637, 841.2008591837779, 848.0501837941545, 856.4775486953079, 4178.7283826551175, 4197.8320867165585, 4199.223143718361, 4203.708978606666, 4208.242516347087, 4842.685225460334, 4858.44127925286, 4892.437369702458, 4898.456986049383, 4901.411830580353, 4104.523664943681, 4154.608206250879, 4166.395872598457, 4184.602462766033, 4209.158168366339, 2538.6065159900245, 2579.5614745493313, 2609.8173127880864, 2624.3439140214946, 2626.649149011624, 4548.795304211319, 4565.931511027337, 4588.38056862326, 4599.919019030525, 4631.305304204397, 4867.245576935581, 4986.775595098156, 4991.702157439092, 5007.674501157413, 5053.343065401475, 4582.702124361906, 4603.424571928576, 4610.124076425468, 4613.308003799457, 4617.607503362252, 4889.87775305606, 4910.21436570147, 4913.091078150554, 4921.286413469955, 4925.261210818263, 4644.0944015916575, 4805.927911081407, 4845.9757701211265, 4859.2954021667365, 4886.973735515581, 4477.440319960005, 4485.955140448685, 4568.611668008123, 4589.655775816894, 4621.468531118964, 3466.3240668069484, 3484.390231709491, 3489.7382696297373, 3502.9649703502637, 3537.0980399997834, 4921.458639549575, 4933.802647894187, 4938.234573641092, 4941.209406494943, 4948.430930443045, 4509.323601459807, 4716.716996980193, 4720.884216417286, 4741.032471524417, 4762.809487620457, 4704.506026867267, 4712.882177945578, 4729.43246810385, 4729.479757226771, 4740.051989602024, 4678.742669153093, 4678.953210467988, 4685.5782979532005, 4694.178518759271, 4712.62299016071, 4775.71345719258, 4782.497837726988, 4816.301636262652, 5023.342297042041, 5052.8989420210655, 4896.363472748662, 4911.582515486224, 4912.312276281395, 4915.311856340187, 4934.7416408815525], "value": 3738.5872350182894, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [654.5712558274332, 675.5845155229536, 727.0584592952126, 749.5203070035177, 768.224093665398, 12237.326947017922, 12935.0, 13013.284269365182, 13013.47946082158, 13068.542601008967, 12498.812517812217, 12649.17780344277, 12669.113162078658, 12669.429875655595, 12675.809862852064, 649.1398896462183, 667.9098321726577, 767.2787579675088, 782.6517199846056, 801.0387534958045, 791.3313250303513, 807.0879905706544, 807.422692774666, 807.7536351412816, 810.8054067023901, 848.817504236589, 849.2738708404318, 849.3247867944998, 849.5921957460423, 849.6771226933756, 679.8878185099453, 700.6916956539106, 801.6312496251732, 847.9194476524751, 849.961751721174, 613.9416755408229, 616.4051690119056, 619.566303587486, 620.1194304088206, 622.2160078301328, 12491.625251242478, 12544.0, 12672.936635316764, 12866.292353920531, 12896.80654790179, 12348.0, 12689.492420303177, 12749.745005099847, 12752.681182970407, 13053.477860885554, 12145.271283722917, 12171.411566312883, 12421.689457763538, 12465.252084874937, 12493.125481216319, 4077.5922407758985, 4174.686898482626, 4196.307609244471, 4196.832126714928, 4302.397664326997, 67515.0, 68320.31679683238, 68335.72460544005, 68782.3121768786, 69514.3048569518, 10165.28842980999, 10262.127719143957, 10274.434906080238, 10476.161907047417, 10529.684109476728, 17135.828641713422, 18998.81001189998, 19073.904630476758, 19138.617227655646, 19238.71141932872, 6606.339366063451, 6757.493188010919, 6762.289959554274, 6845.281245469254, 6984.057152284478, 3382.1713680148523, 3493.371193185251, 3513.1744040150543, 3559.6440355964714, 3618.638136186413, 2867.885284588635, 2879.1506505580905, 2929.8242105473314, 2940.7647388208893, 2967.0, 498.95010498949813, 499.9925001124986, 501.75664802570725, 513.081583964699, 514.326232635249, 572.4418691775486, 595.8152972578514, 596.9074793406942, 603.6348009454305, 610.188449362347, 7666.699990002977, 7856.214378562212, 8226.614281858314, 8278.586070696441, 8368.623411946477, 463.31429484362656, 474.3051429655502, 476.5520410813824, 488.7214287855888, 510.74973263101555, 3710.5510363160183, 4154.584541545882, 4277.080427708058, 4293.4203882476095, 4409.691321607521, 67296.0, 69340.30659693439, 70384.6480767603, 70812.64593677102, 73234.63382683159, 58995.0, 59252.703736481904, 59321.70339148277, 63337.36662633407, 63387.04919426212, 54491.72754136283, 55542.0, 55766.0, 55829.7208513963, 55906.44093559094, 7596.468247222641, 7927.841443170989, 7948.841023179389, 8100.594970251346, 8178.100408955129, 33734.156646083065, 34009.48985765166, 34039.149021274665, 34480.137996550286, 34881.8255908729, 40925.181496370504, 43293.0, 43632.34551481667, 43983.340249895635, 44064.33903491386, 10675.679729608124, 10712.89287107119, 10717.057734790113, 10945.343279403261, 11025.062869655849, 12574.772691019563, 12627.052971027026, 12710.936445317715, 12980.935095324463, 13277.933610331887, 11706.941465292619, 12049.457774400349, 12170.14808963364, 12454.564090256797, 12874.742505149658, 10807.513661885077, 10909.454527273763, 11445.828312575151, 11579.478923448296, 11657.0, 11010.944945275543, 11766.82349764737, 11781.175317727677, 11891.167618266638, 11909.106816988933, 8909.242714369351, 8919.375643704878, 9460.0, 9499.0, 9646.1521654932, 8493.278071364004, 8495.277901378206, 9195.724128276162, 9283.582238799136, 9332.860007099764], "value": 6286.601247003568, "unit": "score"}], "name": "dromaeo_css", "value": 6275.409162288197, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 117.0, 115.0, 116.0, 115.0, 116.0, 113.0, 115.0, 116.0, 114.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 126.0, 124.0, 164.0, 127.0, 129.0, 125.0, 127.0, 162.0, 132.0], "value": 137.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 162.0, 161.0, 162.0, 162.0, 163.0, 162.0, 160.0, 162.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 76.0, 105.0, 76.0, 76.0, 78.0, 77.0, 91.0, 76.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 112.0, 84.0, 84.0, 84.0, 100.0, 84.0, 85.0, 108.0], "value": 91.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 119.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 119.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 149.0, 150.0, 150.0, 150.0, 150.0, 149.0, 149.0, 149.0, 149.0], "value": 149.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 97.0, 95.0, 96.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 66.0, 65.0, 68.0, 68.0, 66.0, 67.0, 67.0], "value": 66.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 68.0, 68.0, 67.0, 68.0, 68.0, 69.0, 69.0, 67.0], "value": 68.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 84.0, 85.0, 85.0, 85.0, 84.0, 86.0, 85.0, 84.0], "value": 84.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 144.0, 133.0, 137.0, 140.0, 133.0, 144.0, 141.0, 179.0, 136.0], "value": 143.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 178.0, 182.0, 173.0, 178.0, 173.0, 177.0, 176.0, 178.0, 182.0], "value": 177.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 67.0, 68.0, 68.0, 67.0, 68.0, 66.0, 66.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1555.7000000000003, "alertThreshold": 2.0}]} 11:17:29 INFO - Return code: 0 11:17:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:17:30 INFO - # TBPL SUCCESS # 11:17:30 INFO - Running post-action listener: _resource_record_post_action 11:17:30 INFO - [mozharness: 2016-12-27 19:17:30.037232Z] Finished run-tests step (success) 11:17:30 INFO - Running post-run listener: _resource_record_post_run 11:17:30 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 4096; Write bytes: 382623744; Read time: 12; Write time: 305896 11:17:30 INFO - TinderboxPrint: CPU usage
13.1% 11:17:30 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 11:17:30 INFO - TinderboxPrint: I/O write bytes / time
382,623,744 / 305,896 11:17:30 INFO - TinderboxPrint: CPU idle
8,328.1 (86.9%) 11:17:30 INFO - TinderboxPrint: CPU user
1,214.2 (12.7%) 11:17:30 INFO - TinderboxPrint: Swap in / out
0 / 0 11:17:30 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99889152; Read time: 0; Write time: 85940 11:17:30 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 0; Write bytes: 282734592; Read time: 0; Write time: 219956 11:17:30 INFO - Running post-run listener: _upload_blobber_files 11:17:30 INFO - Blob upload gear active. 11:17:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:17:30 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 11:17:30 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:17:30 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:17:31 INFO - (blobuploader) - INFO - Open directory for files ... 11:17:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:17:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:17:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:17:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:17:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:17:31 INFO - (blobuploader) - INFO - Done attempting. 11:17:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:17:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:17:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:17:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:17:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:17:32 INFO - (blobuploader) - INFO - Done attempting. 11:17:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:17:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:17:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:17:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:17:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:17:35 INFO - (blobuploader) - INFO - Done attempting. 11:17:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:17:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:17:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:17:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:17:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:17:36 INFO - (blobuploader) - INFO - Done attempting. 11:17:36 INFO - (blobuploader) - INFO - Iteration through files over. 11:17:36 INFO - Return code: 0 11:17:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:17:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:17:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3dca960618e7ada1ef78b653fda348491f323a8a854f08c3591b02464cb2080eadb993b8e18abb1f4f200be8b672769143af913ead87524357a26d200bca6863", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4fd9b118c0605bde06a71400e046886f04cc621db0e72cda509a3449bb6bf871f011ce9566a1508748e3570410c98a43d0132cb731a650fd88e59d427cb16d23", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f303b65d56cab8ecfb56df6f9314a4512fb967bf4aaa7bb408463de974a19896b80a021935bb46463390f90d37826c23a2495effdb5fff58df41a877a1168394", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/2749475eb27b8a1cabef83cc53564a78f8d1adad106e0eef846ffe32c1d5f4bf93eb93c8bf2bb4d9f6fe6b6bec4733d342b463877fb1d0d724a9cf5b4fa1344b"} 11:17:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:17:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:17:36 INFO - Contents: 11:17:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3dca960618e7ada1ef78b653fda348491f323a8a854f08c3591b02464cb2080eadb993b8e18abb1f4f200be8b672769143af913ead87524357a26d200bca6863", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4fd9b118c0605bde06a71400e046886f04cc621db0e72cda509a3449bb6bf871f011ce9566a1508748e3570410c98a43d0132cb731a650fd88e59d427cb16d23", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f303b65d56cab8ecfb56df6f9314a4512fb967bf4aaa7bb408463de974a19896b80a021935bb46463390f90d37826c23a2495effdb5fff58df41a877a1168394", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/2749475eb27b8a1cabef83cc53564a78f8d1adad106e0eef846ffe32c1d5f4bf93eb93c8bf2bb4d9f6fe6b6bec4733d342b463877fb1d0d724a9cf5b4fa1344b"} 11:17:36 INFO - Running post-run listener: copy_logs_to_upload_dir 11:17:36 INFO - Copying logs to upload dir... 11:17:36 INFO - mkdir: /builds/slave/test/build/upload/logs 11:17:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.173142 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-12-27 11:17:36.333237) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-27 11:17:36.337033) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1482864883.280121-828086330 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3dca960618e7ada1ef78b653fda348491f323a8a854f08c3591b02464cb2080eadb993b8e18abb1f4f200be8b672769143af913ead87524357a26d200bca6863", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4fd9b118c0605bde06a71400e046886f04cc621db0e72cda509a3449bb6bf871f011ce9566a1508748e3570410c98a43d0132cb731a650fd88e59d427cb16d23", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f303b65d56cab8ecfb56df6f9314a4512fb967bf4aaa7bb408463de974a19896b80a021935bb46463390f90d37826c23a2495effdb5fff58df41a877a1168394", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/2749475eb27b8a1cabef83cc53564a78f8d1adad106e0eef846ffe32c1d5f4bf93eb93c8bf2bb4d9f6fe6b6bec4733d342b463877fb1d0d724a9cf5b4fa1344b"} build_url:https://queue.taskcluster.net/v1/task/AtSqGWIzSKSlNBraD7c1Xg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011480 build_url: 'https://queue.taskcluster.net/v1/task/AtSqGWIzSKSlNBraD7c1Xg/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3dca960618e7ada1ef78b653fda348491f323a8a854f08c3591b02464cb2080eadb993b8e18abb1f4f200be8b672769143af913ead87524357a26d200bca6863", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4fd9b118c0605bde06a71400e046886f04cc621db0e72cda509a3449bb6bf871f011ce9566a1508748e3570410c98a43d0132cb731a650fd88e59d427cb16d23", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/f303b65d56cab8ecfb56df6f9314a4512fb967bf4aaa7bb408463de974a19896b80a021935bb46463390f90d37826c23a2495effdb5fff58df41a877a1168394", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/2749475eb27b8a1cabef83cc53564a78f8d1adad106e0eef846ffe32c1d5f4bf93eb93c8bf2bb4d9f6fe6b6bec4733d342b463877fb1d0d724a9cf5b4fa1344b"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-27 11:17:36.362811) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 11:17:36.363149) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1482864883.280121-828086330 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004468 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-27 11:17:36.447475) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-27 11:17:36.447800) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-27 11:17:36.448105) ========= ========= Total master_lag: 0.17 =========