builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-033 starttime: 1471526096.58 results: success (0) buildid: 20160818023814 builduid: 3c3cd6219c2c476db425737bc50e3032 revision: a6f67ab4a4ec4dd66f53c4b4423e5b8b63023388 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 06:14:56.579269) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 06:14:56.579725) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 06:14:56.579998) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 06:14:56.594457) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 06:14:56.594766) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1471526094.107669-871665906 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-18 06:14:56-- 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: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 88.8M=0s 2016-08-18 06:14:56 (88.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.086049 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 06:14:56.694382) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 06:14:56.694976) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1471526094.107669-871665906 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015247 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 06:14:56.744585) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 06:14:56.744953) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev a6f67ab4a4ec4dd66f53c4b4423e5b8b63023388 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev a6f67ab4a4ec4dd66f53c4b4423e5b8b63023388 --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=edae0fb5fee9fb758e8af6d3000002a4-1471526094.107669-871665906 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-18 06:14:56,746 truncating revision to first 12 chars 2016-08-18 06:14:56,747 Setting DEBUG logging. 2016-08-18 06:14:56,747 attempt 1/10 2016-08-18 06:14:56,747 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/a6f67ab4a4ec?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-18 06:14:57,091 unpacking tar archive at: fx-team-a6f67ab4a4ec/testing/mozharness/ program finished with exit code 0 elapsedTime=0.690160 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 06:14:57.449150) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 06:14:57.449447) ========= script_repo_revision: a6f67ab4a4ec4dd66f53c4b4423e5b8b63023388 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 06:14:57.449832) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 06:14:57.450089) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 06:14:57.460921) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-08-18 06:14:57.461234) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] 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=edae0fb5fee9fb758e8af6d3000002a4-1471526094.107669-871665906 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:14:57 INFO - MultiFileLogger online at 20160818 06:14:57 in /builds/slave/test 06:14:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 06:14:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:14:57 INFO - {'append_to_log': False, 06:14:57 INFO - 'base_work_dir': '/builds/slave/test', 06:14:57 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 06:14:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:14:57 INFO - 'branch': 'Fx-Team-Non-PGO', 06:14:57 INFO - 'buildbot_json_path': 'buildprops.json', 06:14:57 INFO - 'config_files': ('talos/linux_config.py',), 06:14:57 INFO - 'default_actions': ('clobber', 06:14:57 INFO - 'read-buildbot-config', 06:14:57 INFO - 'download-and-extract', 06:14:57 INFO - 'populate-webroot', 06:14:57 INFO - 'create-virtualenv', 06:14:57 INFO - 'install', 06:14:57 INFO - 'run-tests'), 06:14:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:14:57 INFO - 'download_minidump_stackwalk': True, 06:14:57 INFO - 'download_symbols': 'ondemand', 06:14:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:14:57 INFO - 'tooltool.py': '/tools/tooltool.py', 06:14:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:14:57 INFO - '/tools/misc-python/virtualenv.py')}, 06:14:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:14:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:14:57 INFO - 'installer_path': 'installer.exe', 06:14:57 INFO - 'log_level': 'info', 06:14:57 INFO - 'log_name': 'talos', 06:14:57 INFO - 'log_to_console': True, 06:14:57 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:14:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:14:57 INFO - 'opt_config_files': (), 06:14:57 INFO - 'pip_index': False, 06:14:57 INFO - 'sps_profile': False, 06:14:57 INFO - 'sps_profile_interval': 0, 06:14:57 INFO - 'suite': 'dromaeojs', 06:14:57 INFO - 'system_bits': '32', 06:14:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:14:57 INFO - 'title': 'talos-linux64-ix-033', 06:14:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:14:57 INFO - 'use_talos_json': True, 06:14:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:14:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:14:57 INFO - 'work_dir': 'build'} 06:14:57 INFO - [mozharness: 2016-08-18 13:14:57.555098Z] Running clobber step. 06:14:57 INFO - Running pre-action listener: _resource_record_pre_action 06:14:57 INFO - Running main action method: clobber 06:14:57 INFO - rmtree: /builds/slave/test/build 06:14:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:14:58 INFO - Running post-action listener: _resource_record_post_action 06:14:58 INFO - [mozharness: 2016-08-18 13:14:58.515791Z] Finished clobber step (success) 06:14:58 INFO - [mozharness: 2016-08-18 13:14:58.515891Z] Running read-buildbot-config step. 06:14:58 INFO - Running pre-action listener: _resource_record_pre_action 06:14:58 INFO - Running main action method: read_buildbot_config 06:14:58 INFO - Using buildbot properties: 06:14:58 INFO - { 06:14:58 INFO - "project": "", 06:14:58 INFO - "product": "firefox", 06:14:58 INFO - "script_repo_revision": "production", 06:14:58 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 06:14:58 INFO - "repository": "", 06:14:58 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 06:14:58 INFO - "buildid": "20160818023814", 06:14:58 INFO - "pgo_build": "False", 06:14:58 INFO - "basedir": "/builds/slave/test", 06:14:58 INFO - "buildnumber": 3245, 06:14:58 INFO - "slavename": "talos-linux64-ix-033", 06:14:58 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 06:14:58 INFO - "platform": "ubuntu64_hw", 06:14:58 INFO - "scheduler": "tests-fx-team-linux64-talos-7-3600", 06:14:58 INFO - "branch": "fx-team", 06:14:58 INFO - "repo_path": "integration/fx-team", 06:14:58 INFO - "revision": "a6f67ab4a4ec4dd66f53c4b4423e5b8b63023388", 06:14:58 INFO - "stage_platform": "linux64", 06:14:58 INFO - "builduid": "3c3cd6219c2c476db425737bc50e3032", 06:14:58 INFO - "slavebuilddir": "test" 06:14:58 INFO - } 06:14:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/Xu9dstL-T5WrJDQdXmUGBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 06:14:58 INFO - Running post-action listener: _resource_record_post_action 06:14:58 INFO - [mozharness: 2016-08-18 13:14:58.518312Z] Finished read-buildbot-config step (success) 06:14:58 INFO - [mozharness: 2016-08-18 13:14:58.518401Z] Running download-and-extract step. 06:14:58 INFO - Running pre-action listener: _resource_record_pre_action 06:14:58 INFO - Running main action method: download_and_extract 06:14:58 INFO - mkdir: /builds/slave/test/build/tests 06:14:58 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')]} 06:14:58 INFO - trying https://queue.taskcluster.net/v1/task/Xu9dstL-T5WrJDQdXmUGBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 06:14:58 INFO - Downloading https://queue.taskcluster.net/v1/task/Xu9dstL-T5WrJDQdXmUGBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 06:14:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xu9dstL-T5WrJDQdXmUGBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 06:14:59 INFO - Downloaded 1361 bytes. 06:14:59 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 06:14:59 INFO - Using the following test package requirements: 06:14:59 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 06:14:59 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 06:14:59 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 06:14:59 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 06:14:59 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 06:14:59 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 06:14:59 INFO - u'jsshell-linux-x86_64.zip'], 06:14:59 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 06:14:59 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 06:14:59 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 06:14:59 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 06:14:59 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 06:14:59 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 06:14:59 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 06:14:59 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 06:14:59 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 06:14:59 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 06:14:59 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 06:14:59 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 06:14:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:14:59 INFO - trying https://queue.taskcluster.net/v1/task/Xu9dstL-T5WrJDQdXmUGBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 06:14:59 INFO - Downloading https://queue.taskcluster.net/v1/task/Xu9dstL-T5WrJDQdXmUGBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 06:14:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xu9dstL-T5WrJDQdXmUGBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:14:59 INFO - Downloaded 20317580 bytes. 06:14:59 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 06:15:04 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 06:15:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:15:04 INFO - trying https://queue.taskcluster.net/v1/task/Xu9dstL-T5WrJDQdXmUGBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 06:15:04 INFO - Downloading https://queue.taskcluster.net/v1/task/Xu9dstL-T5WrJDQdXmUGBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 06:15:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xu9dstL-T5WrJDQdXmUGBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:15:05 INFO - Downloaded 20317580 bytes. 06:15:05 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 06:15: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')]} 06:15:09 INFO - trying https://queue.taskcluster.net/v1/task/Xu9dstL-T5WrJDQdXmUGBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 06:15:09 INFO - Downloading https://queue.taskcluster.net/v1/task/Xu9dstL-T5WrJDQdXmUGBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 06:15:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xu9dstL-T5WrJDQdXmUGBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 06:15:10 INFO - Downloaded 14413981 bytes. 06:15:10 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 06:15:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:15:10 INFO - trying https://queue.taskcluster.net/v1/task/Xu9dstL-T5WrJDQdXmUGBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 06:15:10 INFO - Downloading https://queue.taskcluster.net/v1/task/Xu9dstL-T5WrJDQdXmUGBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 06:15:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xu9dstL-T5WrJDQdXmUGBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 06:15:12 INFO - Downloaded 55760674 bytes. 06:15:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Xu9dstL-T5WrJDQdXmUGBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 06:15:12 INFO - mkdir: /builds/slave/test/properties 06:15:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:15:12 INFO - Writing to file /builds/slave/test/properties/build_url 06:15:12 INFO - Contents: 06:15:12 INFO - build_url:https://queue.taskcluster.net/v1/task/Xu9dstL-T5WrJDQdXmUGBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 06:15:12 INFO - Running post-action listener: _resource_record_post_action 06:15:12 INFO - Running post-action listener: set_extra_try_arguments 06:15:12 INFO - [mozharness: 2016-08-18 13:15:12.798472Z] Finished download-and-extract step (success) 06:15:12 INFO - [mozharness: 2016-08-18 13:15:12.798697Z] Running populate-webroot step. 06:15:12 INFO - Running pre-action listener: _resource_record_pre_action 06:15:12 INFO - Running main action method: populate_webroot 06:15:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:15:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:15:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:15:12 INFO - '--tppagecycles', 06:15:12 INFO - '7'], 06:15:12 INFO - 'tests': ['tsvgm']}}, 06:15:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:15:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:15:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:15:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:15:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:15:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:15:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:15:12 INFO - 'talos_options': ['--disable-e10s'], 06:15:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:15:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:15:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:15:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:15:12 INFO - 'talos_options': ['--disable-e10s'], 06:15:12 INFO - 'tests': ['damp', 'tps']}, 06:15:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:15:12 INFO - 'tests': ['damp', 'tps']}, 06:15:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:15:12 INFO - 'tests': ['dromaeo_dom']}, 06:15:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:15:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:15:12 INFO - 'tests': ['basic_compositor_video']}, 06:15:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:15:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:15:12 INFO - 'tests': ['a11yr', 06:15:12 INFO - 'ts_paint', 06:15:12 INFO - 'tpaint', 06:15:12 INFO - 'sessionrestore', 06:15:12 INFO - 'sessionrestore_no_auto_restore', 06:15:12 INFO - 'tabpaint']}, 06:15:12 INFO - 'other-e10s': {'tests': ['a11yr', 06:15:12 INFO - 'ts_paint', 06:15:12 INFO - 'tpaint', 06:15:12 INFO - 'sessionrestore', 06:15:12 INFO - 'sessionrestore_no_auto_restore', 06:15:12 INFO - 'tabpaint']}, 06:15:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:15:12 INFO - 'ts_paint', 06:15:12 INFO - 'tpaint', 06:15:12 INFO - 'sessionrestore', 06:15:12 INFO - 'sessionrestore_no_auto_restore', 06:15:12 INFO - 'tabpaint']}, 06:15:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:15:12 INFO - 'ts_paint', 06:15:12 INFO - 'tpaint', 06:15:12 INFO - 'sessionrestore', 06:15:12 INFO - 'sessionrestore_no_auto_restore', 06:15:12 INFO - 'tabpaint']}, 06:15:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:15:12 INFO - 'tests': ['a11yr', 06:15:12 INFO - 'ts_paint', 06:15:12 INFO - 'tpaint', 06:15:12 INFO - 'sessionrestore', 06:15:12 INFO - 'sessionrestore_no_auto_restore', 06:15:12 INFO - 'tabpaint']}, 06:15:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:15:12 INFO - 'tests': ['a11yr', 06:15:12 INFO - 'ts_paint', 06:15:12 INFO - 'tpaint', 06:15:12 INFO - 'sessionrestore', 06:15:12 INFO - 'sessionrestore_no_auto_restore', 06:15:12 INFO - 'tabpaint']}, 06:15:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:15:12 INFO - 'tests': ['tsvgx', 06:15:12 INFO - 'tsvgr_opacity', 06:15:12 INFO - 'tart', 06:15:12 INFO - 'tscrollx', 06:15:12 INFO - 'cart']}, 06:15:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:15:12 INFO - 'tsvgr_opacity', 06:15:12 INFO - 'tart', 06:15:12 INFO - 'tscrollx', 06:15:12 INFO - 'cart']}, 06:15:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:15:12 INFO - 'talos_options': ['--disable-e10s'], 06:15:12 INFO - 'tests': ['tp5o']}, 06:15:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:15:12 INFO - 'tests': ['tp5o']}, 06:15:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:15:12 INFO - 'talos_options': ['--disable-e10s', 06:15:12 INFO - '--xperf_path', 06:15:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:15:12 INFO - 'tests': ['tp5n']}, 06:15:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:15:12 INFO - 'talos_options': ['--xperf_path', 06:15:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:15:12 INFO - 'tests': ['tp5n']}}, 06:15:12 INFO - 'talos.zip': {'path': '', 06:15:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:15:12 INFO - Running post-action listener: _resource_record_post_action 06:15:12 INFO - [mozharness: 2016-08-18 13:15:12.816333Z] Finished populate-webroot step (success) 06:15:12 INFO - [mozharness: 2016-08-18 13:15:12.816421Z] Running create-virtualenv step. 06:15:12 INFO - Running pre-action listener: _resource_record_pre_action 06:15:12 INFO - Running main action method: create_virtualenv 06:15:12 INFO - Creating virtualenv /builds/slave/test/build/venv 06:15:12 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 06:15:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:15:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:15:12 INFO - Using real prefix '/usr' 06:15:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:15:13 INFO - Installing distribute.............................................................................................................................................................................................done. 06:15:15 INFO - Installing pip.................done. 06:15:15 INFO - Return code: 0 06:15:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:15:15 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')]} 06:15:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:15 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')]} 06:15:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:15 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 0x7fd566151f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26cb230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c5090>, '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 0x26c89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c8e40>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471526094.107669-871665906', '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 06:15:15 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 06:15:15 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 06:15:15 INFO - Using env: {'DISPLAY': ':0', 06:15:15 INFO - 'HOME': '/home/cltbld', 06:15:15 INFO - 'LANG': 'en_US.UTF-8', 06:15:15 INFO - 'LANGUAGE': 'en_US:en', 06:15:15 INFO - 'LOGNAME': 'cltbld', 06:15:15 INFO - 'MAIL': '/var/mail/cltbld', 06:15:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:15 INFO - 'MOZ_NO_REMOTE': '1', 06:15:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:15:15 INFO - 'NO_EM_RESTART': '1', 06:15:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:15:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:15 INFO - 'PWD': '/builds/slave/test', 06:15:15 INFO - 'SHELL': '/bin/bash', 06:15:15 INFO - 'SHLVL': '1', 06:15:15 INFO - 'TERM': 'linux', 06:15:15 INFO - 'TMOUT': '86400', 06:15:15 INFO - 'USER': 'cltbld', 06:15:15 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471526094.107669-871665906', 06:15:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:15 INFO - '_': '/tools/buildbot/bin/python'} 06:15:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:15 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 06:15:15 INFO - Cleaning up... 06:15:15 INFO - Return code: 0 06:15:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:15:15 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')]} 06:15:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:15 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')]} 06:15:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:15 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 0x7fd566151f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26cb230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c5090>, '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 0x26c89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c8e40>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471526094.107669-871665906', '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 06:15:15 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 06:15:15 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 06:15:15 INFO - Using env: {'DISPLAY': ':0', 06:15:15 INFO - 'HOME': '/home/cltbld', 06:15:15 INFO - 'LANG': 'en_US.UTF-8', 06:15:15 INFO - 'LANGUAGE': 'en_US:en', 06:15:15 INFO - 'LOGNAME': 'cltbld', 06:15:15 INFO - 'MAIL': '/var/mail/cltbld', 06:15:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:15 INFO - 'MOZ_NO_REMOTE': '1', 06:15:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:15:15 INFO - 'NO_EM_RESTART': '1', 06:15:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:15:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:15 INFO - 'PWD': '/builds/slave/test', 06:15:15 INFO - 'SHELL': '/bin/bash', 06:15:15 INFO - 'SHLVL': '1', 06:15:15 INFO - 'TERM': 'linux', 06:15:15 INFO - 'TMOUT': '86400', 06:15:15 INFO - 'USER': 'cltbld', 06:15:15 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471526094.107669-871665906', 06:15:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:15 INFO - '_': '/tools/buildbot/bin/python'} 06:15:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:15 INFO - Downloading/unpacking psutil>=3.1.1 06:15:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:15:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:15:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:15:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:15:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:15:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:15:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:15:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:15:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:15:17 INFO - Installing collected packages: psutil 06:15:17 INFO - Running setup.py install for psutil 06:15:17 INFO - building 'psutil._psutil_linux' extension 06:15:17 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 06:15:18 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 06:15:18 INFO - building 'psutil._psutil_posix' extension 06:15:18 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 06:15:18 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 06:15:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:15:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:15:18 INFO - Successfully installed psutil 06:15:18 INFO - Cleaning up... 06:15:18 INFO - Return code: 0 06:15:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:15:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:15:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd566151f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26cb230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c5090>, '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 0x26c89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c8e40>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471526094.107669-871665906', '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 06:15:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:15:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 06:15:18 INFO - Using env: {'DISPLAY': ':0', 06:15:18 INFO - 'HOME': '/home/cltbld', 06:15:18 INFO - 'LANG': 'en_US.UTF-8', 06:15:18 INFO - 'LANGUAGE': 'en_US:en', 06:15:18 INFO - 'LOGNAME': 'cltbld', 06:15:18 INFO - 'MAIL': '/var/mail/cltbld', 06:15:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:18 INFO - 'MOZ_NO_REMOTE': '1', 06:15:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:15:18 INFO - 'NO_EM_RESTART': '1', 06:15:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:15:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:18 INFO - 'PWD': '/builds/slave/test', 06:15:18 INFO - 'SHELL': '/bin/bash', 06:15:18 INFO - 'SHLVL': '1', 06:15:18 INFO - 'TERM': 'linux', 06:15:18 INFO - 'TMOUT': '86400', 06:15:18 INFO - 'USER': 'cltbld', 06:15:18 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471526094.107669-871665906', 06:15:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:18 INFO - '_': '/tools/buildbot/bin/python'} 06:15:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:15:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:15:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:15:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:15:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:15:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:15:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:15:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:15:20 INFO - Installing collected packages: mozsystemmonitor 06:15:20 INFO - Running setup.py install for mozsystemmonitor 06:15:20 INFO - Successfully installed mozsystemmonitor 06:15:20 INFO - Cleaning up... 06:15:20 INFO - Return code: 0 06:15:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:15:20 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')]} 06:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:20 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')]} 06:15:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:20 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 0x7fd566151f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26cb230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c5090>, '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 0x26c89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c8e40>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471526094.107669-871665906', '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 06:15:20 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 06:15:20 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 06:15:20 INFO - Using env: {'DISPLAY': ':0', 06:15:20 INFO - 'HOME': '/home/cltbld', 06:15:20 INFO - 'LANG': 'en_US.UTF-8', 06:15:20 INFO - 'LANGUAGE': 'en_US:en', 06:15:20 INFO - 'LOGNAME': 'cltbld', 06:15:20 INFO - 'MAIL': '/var/mail/cltbld', 06:15:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:20 INFO - 'MOZ_NO_REMOTE': '1', 06:15:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:15:20 INFO - 'NO_EM_RESTART': '1', 06:15:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:15:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:20 INFO - 'PWD': '/builds/slave/test', 06:15:20 INFO - 'SHELL': '/bin/bash', 06:15:20 INFO - 'SHLVL': '1', 06:15:20 INFO - 'TERM': 'linux', 06:15:20 INFO - 'TMOUT': '86400', 06:15:20 INFO - 'USER': 'cltbld', 06:15:20 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471526094.107669-871665906', 06:15:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:20 INFO - '_': '/tools/buildbot/bin/python'} 06:15:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:20 INFO - Downloading/unpacking jsonschema==2.5.1 06:15: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 06:15: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 06:15: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 06:15: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 06:15: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 06:15: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 06:15:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:15:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:15:22 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 06:15:22 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 06:15:22 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 06:15:22 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 06:15:22 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 06:15:22 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 06:15:22 INFO - Downloading functools32-3.2.3-2.tar.gz 06:15:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:15:23 INFO - warning: no files found matching '*.txt' 06:15:23 INFO - no previously-included directories found matching 'build' 06:15:23 INFO - no previously-included directories found matching 'dist' 06:15:23 INFO - no previously-included directories found matching '.git*' 06:15:23 INFO - Installing collected packages: jsonschema, functools32 06:15:23 INFO - Running setup.py install for functools32 06:15:23 INFO - warning: no files found matching '*.txt' 06:15:23 INFO - no previously-included directories found matching 'build' 06:15:23 INFO - no previously-included directories found matching 'dist' 06:15:23 INFO - no previously-included directories found matching '.git*' 06:15:23 INFO - Successfully installed jsonschema functools32 06:15:23 INFO - Cleaning up... 06:15:23 INFO - Return code: 0 06:15:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:15:23 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')]} 06:15:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:23 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')]} 06:15:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:23 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 0x7fd566151f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26cb230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c5090>, '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 0x26c89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c8e40>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471526094.107669-871665906', '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 06:15:23 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 06:15:23 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 06:15:23 INFO - Using env: {'DISPLAY': ':0', 06:15:23 INFO - 'HOME': '/home/cltbld', 06:15:23 INFO - 'LANG': 'en_US.UTF-8', 06:15:23 INFO - 'LANGUAGE': 'en_US:en', 06:15:23 INFO - 'LOGNAME': 'cltbld', 06:15:23 INFO - 'MAIL': '/var/mail/cltbld', 06:15:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:23 INFO - 'MOZ_NO_REMOTE': '1', 06:15:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:15:23 INFO - 'NO_EM_RESTART': '1', 06:15:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:15:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:23 INFO - 'PWD': '/builds/slave/test', 06:15:23 INFO - 'SHELL': '/bin/bash', 06:15:23 INFO - 'SHLVL': '1', 06:15:23 INFO - 'TERM': 'linux', 06:15:23 INFO - 'TMOUT': '86400', 06:15:23 INFO - 'USER': 'cltbld', 06:15:23 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471526094.107669-871665906', 06:15:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:23 INFO - '_': '/tools/buildbot/bin/python'} 06:15:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:15:23 INFO - Cleaning up... 06:15:23 INFO - Return code: 0 06:15:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:15:23 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')]} 06:15:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:23 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')]} 06:15:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:23 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 0x7fd566151f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26cb230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c5090>, '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 0x26c89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c8e40>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471526094.107669-871665906', '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 06:15:23 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 06:15:23 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 06:15:23 INFO - Using env: {'DISPLAY': ':0', 06:15:23 INFO - 'HOME': '/home/cltbld', 06:15:23 INFO - 'LANG': 'en_US.UTF-8', 06:15:23 INFO - 'LANGUAGE': 'en_US:en', 06:15:23 INFO - 'LOGNAME': 'cltbld', 06:15:23 INFO - 'MAIL': '/var/mail/cltbld', 06:15:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:23 INFO - 'MOZ_NO_REMOTE': '1', 06:15:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:15:23 INFO - 'NO_EM_RESTART': '1', 06:15:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:15:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:23 INFO - 'PWD': '/builds/slave/test', 06:15:23 INFO - 'SHELL': '/bin/bash', 06:15:23 INFO - 'SHLVL': '1', 06:15:23 INFO - 'TERM': 'linux', 06:15:23 INFO - 'TMOUT': '86400', 06:15:23 INFO - 'USER': 'cltbld', 06:15:23 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471526094.107669-871665906', 06:15:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:23 INFO - '_': '/tools/buildbot/bin/python'} 06:15:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:23 INFO - Downloading/unpacking blobuploader==1.2.4 06:15:23 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 06:15:23 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 06:15:23 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 06:15:23 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 06:15:23 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 06:15:23 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 06:15:25 INFO - Downloading blobuploader-1.2.4.tar.gz 06:15:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:15:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:15: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 06:15: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 06:15: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 06:15: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 06:15: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 06:15: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 06:15:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:15:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:15: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 06:15: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 06:15: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 06:15: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 06:15: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 06:15: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 06:15:26 INFO - Downloading docopt-0.6.1.tar.gz 06:15:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:15:26 INFO - Installing collected packages: blobuploader, requests, docopt 06:15:26 INFO - Running setup.py install for blobuploader 06:15:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:15:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:15:26 INFO - Running setup.py install for requests 06:15:26 INFO - Running setup.py install for docopt 06:15:26 INFO - Successfully installed blobuploader requests docopt 06:15:26 INFO - Cleaning up... 06:15:26 INFO - Return code: 0 06:15:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:15:26 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')]} 06:15:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:26 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')]} 06:15:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:26 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 0x7fd566151f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26cb230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c5090>, '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 0x26c89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c8e40>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471526094.107669-871665906', '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 06:15:26 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 06:15:26 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 06:15:26 INFO - Using env: {'DISPLAY': ':0', 06:15:26 INFO - 'HOME': '/home/cltbld', 06:15:26 INFO - 'LANG': 'en_US.UTF-8', 06:15:26 INFO - 'LANGUAGE': 'en_US:en', 06:15:26 INFO - 'LOGNAME': 'cltbld', 06:15:26 INFO - 'MAIL': '/var/mail/cltbld', 06:15:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:26 INFO - 'MOZ_NO_REMOTE': '1', 06:15:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:15:26 INFO - 'NO_EM_RESTART': '1', 06:15:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:15:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:26 INFO - 'PWD': '/builds/slave/test', 06:15:26 INFO - 'SHELL': '/bin/bash', 06:15:26 INFO - 'SHLVL': '1', 06:15:26 INFO - 'TERM': 'linux', 06:15:26 INFO - 'TMOUT': '86400', 06:15:26 INFO - 'USER': 'cltbld', 06:15:26 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471526094.107669-871665906', 06:15:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:26 INFO - '_': '/tools/buildbot/bin/python'} 06:15:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:15:27 INFO - Running setup.py (path:/tmp/pip-AuOYlD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:15:27 INFO - Running setup.py (path:/tmp/pip-D790j3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:15:27 INFO - Running setup.py (path:/tmp/pip-gE1WjY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:15:27 INFO - Running setup.py (path:/tmp/pip-g9P3ss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:15:27 INFO - Running setup.py (path:/tmp/pip-bAah94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:15:27 INFO - Running setup.py (path:/tmp/pip-JGpxLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:15:27 INFO - Running setup.py (path:/tmp/pip-PcqNJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:15:27 INFO - Running setup.py (path:/tmp/pip-m9Yv0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:15:27 INFO - Running setup.py (path:/tmp/pip-491OPp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:15:27 INFO - Running setup.py (path:/tmp/pip-Vmt079-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:15:27 INFO - Running setup.py (path:/tmp/pip-nWmuft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:15:27 INFO - Running setup.py (path:/tmp/pip-h8zxG9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:15:27 INFO - Running setup.py (path:/tmp/pip-G6scnR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:15:27 INFO - Running setup.py (path:/tmp/pip-JlUQXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:15:28 INFO - Running setup.py (path:/tmp/pip-RUoSuE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:15:28 INFO - Running setup.py (path:/tmp/pip-LGViHy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:15:28 INFO - Running setup.py (path:/tmp/pip-EDWmWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:15:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:15:28 INFO - Running setup.py install for manifestparser 06:15:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:15:28 INFO - Running setup.py install for mozcrash 06:15:28 INFO - Running setup.py install for mozdebug 06:15:28 INFO - Running setup.py install for mozdevice 06:15:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:15:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:15:28 INFO - Running setup.py install for mozfile 06:15:28 INFO - Running setup.py install for mozhttpd 06:15:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:15:28 INFO - Running setup.py install for mozinfo 06:15:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:15:28 INFO - Running setup.py install for mozInstall 06:15:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:15:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:15:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:15:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:15:29 INFO - Running setup.py install for mozleak 06:15:29 INFO - Running setup.py install for mozlog 06:15:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:15:29 INFO - Running setup.py install for moznetwork 06:15:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:15:29 INFO - Running setup.py install for mozprocess 06:15:29 INFO - Running setup.py install for mozprofile 06:15:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:15:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:15:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:15:29 INFO - Running setup.py install for mozrunner 06:15:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:15:29 INFO - Running setup.py install for mozscreenshot 06:15:29 INFO - Running setup.py install for moztest 06:15:29 INFO - Running setup.py install for mozversion 06:15:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:15:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:15:30 INFO - Cleaning up... 06:15:30 INFO - Return code: 0 06:15:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:15:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:15:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:15:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:30 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 0x7fd566151f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26cb230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c5090>, '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 0x26c89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c8e40>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471526094.107669-871665906', '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 06:15:30 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 06:15:30 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 06:15:30 INFO - Using env: {'DISPLAY': ':0', 06:15:30 INFO - 'HOME': '/home/cltbld', 06:15:30 INFO - 'LANG': 'en_US.UTF-8', 06:15:30 INFO - 'LANGUAGE': 'en_US:en', 06:15:30 INFO - 'LOGNAME': 'cltbld', 06:15:30 INFO - 'MAIL': '/var/mail/cltbld', 06:15:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:30 INFO - 'MOZ_NO_REMOTE': '1', 06:15:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:15:30 INFO - 'NO_EM_RESTART': '1', 06:15:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:15:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:30 INFO - 'PWD': '/builds/slave/test', 06:15:30 INFO - 'SHELL': '/bin/bash', 06:15:30 INFO - 'SHLVL': '1', 06:15:30 INFO - 'TERM': 'linux', 06:15:30 INFO - 'TMOUT': '86400', 06:15:30 INFO - 'USER': 'cltbld', 06:15:30 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471526094.107669-871665906', 06:15:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:30 INFO - '_': '/tools/buildbot/bin/python'} 06:15:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:15:30 INFO - Running setup.py (path:/tmp/pip-WJssO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:15:30 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)) 06:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:15:30 INFO - Running setup.py (path:/tmp/pip-SR25NT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:15:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:15:30 INFO - Running setup.py (path:/tmp/pip-ny7771-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:15:30 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)) 06:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:15:30 INFO - Running setup.py (path:/tmp/pip-xxkrX9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:15:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:15:30 INFO - Running setup.py (path:/tmp/pip-n9heGH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:15:30 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)) 06:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:15:30 INFO - Running setup.py (path:/tmp/pip-tfEYoX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:15:30 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)) 06:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:15:30 INFO - Running setup.py (path:/tmp/pip-skC95V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:15:30 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)) 06:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:15:30 INFO - Running setup.py (path:/tmp/pip-z4rEb8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:15:30 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)) 06:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:15:30 INFO - Running setup.py (path:/tmp/pip-HszEKN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:15:30 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)) 06:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:15:30 INFO - Running setup.py (path:/tmp/pip-dc4Dfo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:15:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 06:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:15:31 INFO - Running setup.py (path:/tmp/pip-c98y6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:15:31 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)) 06:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:15:31 INFO - Running setup.py (path:/tmp/pip-o0nPVb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:15:31 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)) 06:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:15:31 INFO - Running setup.py (path:/tmp/pip-_3DLRi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:15:31 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)) 06:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:15:31 INFO - Running setup.py (path:/tmp/pip-12w5Md-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:15:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 06:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:15:31 INFO - Running setup.py (path:/tmp/pip-CCvObb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:15:31 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)) 06:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:15:31 INFO - Running setup.py (path:/tmp/pip-ZYRuaD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:15:31 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)) 06:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:15:31 INFO - Running setup.py (path:/tmp/pip-PwgEgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:15:31 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)) 06:15:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:15:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:15:31 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)) 06:15:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:15:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:15:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:15:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:15:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:15:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:15:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:15:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:15:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:15:33 INFO - Downloading blessings-1.6.tar.gz 06:15:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:15:33 INFO - Installing collected packages: blessings 06:15:33 INFO - Running setup.py install for blessings 06:15:33 INFO - Successfully installed blessings 06:15:33 INFO - Cleaning up... 06:15:33 INFO - Return code: 0 06:15:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:15:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:15:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:15:33 INFO - Reading from file tmpfile_stdout 06:15:33 INFO - Current package versions: 06:15:33 INFO - argparse == 1.2.1 06:15:33 INFO - blessings == 1.6 06:15:33 INFO - blobuploader == 1.2.4 06:15:33 INFO - docopt == 0.6.1 06:15:33 INFO - functools32 == 3.2.3-2 06:15:33 INFO - jsonschema == 2.5.1 06:15:33 INFO - manifestparser == 1.1 06:15:33 INFO - mozInstall == 1.12 06:15:33 INFO - mozcrash == 0.17 06:15:33 INFO - mozdebug == 0.1 06:15:33 INFO - mozdevice == 0.48 06:15:33 INFO - mozfile == 1.2 06:15:33 INFO - mozhttpd == 0.7 06:15:33 INFO - mozinfo == 0.9 06:15:33 INFO - mozleak == 0.1 06:15:33 INFO - mozlog == 3.3 06:15:33 INFO - moznetwork == 0.27 06:15:33 INFO - mozprocess == 0.23 06:15:33 INFO - mozprofile == 0.28 06:15:33 INFO - mozrunner == 6.12 06:15:33 INFO - mozscreenshot == 0.1 06:15:33 INFO - mozsystemmonitor == 0.3 06:15:33 INFO - moztest == 0.7 06:15:33 INFO - mozversion == 1.4 06:15:33 INFO - psutil == 3.1.1 06:15:33 INFO - requests == 1.2.3 06:15:33 INFO - wsgiref == 0.1.2 06:15:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:15:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:15:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:15:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:33 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 0x7fd566151f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26cb230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c5090>, '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 0x26c89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c8e40>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471526094.107669-871665906', '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 06:15:33 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 06:15:33 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 06:15:33 INFO - Using env: {'DISPLAY': ':0', 06:15:33 INFO - 'HOME': '/home/cltbld', 06:15:33 INFO - 'LANG': 'en_US.UTF-8', 06:15:33 INFO - 'LANGUAGE': 'en_US:en', 06:15:33 INFO - 'LOGNAME': 'cltbld', 06:15:33 INFO - 'MAIL': '/var/mail/cltbld', 06:15:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:33 INFO - 'MOZ_NO_REMOTE': '1', 06:15:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:15:33 INFO - 'NO_EM_RESTART': '1', 06:15:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:15:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:33 INFO - 'PWD': '/builds/slave/test', 06:15:33 INFO - 'SHELL': '/bin/bash', 06:15:33 INFO - 'SHLVL': '1', 06:15:33 INFO - 'TERM': 'linux', 06:15:33 INFO - 'TMOUT': '86400', 06:15:33 INFO - 'USER': 'cltbld', 06:15:33 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471526094.107669-871665906', 06:15:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:33 INFO - '_': '/tools/buildbot/bin/python'} 06:15:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:34 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)) 06:15:34 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)) 06:15:34 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)) 06:15:34 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)) 06:15:34 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)) 06:15:34 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)) 06:15:34 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)) 06:15:34 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)) 06:15:34 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)) 06:15:34 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)) 06:15:34 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)) 06:15:34 INFO - Cleaning up... 06:15:34 INFO - Return code: 0 06:15:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:15:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:15:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:15:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:15:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:15:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:15:34 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 0x7fd566151f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26cb230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26c5090>, '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 0x26c89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c8e40>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471526094.107669-871665906', '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 06:15:34 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 06:15:34 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 06:15:34 INFO - Using env: {'DISPLAY': ':0', 06:15:34 INFO - 'HOME': '/home/cltbld', 06:15:34 INFO - 'LANG': 'en_US.UTF-8', 06:15:34 INFO - 'LANGUAGE': 'en_US:en', 06:15:34 INFO - 'LOGNAME': 'cltbld', 06:15:34 INFO - 'MAIL': '/var/mail/cltbld', 06:15:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:34 INFO - 'MOZ_NO_REMOTE': '1', 06:15:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:15:34 INFO - 'NO_EM_RESTART': '1', 06:15:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:15:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:34 INFO - 'PWD': '/builds/slave/test', 06:15:34 INFO - 'SHELL': '/bin/bash', 06:15:34 INFO - 'SHLVL': '1', 06:15:34 INFO - 'TERM': 'linux', 06:15:34 INFO - 'TMOUT': '86400', 06:15:34 INFO - 'USER': 'cltbld', 06:15:34 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471526094.107669-871665906', 06:15:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:34 INFO - '_': '/tools/buildbot/bin/python'} 06:15:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:15:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:15:34 INFO - Cleaning up... 06:15:34 INFO - Return code: 0 06:15:34 INFO - Running post-action listener: _resource_record_post_action 06:15:34 INFO - Running post-action listener: _start_resource_monitoring 06:15:34 INFO - Starting resource monitoring. 06:15:34 INFO - [mozharness: 2016-08-18 13:15:34.407347Z] Finished create-virtualenv step (success) 06:15:34 INFO - [mozharness: 2016-08-18 13:15:34.407855Z] Running install step. 06:15:34 INFO - Running pre-action listener: _resource_record_pre_action 06:15:34 INFO - Running main action method: install 06:15:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:15:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:15:34 INFO - Reading from file tmpfile_stdout 06:15:34 INFO - Detecting whether we're running mozinstall >=1.0... 06:15:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:15:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:15:34 INFO - Reading from file tmpfile_stdout 06:15:34 INFO - Output received: 06:15:34 INFO - Usage: mozinstall [options] installer 06:15:34 INFO - Options: 06:15:34 INFO - -h, --help show this help message and exit 06:15:34 INFO - -d DEST, --destination=DEST 06:15:34 INFO - Directory to install application into. [default: 06:15:34 INFO - "/builds/slave/test"] 06:15:34 INFO - --app=APP Application being installed. [default: firefox] 06:15:34 INFO - mkdir: /builds/slave/test/build/application 06:15:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 06:15:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 06:15:50 INFO - Reading from file tmpfile_stdout 06:15:50 INFO - Output received: 06:15:50 INFO - /builds/slave/test/build/application/firefox/firefox 06:15:50 INFO - Running post-action listener: _resource_record_post_action 06:15:50 INFO - [mozharness: 2016-08-18 13:15:50.793153Z] Finished install step (success) 06:15:50 INFO - [mozharness: 2016-08-18 13:15:50.793403Z] Running run-tests step. 06:15:50 INFO - Running pre-action listener: _resource_record_pre_action 06:15:50 INFO - Running main action method: run_tests 06:15:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:15:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:15:50 INFO - Python 2.7.3 06:15:50 INFO - Return code: 0 06:15:50 INFO - grabbing minidump binary from tooltool 06:15:50 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')]} 06:15:50 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 0x26c5090>, '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 0x26c89b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26c8e40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:15:50 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 06:15:50 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 06:15:50 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 06:15:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:15:50 INFO - Return code: 0 06:15:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 06:15:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:15:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:15:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 06:15:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:15:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Xu9dstL-T5WrJDQdXmUGBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--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 06:15:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Xu9dstL-T5WrJDQdXmUGBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-033 --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 06:15:50 INFO - Using env: {'DISPLAY': ':0', 06:15:50 INFO - 'HOME': '/home/cltbld', 06:15:50 INFO - 'LANG': 'en_US.UTF-8', 06:15:50 INFO - 'LANGUAGE': 'en_US:en', 06:15:50 INFO - 'LOGNAME': 'cltbld', 06:15:50 INFO - 'MAIL': '/var/mail/cltbld', 06:15:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:15:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:15:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:15:50 INFO - 'MOZ_NO_REMOTE': '1', 06:15:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:15:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:15:50 INFO - 'NO_EM_RESTART': '1', 06:15:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:15:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:15:50 INFO - 'PWD': '/builds/slave/test', 06:15:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:15:50 INFO - 'SHELL': '/bin/bash', 06:15:50 INFO - 'SHLVL': '1', 06:15:50 INFO - 'TERM': 'linux', 06:15:50 INFO - 'TMOUT': '86400', 06:15:50 INFO - 'USER': 'cltbld', 06:15:50 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471526094.107669-871665906', 06:15:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:15:50 INFO - '_': '/tools/buildbot/bin/python'} 06:15:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Xu9dstL-T5WrJDQdXmUGBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--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 06:15:51 INFO - mozversion application_buildid: 20160818023814 06:15:51 INFO - mozversion application_changeset: a6f67ab4a4ec4dd66f53c4b4423e5b8b63023388 06:15:51 INFO - mozversion application_display_name: Nightly 06:15:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:15:51 INFO - mozversion application_name: Firefox 06:15:51 INFO - mozversion application_remotingname: firefox 06:15:51 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 06:15:51 INFO - mozversion application_vendor: Mozilla 06:15:51 INFO - mozversion application_version: 51.0a1 06:15:51 INFO - mozversion platform_buildid: 20160818023814 06:15:51 INFO - mozversion platform_changeset: a6f67ab4a4ec4dd66f53c4b4423e5b8b63023388 06:15:51 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 06:15:51 INFO - mozversion platform_version: 51.0a1 06:15:51 INFO - using testdate: 1471526151 06:15:51 INFO - actual date: 1471526151 06:15:51 INFO - starting webserver on 'localhost:60957' 06:15:51 INFO - SUITE-START | Running 2 tests 06:15:51 INFO - TEST-START | dromaeo_css 06:15:51 INFO - operating with platform_type : linux_ 06:15:51 INFO - Initialising browser for dromaeo_css test... 06:15:51 INFO - TEST-INFO | started process 10813 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjFwope/profile http://localhost:60957/getInfo.html) 06:15:56 INFO - PROCESS | 10813 | __metrics Screen width/height:1600/1200 06:15:56 INFO - PROCESS | 10813 | colorDepth:24 06:15:56 INFO - PROCESS | 10813 | Browser inner width/height: 1024/697 06:15:56 INFO - PROCESS | 10813 | __metrics 06:15:56 INFO - PROCESS | 10813 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:15:56 INFO - PROCESS | 10813 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:15:57 INFO - PROCESS | 10813 | console.error: PushService: 06:15:57 INFO - PROCESS | 10813 | stateChangeProcessEnqueue: Error transitioning state 06:15:57 INFO - PROCESS | 10813 | UnknownError 06:15:58 INFO - TEST-INFO | 10813: exit 0 06:15:58 INFO - Browser initialized. 06:15:58 INFO - Running cycle 1/1 for dromaeo_css test... 06:15:58 INFO - TEST-INFO | started process 10894 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjFwope/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:16:10 INFO - PROCESS | 10894 | RSS: Main: 157163520 06:16:10 INFO - PROCESS | 10894 | 06:17:00 INFO - PROCESS | 10894 | 1471526220978 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 06:19:14 INFO - PROCESS | 10894 | Cycle 1(1): loaded http://localhost:60957/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60957/tests/dromaeo/cssquery-ext.html) 06:19:15 INFO - PROCESS | 10894 | RSS: Main: 227786752 06:19:15 INFO - PROCESS | 10894 | 06:22:18 INFO - PROCESS | 10894 | Cycle 1(1): loaded http://localhost:60957/tests/dromaeo/cssquery-ext.html (next: http://localhost:60957/tests/dromaeo/cssquery-jquery.html) 06:22:18 INFO - PROCESS | 10894 | RSS: Main: 239763456 06:22:18 INFO - PROCESS | 10894 | 06:25:21 INFO - PROCESS | 10894 | Cycle 1(1): loaded http://localhost:60957/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60957/tests/dromaeo/cssquery-mootools.html) 06:25:21 INFO - PROCESS | 10894 | RSS: Main: 213200896 06:25:21 INFO - PROCESS | 10894 | 06:28:24 INFO - PROCESS | 10894 | Cycle 1(1): loaded http://localhost:60957/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60957/tests/dromaeo/cssquery-prototype.html) 06:28:24 INFO - PROCESS | 10894 | RSS: Main: 190558208 06:28:24 INFO - PROCESS | 10894 | 06:31:27 INFO - PROCESS | 10894 | Cycle 1(1): loaded http://localhost:60957/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60957/tests/dromaeo/cssquery-yui.html) 06:31:28 INFO - PROCESS | 10894 | RSS: Main: 188825600 06:31:28 INFO - PROCESS | 10894 | 06:34:31 INFO - PROCESS | 10894 | Cycle 1(1): loaded http://localhost:60957/tests/dromaeo/cssquery-yui.html (next: http://localhost:60957/tests/dromaeo/cssquery-dojo.html) 06:34:31 INFO - PROCESS | 10894 | RSS: Main: 277606400 06:34:31 INFO - PROCESS | 10894 | 06:34:31 INFO - PROCESS | 10894 | __start_tp_report 06:34:31 INFO - PROCESS | 10894 | _x_x_mozilla_page_load 06:34:31 INFO - PROCESS | 10894 | _x_x_mozilla_page_load_details 06:34:31 INFO - PROCESS | 10894 | |i|pagename|runs| 06:34:31 INFO - PROCESS | 10894 | |0;dojo.html;2579.2133399313207;2601.661783968084;2612.947741045179;2630.631711560382;2633.3943193065593;4614.746188959606;4709.458412282588;4732.4321081470225;4793.424789025317;4795.784189711462;4637.628989680826;4688.367070445497;4718.9292160617615;4739.620830333573;4747.572718455338;4304.225239456912;4331.393604895317;4335.176316499871;4344.283193273106;4380.934285985712;4381.07997320563;4392.912141757163;4396.714213576123;4404.647628189737;4449.866504004885;4266.1467706458525;4320.308750599889;4361.389405483234;4374.431323927901;4432.024954510002;4282.314829627245;4344.391785150082;4354;4377.7592232427205;4422;4318.1363727254675;4339;4351.651867850596;4380.60574548289;4392.253316936129;4551.544845515455;4702.27114797207;4714.316424118484;4743.7628118594275;4764.118638051971;4975.651704380694;5051.722155281458;5075.28945947564;5153.201253805666;5158.071547121516;5179.637425380225;5194.039102766001;5197.948020519784;5203.19350500673;5206.427292997767;4814.229723244299;4815.735134567562;4823.107725070873;4823.3729615150505;4831.275308703723;5020.046191223656;5134.614903882224;5162.664426812245;5184.066867963803;5294.788208471695;4989;5019.899602007939;5027.421846487628;5034.11902916988;5036.395632524121;5089.134847076006;5091.643584949018;5128.410232823198;5136.691798492103;5138.07514647367;4904.975475122602;4928.211486162197;5036.7733451994745;5039.546440820343;5048.066107770074;4932.383452068491;4984.2025275955675;5002.924956125661;5028.497150284942;5051.4695956924;4309.245881970643;4339.175556644227;4345.174416860849;4346.217680817484;4385.978070109692;3727.5154229949935;3758.530183727034;3766.8116594170183;3780.281746468162;3810.0855794609647;4267.0825772459075;4281.486221653421;4323.63249123828;4348.869533913988;4380;5050.343455350854;5080.822171224063;5087.8982420351385;5094.847154585368;5103.030424219461;4342.804573794124;4344.087741574242;4350.73895566267;4352.608265256142;4390.363397307373;4446.5553444655925;4533.818647254139;4547.022390186125;4608.078384323148;4656.3946686930485;4753.738544380092;4783.976080119577;4788.353572267699;4789;4835.2505361669;5073.16292811682;5077.466865979167;5082.161443361952;5089.185730283137;5124.026434977342;4632.675712700078;4736.100140973342;4768.976155119202;4794.59245964083;4856.344393506832;4999.825006124768;5008.84973450797;5028.84913452597;5031.371078615173;5055.5955523557295;5063.392392912874;5068.391792984866;5086.465921078234;5100.846974590768;5114.769835357492;4795.544423279783;4894.070126675921;4920.6555541111775;4939.110960027229;4981.62637802159;4948.15881300187;4954.950450495449;4959.330490383896;4959.603231741524;5005.099082165245;4711.363965864564;4828.637852161031;4838.8790280241865;4880.41435027799;4883.902321953612;4921.04039712267;4922.92615610759;4974.403071631283;4977.377827771528;5028.094942910311;4891.119598472309;4900.191468407673;4901.950980490292;4903.53416425439;4944.1100601892 06:34:31 INFO - PROCESS | 10894 | |1;ext.html;573.4408951272508;574.6781802190773;583.3379114704809;586.5395664403443;590.0352922970941;29496.557551636717;39705.80147099264;40651;40838.38742418866;44440.11119777602;41244.793776031074;41643.37534936978;41657.58342416575;41675.791621041855;41724.791376043075;7594.658240379169;7728.806779830521;7735.033120859893;7736.9613151934445;7737.535747855119;7643.503172293811;7743.032120984877;7745.109312429058;7745.148033716287;7746;7743.18696536864;7783.883241751379;7786;7789.221077892222;7845.764627061198;7686.923130768705;7696.9615151924445;7701.845963080763;7702.46082774206;7703.345215656648;7461.552306861606;7541;7541.660625271821;7544.812004518683;7545.69817207311;39171.804140979395;39507;39525.60474395248;39544.80227598843;39548;3023.909282721522;3049.2681756378533;3066.8313242771637;3143.921401964946;3270.787398819093;29339.85330073357;29652;29674;29773.40453190924;29950;2919.5182794838825;2921.3134913295357;2922.8976985805607;2934.501134807088;2936.9853150734325;251852;252628;252965.99999999817;253032;253146;52157;52482;52500.21249681258;52525.474745252825;52575;32727.345453090806;33229.83385083059;33244.1688957778;35251.64748352535;35289.8235508826;14585.124892506483;14789.408423662933;14798.334074966653;14800.259987000822;14810.703785924437;10222.591096356062;10245.795084098276;10246.129079028216;10311.432871192157;10344.37933723979;18192.633767895873;18216.362427315245;18282.634347313244;18344.357947471774;18377.356792512197;6599.505037122235;6747.898781518183;6875.759348422881;7012.5792452453015;7017.789466316019;722.4148439763808;751.7406494759299;871.7428358634218;994.6667866264737;1020.9540570674337;25985.740142598333;27911.441771164464;28160.71839281581;28399.71600284012;28501.857490712828;812.5571563497908;916.9541522923827;929.9535023248945;952.9279560494443;955.073578628729;13628;14695.559133226021;14704.558863233906;15101.773473397909;15107.4712385066;413367;416109;416592;416891;419972.3009694646;50963;51124.74437627788;51241.487585124414;51498;51557;1045.2369770067742;1058.6612284069179;1116.0234794554765;1117.1342209787479;1128.1087940527057;26316.57884475006;28933.85533072321;29287.26781830386;29568.408631826813;30149;46880.29679554878;48192;48204.758976204896;52208.47791522036;52401.7379913098;360102;360861;363095;363687;365127;24578.754212458363;25552.74447255578;25663.615045774703;26808.8659556701;27076.1877143686;42073.78963105165;42475.787621061696;42845.78577107094;42914.57085429231;43085.35371969486;35056;35059.47410788891;35060.824695877374;35362.29275414529;35544.644553554135;35348.116297092776;35619.82190089033;35713.821430892676;36002.81998590095;36100.63899361078;34820.47769283412;35287.82356088203;35715;35882.8205858969;36170.45744313785;30485.23786905345;32533.511997320533;33078.50382244316;33177.66822331746;33322;24582.87708561446;24765.62851557192;25018.999240030196;25222.873885630455;25712.228633141036 06:34:31 INFO - PROCESS | 10894 | |2;jquery.html;342.9727964745587;358.6664402106042;364.64811456944045;366.67915573872864;379.5142217961011;12484.375781210938;16190.757138642915;16580.088095154762;16964.83035169648;18176.818231817677;16244.269007894643;16295.103769292684;16321.918390408062;16434.260458279376;16728.330866765315;4998.125328067583;5132.717700526469;5140.408852981899;5168.948310516903;5182.740862956856;3723.8882833514904;3892.2799282132896;4921.827736029221;5110.386753589574;5111.463296353885;3929.5873933237026;3934.4295077213787;5477.534409575191;5827.446392592697;5859.912101318462;5719.313682358102;5922.733476993524;5929.14027466015;5930.614510056833;5934.287885453773;5257.658252213632;5259.105951988133;5264.578833693296;5266.7366631668265;5266.8419947401635;20520.79479205204;21144.788552114434;21146.048427820784;21232.78767212339;21329.68005479919;1811.1578116176042;1855.4990152658706;1861.1810803246528;1910.8471322294183;2322.2917010312017;12747.61757147287;13314.533991310258;13415.262160581164;13557.661058473519;13867;1695.033830716492;1698;1748.9304389161746;1753.6668033073674;1802.0426450421612;68934.31065689329;70720.2927970724;70797.64601176961;71900;72072;9467.52662366879;9554.665586704432;9707.6116955321;9781.266405019653;9999.35004224723;13031.804522932163;13032.283224422557;13058.738825223443;13062.216267024009;13109.082364234411;7281.417486601059;7433.962830185815;7580.507267027626;7627.5042122261875;7663.923360766433;7280.745173918887;7333.523320984119;7344.045274114438;7520.887186692204;7550.735724249625;5991.970040149771;5993.190919225936;6092.51259899207;6128.724207410677;6254.093156492374;1619.1175809184024;1703.7955445346638;1720.9225584848712;1770.433461292374;1771.4154329071375;818.4352796570347;885.4819930340823;895.5701263393478;927.2720914082413;961.6874515782399;16553.10581491062;16705.83294167067;17645.117744112744;17691.38080167188;17947.910260448614;796.2077732656045;800.4836880212231;812.813052997802;814.621201141472;820.8686610142468;9673.177779888789;9720.562574684156;9932.255080868963;10087.243456740627;10150.593976240867;362213;378366;378735;380357;381952;70773.29226707667;71715.64142179256;72069;72894.9065764014;82163.5891820537;934.2572654739498;951.048951048951;957.8850537935492;957.9952100239454;958.5446912716543;15675.608109797346;15920.283587238371;16254.43109491162;16267.3493060281;16630.417935372687;35602.46596301109;35958.46062309015;36326.818365908;39567.40648890211;39694.404583930685;47406;50446.74776626093;50686;51314.48685513097;51917.76986761613;6505.60966342002;6575.408213260829;6645.966770166118;6809.965950170217;7595.3164215220895;7364.889526657212;8819.735407937773;11772.764544709229;12028.578999734968;12636.747265054832;9812.754681133028;10024.348417352849;10498.737531561772;10548.894511054792;10801.945990269998;4743.952560474351;9838.193474233012;10235.7441063974;10387.68836934893;11001.834972475259;9768.462734549668;10040.347377420445;10671.519781609692;10686.73283167927;10753.46232688378;6269.937300626935;6560.967195164185;6712.261651218264;6857.759978400732;6958.965205174144;7696.307332340116;9488.95255523718;9766.902330976884;9805.803883922425;10012.799744004933 06:34:31 INFO - PROCESS | 10894 | |3;mootools.html;1243.365883399466;1332.3271747767378;1392.5056604905258;1414.7807089901069;1600.9519714408564;6394.488440924726;7415.740449084283;7693.653785579649;7700.114486834016;7851.842963140733;7694.807629809257;7752;7758;7758;7759;2129.5847309774563;2146.624340740372;2155.1594877997595;2156.5471251037225;2159.384575396012;2129.1802655977403;2170.4248374180856;2177.5318306564163;2178.6078505868936;2184.563087382522;2140.4969832089446;2163.945901352471;2167.6315026445463;2174.369432864475;2175.8912054397297;2096.6540520814046;2097.2974053692033;2098.8635738677012;2100.1389430333643;2103.9474013149643;1997.7902320256455;2085.165933626546;2087.832973362127;2089.185217765073;2094.49732064305;7293.124825021031;7743.148253692089;7834.33408160307;7843.372530197627;7848.7645370638975;4283;4480.171168333836;4500.774961251925;4515.548445155491;4517.322401639747;2843.8720257588457;2926.8536573171255;2972.271793410628;2978.091682036978;2986.970130298691;969.6412327438823;977.6480467031863;983.0759086458777;994.5027486256871;998.7403275148441;10278.434686092261;10341.224408169417;10350.482475876175;10384.844227336596;10402.271840971134;3844.865764599394;3856.768593884376;3892.065904182976;3908.9609103909165;3926.54844692864;7722.613869306512;7755.2632499912415;7773.261540153676;7778.299953004257;7779.066512018594;4273.829046838154;4275;4275.657947364203;4278.593533614302;4281.593248641374;3561.554805649294;3821.1593449441634;3942.250972315251;3950.328444164499;3996.600339966038;3380.7464440167087;3388.2207092368894;3521.7710848794745;3522.559680039995;3537.7877327360443;1026.3995562595817;1030.5414090729553;1036.2435422141878;1052.9052385285208;1059.2743970380313;699.2937133495207;719.9280071992864;725.0863911471583;738.2174894611729;766.0424469413233;4809.038192361541;4884.0476107159475;4967.130752118364;4984.27727979441;5117.462666419972;398.954120276172;423.44105780369614;426.70770522192396;426.90608066226;436.0885748784991;3335.4163021471145;3338.21551935295;3351;3419.863205471803;3457.291255292659;9216.354855160209;9558.90441095594;9609.375390599589;9611.903880961241;9651.565679544437;9509.239260859253;9514.714558563253;9528.237740980705;9545.713628591153;9558.139767420966;7165;7221.386182174574;7249.601271929989;7298.671559779823;7320.670569824264;4790.161721698759;4941;4967.925481117858;4977.303177555073;5002.974985125051;8848.778780530538;8860.202581767671;8873.689420870238;8885.644574216958;8956.865647015169;8819.250363719155;9094.499407597672;9232.861507077534;9279.118483744034;9288.442693438416;5747.19539264495;6149.446549810538;6405.583637063576;6470.805875823733;6486.5459417842185;7350.558966462029;7460.29127232912;7512.924870751441;7523.1348394935285;7530.246975302426;6358.77744278948;6404.327545607644;6467.514936379696;6588.110605068255;6662.933370666426;6200.7519699211525;6217.440430361289;6377.298497165215;6482.222133344029;6528.9020664689115;5853.209816674865;5999.400059993965;6002.369751176064;6006.669633170218;6009.06859436788;4342;4415.712978656377;4436.179306828247;4517.909641807079;4587.311903214544;4376.671749618855;4377.321515165155;4457.910841783211;4567.908641827211;4627.1439783640135 06:34:31 INFO - PROCESS | 10894 | |4;prototype.html;298.5357768669719;300.7503771869349;304.3076999825397;309.4182936080169;309.4569031349981;4870.780814863331;4874.268859671051;4893.849945262865;4909.361782968218;4926.876828079296;4828.444728856174;4829.323894654743;4830.613550915927;4831.613470922327;4850.442199147099;3631.509746184258;3745.981270093646;3750.4374343848367;3752.699784017272;3759.3233218020746;3676.1177317443753;3754.9436758448646;3784.432335149722;3849.1724279279947;3874.9418758718643;3797.411401232813;3806.5622453417936;3809.314323421783;3810.6760925321382;3814.4278358246206;3541.8052007139595;3738.149615940127;3789.6399842014966;3796.563395209545;3815.923681526354;3250.780957141072;3265.6407795142513;3268.1176082457605;3278.852451639682;3280.9671903281137;4700.1539722849875;4702.260163739438;4707.340972263886;4711.623070154414;4715.528447155291;4252.4046633471335;4284.892877678052;4292;4296.5273819879785;4301.354796780477;4945.233488809205;5008.974955125237;5054.646174767768;5082.491750824925;5102.974485127587;3598.229363390175;3601.7658852174527;3603.423452247654;3605.5938184108218;3609.4946707460977;5312.335958005249;5335.692755274982;5340.504658695532;5342.5993050521365;5344.812931547416;3477.2697733476252;3651;3659.8719044833833;3711.4247291669835;3714.551324983259;3700.1119731264303;3701.7408781385043;3706;3707.666310032056;3723.2925744108534;4223.746575205498;4231.851885184004;4252.213340532012;4265.6563182598;4266.442748396875;4923.335349727812;5087.440381558025;5098.668586541863;5133.383993920754;5147.562457191106;1283.640580637423;1296.4036543190239;1304.9021323400782;1305.830242068544;1308.2019967819622;781.5193655901693;815.388458656008;822.5681517203516;827.3174630929507;827.9172082791673;4286.949697324176;4360.978195109067;4421.695599798068;4480.14877173336;4494.011317510142;5222.92165617516;5245;5250.422453530108;5258.421573626898;5288.233206185082;4468.173387923244;4477.731336119843;4493.483249426358;4497.123061003138;4505.864824055284;2658.322127857423;2747.3818390861898;2766.349907771672;2773.972260277412;2782.2627003844;4908.435529914105;4920.8769780755765;4929.359183306147;4931.4821943696115;4953.23224900141;5195.19474481456;5343.572514198855;5348.91976620351;5362.249285100089;5376.301080859464;4849.830255941025;4911.877203069952;4925.448483727614;4942.604591632589;4943.134951383494;5215.608829337891;5216.817411390583;5219.243209734643;5229.581633469389;5239.502247286502;5000.974995125147;5060.58305646204;5104.1067813133295;5105.744712764421;5126.2054381570915;4902.436219834764;4913.508649135058;4932.802687892444;4954.281629163823;4963.478834722447;3774.094217387862;3793.1844653399653;3806.2577797329254;3809.828557714965;3812.904677383088;4896.804127834989;5138.3577052868395;5154.407243167084;5170.974145129401;5193.636445448933;4963.205887058053;5000.199968005102;5001.324821149098;5025.648204625787;5031.874203144951;5039.672421292605;5041.596672266283;5042.974785126051;5046;5051.520105589963;4712.316714076371;4922.335484709519;4943.728094954812;4946.282788995503;4947.678400903929;4936.9259461107395;5063.620228482952;5126.179811230185;5151.5106064923775;5184.870378240575;4882.60939124862;4950.579200767975;4958.677685950402;4961.479044700254;5053.29253904461 06:34:31 INFO - PROCESS | 10894 | |5;yui.html;572.5791543215736;604.8085272014137;607.9300880398755;611.4222060153155;616.9049936362956;12986.54547090852;13885;14001.299935003248;14022.158670479754;14023.649408764786;13070.215787052783;13278.402471888765;13715.725685486284;13936.372863221153;14410.639734006654;625.7121724006962;748.4386709967524;760.3650951455528;777.2848978939361;779.2168870285384;759.0246533204833;779.6920216514474;780.8945792318021;782;782.178712352031;820.1347578304886;821.1706176761484;822.0874828939872;822.1244374740912;822.3873214455239;652.5595223224352;676.1041619853664;819.4223072733708;819.6147810529042;819.737683941139;496.54566072044037;497.16228155558;568.5252813900398;583.5739909865779;590.5128269177945;12599.685007874785;12616.369181540886;12643.873561264454;12649.494020239179;12710.110292279544;11314.943425282821;12010.699732506757;12059.698507537381;12098.758024839542;12372.072094592851;10074.496275186284;10254.53854576546;11122.666320010412;11222;11332.49003794823;3134.45147099259;3222.1622378181605;3342.5988881334397;3469;3473.11435583927;53206.20190697143;53650;54183.18725219125;61359.38640613581;61536.69231653858;9830.803383932282;10466.058054775107;10542.367457952547;10632.46837658114;10899.891001089887;20215.595688086156;20314.79685203129;20414.38756837297;20546.486337841674;20607.690884636744;6528.183977002875;6641.302663220389;6678.866422671616;6798.8300292492095;7048.224695283515;3640.581333146669;3651.8721844735305;3653.397189463709;3671.52270204877;3687.078230442389;2876.669183043977;2951.4687356276077;2959.9704002959693;2973.8067025643263;2977.2556860784803;497.32363984980395;498.4100718707312;510.2601228219097;511.4348644747575;517.8265281130788;416.61046921128843;499.4206720204545;507.8222622082242;517.3145582103652;518.8650950752756;7416.851662966818;7463.888041679379;7703.460757747016;7746.883796743054;8010.71962481322;477.11733293406917;488.16280079663545;489.9142650036229;491.55268705477846;501.7691861743639;3439.94840077399;3469.115375579211;3475.5308033415167;3487.5640544931885;3551.6448355164794;96840;99902.50048749709;103949.48025259825;106530;107781;87322;90317.54841225884;92782.53608732048;95409;95550.0444995555;72121;79598.60200698959;80064.99999999884;80416.59791701005;81166.18833811705;6635.834104147435;6648.7340506379205;7117.252688467636;7157.427405807419;7212.098487689039;45528.772356138004;46050.30924536067;46991.530084698716;47434.288485672056;48068.5193148078;67001;70609.64695176491;70652.646736766;71044.28955710377;72416.27583724096;9371.390859594105;10118.494075296352;10192.541335640062;10219.897801022193;10301.536430860479;10709;10752.354858708502;10863.066971917697;11288;11294.04000659953;9475;9526.714198574055;9620.182284505896;10197.337173083726;10206.285560010729;9355.391899526508;9538.761530961781;9636.855447168438;9952.203823694232;9953.751156221153;9633.207802480414;9895.703128906145;10032.799344012932;10150;10283.43441110746;8138.87791683137;8318.62566184511;8354.582270886553;8604.182602652738;8870.068642792414;7485.812854678677;7791.610419479117;8010.559419231999;8105.513669179868;8700.999385070798 06:34:31 INFO - PROCESS | 10894 | __end_tp_report 06:34:31 INFO - PROCESS | 10894 | __start_cc_report 06:34:31 INFO - PROCESS | 10894 | _x_x_mozilla_cycle_collect,1093 06:34:31 INFO - PROCESS | 10894 | __end_cc_report 06:34:31 INFO - PROCESS | 10894 | __startTimestamp1471527271583__endTimestamp 06:34:31 INFO - PROCESS | 10894 | 06:34:31 INFO - PROCESS | 10894 | ------- Summary: start ------- 06:34:31 INFO - PROCESS | 10894 | Number of tests: 6 06:34:31 INFO - PROCESS | 10894 | 06:34:31 INFO - PROCESS | 10894 | [#0] dojo.html Cycles:165 Average:4687.67 Median:4823.11 stddev:498.51 (10.3%) stddev-sans-first:471.80 06:34:31 INFO - PROCESS | 10894 | Values: 2579.2 2601.7 2612.9 2630.6 2633.4 4614.7 4709.5 4732.4 4793.4 4795.8 4637.6 4688.4 4718.9 4739.6 4747.6 4304.2 4331.4 4335.2 4344.3 4380.9 4381.1 4392.9 4396.7 4404.6 4449.9 4266.1 4320.3 4361.4 4374.4 4432.0 4282.3 4344.4 4354.0 4377.8 4422.0 4318.1 4339.0 4351.7 4380.6 4392.3 4551.5 4702.3 4714.3 4743.8 4764.1 4975.7 5051.7 5075.3 5153.2 5158.1 5179.6 5194.0 5197.9 5203.2 5206.4 4814.2 4815.7 4823.1 4823.4 4831.3 5020.0 5134.6 5162.7 5184.1 5294.8 4989.0 5019.9 5027.4 5034.1 5036.4 5089.1 5091.6 5128.4 5136.7 5138.1 4905.0 4928.2 5036.8 5039.5 5048.1 4932.4 4984.2 5002.9 5028.5 5051.5 4309.2 4339.2 4345.2 4346.2 4386.0 3727.5 3758.5 3766.8 3780.3 3810.1 4267.1 4281.5 4323.6 4348.9 4380.0 5050.3 5080.8 5087.9 5094.8 5103.0 4342.8 4344.1 4350.7 4352.6 4390.4 4446.6 4533.8 4547.0 4608.1 4656.4 4753.7 4784.0 4788.4 4789.0 4835.3 5073.2 5077.5 5082.2 5089.2 5124.0 4632.7 4736.1 4769.0 4794.6 4856.3 4999.8 5008.8 5028.8 5031.4 5055.6 5063.4 5068.4 5086.5 5100.8 5114.8 4795.5 4894.1 4920.7 4939.1 4981.6 4948.2 4955.0 4959.3 4959.6 5005.1 4711.4 4828.6 4838.9 4880.4 4883.9 4921.0 4922.9 4974.4 4977.4 5028.1 4891.1 4900.2 4902.0 4903.5 4944.1 06:34:31 INFO - PROCESS | 10894 | 06:34:31 INFO - PROCESS | 10894 | [#1] ext.html Cycles:180 Average:49195.34 Median:360481.50 stddev:92645.64 (25.7%) stddev-sans-first:92833.61 06:34:31 INFO - PROCESS | 10894 | Values: 573.4 574.7 583.3 586.5 590.0 29496.6 39705.8 40651.0 40838.4 44440.1 41244.8 41643.4 41657.6 41675.8 41724.8 7594.7 7728.8 7735.0 7737.0 7737.5 7643.5 7743.0 7745.1 7745.1 7746.0 7743.2 7783.9 7786.0 7789.2 7845.8 7686.9 7697.0 7701.8 7702.5 7703.3 7461.6 7541.0 7541.7 7544.8 7545.7 39171.8 39507.0 39525.6 39544.8 39548.0 3023.9 3049.3 3066.8 3143.9 3270.8 29339.9 29652.0 29674.0 29773.4 29950.0 2919.5 2921.3 2922.9 2934.5 2937.0 251852.0 252628.0 252966.0 253032.0 253146.0 52157.0 52482.0 52500.2 52525.5 52575.0 32727.3 33229.8 33244.2 35251.6 35289.8 14585.1 14789.4 14798.3 14800.3 14810.7 10222.6 10245.8 10246.1 10311.4 10344.4 18192.6 18216.4 18282.6 18344.4 18377.4 6599.5 6747.9 6875.8 7012.6 7017.8 722.4 751.7 871.7 994.7 1021.0 25985.7 27911.4 28160.7 28399.7 28501.9 812.6 917.0 930.0 952.9 955.1 13628.0 14695.6 14704.6 15101.8 15107.5 413367.0 416109.0 416592.0 416891.0 419972.3 50963.0 51124.7 51241.5 51498.0 51557.0 1045.2 1058.7 1116.0 1117.1 1128.1 26316.6 28933.9 29287.3 29568.4 30149.0 46880.3 48192.0 48204.8 52208.5 52401.7 360102.0 360861.0 363095.0 363687.0 365127.0 24578.8 25552.7 25663.6 26808.9 27076.2 42073.8 42475.8 42845.8 42914.6 43085.4 35056.0 35059.5 35060.8 35362.3 35544.6 35348.1 35619.8 35713.8 36002.8 36100.6 34820.5 35287.8 35715.0 35882.8 36170.5 30485.2 32533.5 33078.5 33177.7 33322.0 24582.9 24765.6 25019.0 25222.9 25712.2 06:34:31 INFO - PROCESS | 10894 | 06:34:31 INFO - PROCESS | 10894 | [#2] jquery.html Cycles:180 Average:23905.73 Median:50566.37 stddev:62204.30 (123.0%) stddev-sans-first:62353.64 06:34:31 INFO - PROCESS | 10894 | Values: 343.0 358.7 364.6 366.7 379.5 12484.4 16190.8 16580.1 16964.8 18176.8 16244.3 16295.1 16321.9 16434.3 16728.3 4998.1 5132.7 5140.4 5168.9 5182.7 3723.9 3892.3 4921.8 5110.4 5111.5 3929.6 3934.4 5477.5 5827.4 5859.9 5719.3 5922.7 5929.1 5930.6 5934.3 5257.7 5259.1 5264.6 5266.7 5266.8 20520.8 21144.8 21146.0 21232.8 21329.7 1811.2 1855.5 1861.2 1910.8 2322.3 12747.6 13314.5 13415.3 13557.7 13867.0 1695.0 1698.0 1748.9 1753.7 1802.0 68934.3 70720.3 70797.6 71900.0 72072.0 9467.5 9554.7 9707.6 9781.3 9999.4 13031.8 13032.3 13058.7 13062.2 13109.1 7281.4 7434.0 7580.5 7627.5 7663.9 7280.7 7333.5 7344.0 7520.9 7550.7 5992.0 5993.2 6092.5 6128.7 6254.1 1619.1 1703.8 1720.9 1770.4 1771.4 818.4 885.5 895.6 927.3 961.7 16553.1 16705.8 17645.1 17691.4 17947.9 796.2 800.5 812.8 814.6 820.9 9673.2 9720.6 9932.3 10087.2 10150.6 362213.0 378366.0 378735.0 380357.0 381952.0 70773.3 71715.6 72069.0 72894.9 82163.6 934.3 951.0 957.9 958.0 958.5 15675.6 15920.3 16254.4 16267.3 16630.4 35602.5 35958.5 36326.8 39567.4 39694.4 47406.0 50446.7 50686.0 51314.5 51917.8 6505.6 6575.4 6646.0 6810.0 7595.3 7364.9 8819.7 11772.8 12028.6 12636.7 9812.8 10024.3 10498.7 10548.9 10801.9 4744.0 9838.2 10235.7 10387.7 11001.8 9768.5 10040.3 10671.5 10686.7 10753.5 6269.9 6561.0 6712.3 6857.8 6959.0 7696.3 9489.0 9766.9 9805.8 10012.8 06:34:31 INFO - PROCESS | 10894 | 06:34:31 INFO - PROCESS | 10894 | [#3] mootools.html Cycles:180 Average:4950.15 Median:4799.60 stddev:2830.76 (59.0%) stddev-sans-first:2824.99 06:34:31 INFO - PROCESS | 10894 | Values: 1243.4 1332.3 1392.5 1414.8 1601.0 6394.5 7415.7 7693.7 7700.1 7851.8 7694.8 7752.0 7758.0 7758.0 7759.0 2129.6 2146.6 2155.2 2156.5 2159.4 2129.2 2170.4 2177.5 2178.6 2184.6 2140.5 2163.9 2167.6 2174.4 2175.9 2096.7 2097.3 2098.9 2100.1 2103.9 1997.8 2085.2 2087.8 2089.2 2094.5 7293.1 7743.1 7834.3 7843.4 7848.8 4283.0 4480.2 4500.8 4515.5 4517.3 2843.9 2926.9 2972.3 2978.1 2987.0 969.6 977.6 983.1 994.5 998.7 10278.4 10341.2 10350.5 10384.8 10402.3 3844.9 3856.8 3892.1 3909.0 3926.5 7722.6 7755.3 7773.3 7778.3 7779.1 4273.8 4275.0 4275.7 4278.6 4281.6 3561.6 3821.2 3942.3 3950.3 3996.6 3380.7 3388.2 3521.8 3522.6 3537.8 1026.4 1030.5 1036.2 1052.9 1059.3 699.3 719.9 725.1 738.2 766.0 4809.0 4884.0 4967.1 4984.3 5117.5 399.0 423.4 426.7 426.9 436.1 3335.4 3338.2 3351.0 3419.9 3457.3 9216.4 9558.9 9609.4 9611.9 9651.6 9509.2 9514.7 9528.2 9545.7 9558.1 7165.0 7221.4 7249.6 7298.7 7320.7 4790.2 4941.0 4967.9 4977.3 5003.0 8848.8 8860.2 8873.7 8885.6 8956.9 8819.3 9094.5 9232.9 9279.1 9288.4 5747.2 6149.4 6405.6 6470.8 6486.5 7350.6 7460.3 7512.9 7523.1 7530.2 6358.8 6404.3 6467.5 6588.1 6662.9 6200.8 6217.4 6377.3 6482.2 6528.9 5853.2 5999.4 6002.4 6006.7 6009.1 4342.0 4415.7 4436.2 4517.9 4587.3 4376.7 4377.3 4457.9 4567.9 4627.1 06:34:31 INFO - PROCESS | 10894 | 06:34:31 INFO - PROCESS | 10894 | [#4] prototype.html Cycles:180 Average:4203.70 Median:4850.14 stddev:1225.99 (25.3%) stddev-sans-first:1193.88 06:34:31 INFO - PROCESS | 10894 | Values: 298.5 300.8 304.3 309.4 309.5 4870.8 4874.3 4893.8 4909.4 4926.9 4828.4 4829.3 4830.6 4831.6 4850.4 3631.5 3746.0 3750.4 3752.7 3759.3 3676.1 3754.9 3784.4 3849.2 3874.9 3797.4 3806.6 3809.3 3810.7 3814.4 3541.8 3738.1 3789.6 3796.6 3815.9 3250.8 3265.6 3268.1 3278.9 3281.0 4700.2 4702.3 4707.3 4711.6 4715.5 4252.4 4284.9 4292.0 4296.5 4301.4 4945.2 5009.0 5054.6 5082.5 5103.0 3598.2 3601.8 3603.4 3605.6 3609.5 5312.3 5335.7 5340.5 5342.6 5344.8 3477.3 3651.0 3659.9 3711.4 3714.6 3700.1 3701.7 3706.0 3707.7 3723.3 4223.7 4231.9 4252.2 4265.7 4266.4 4923.3 5087.4 5098.7 5133.4 5147.6 1283.6 1296.4 1304.9 1305.8 1308.2 781.5 815.4 822.6 827.3 827.9 4286.9 4361.0 4421.7 4480.1 4494.0 5222.9 5245.0 5250.4 5258.4 5288.2 4468.2 4477.7 4493.5 4497.1 4505.9 2658.3 2747.4 2766.3 2774.0 2782.3 4908.4 4920.9 4929.4 4931.5 4953.2 5195.2 5343.6 5348.9 5362.2 5376.3 4849.8 4911.9 4925.4 4942.6 4943.1 5215.6 5216.8 5219.2 5229.6 5239.5 5001.0 5060.6 5104.1 5105.7 5126.2 4902.4 4913.5 4932.8 4954.3 4963.5 3774.1 3793.2 3806.3 3809.8 3812.9 4896.8 5138.4 5154.4 5171.0 5193.6 4963.2 5000.2 5001.3 5025.6 5031.9 5039.7 5041.6 5043.0 5046.0 5051.5 4712.3 4922.3 4943.7 4946.3 4947.7 4936.9 5063.6 5126.2 5151.5 5184.9 4882.6 4950.6 4958.7 4961.5 5053.3 06:34:31 INFO - PROCESS | 10894 | 06:34:31 INFO - PROCESS | 10894 | [#5] yui.html Cycles:180 Average:18032.76 Median:517.57 stddev:27053.85 (5227.1%) stddev-sans-first:27097.98 06:34:31 INFO - PROCESS | 10894 | Values: 572.6 604.8 607.9 611.4 616.9 12986.5 13885.0 14001.3 14022.2 14023.6 13070.2 13278.4 13715.7 13936.4 14410.6 625.7 748.4 760.4 777.3 779.2 759.0 779.7 780.9 782.0 782.2 820.1 821.2 822.1 822.1 822.4 652.6 676.1 819.4 819.6 819.7 496.5 497.2 568.5 583.6 590.5 12599.7 12616.4 12643.9 12649.5 12710.1 11314.9 12010.7 12059.7 12098.8 12372.1 10074.5 10254.5 11122.7 11222.0 11332.5 3134.5 3222.2 3342.6 3469.0 3473.1 53206.2 53650.0 54183.2 61359.4 61536.7 9830.8 10466.1 10542.4 10632.5 10899.9 20215.6 20314.8 20414.4 20546.5 20607.7 6528.2 6641.3 6678.9 6798.8 7048.2 3640.6 3651.9 3653.4 3671.5 3687.1 2876.7 2951.5 2960.0 2973.8 2977.3 497.3 498.4 510.3 511.4 517.8 416.6 499.4 507.8 517.3 518.9 7416.9 7463.9 7703.5 7746.9 8010.7 477.1 488.2 489.9 491.6 501.8 3439.9 3469.1 3475.5 3487.6 3551.6 96840.0 99902.5 103949.5 106530.0 107781.0 87322.0 90317.5 92782.5 95409.0 95550.0 72121.0 79598.6 80065.0 80416.6 81166.2 6635.8 6648.7 7117.3 7157.4 7212.1 45528.8 46050.3 46991.5 47434.3 48068.5 67001.0 70609.6 70652.6 71044.3 72416.3 9371.4 10118.5 10192.5 10219.9 10301.5 10709.0 10752.4 10863.1 11288.0 11294.0 9475.0 9526.7 9620.2 10197.3 10206.3 9355.4 9538.8 9636.9 9952.2 9953.8 9633.2 9895.7 10032.8 10150.0 10283.4 8138.9 8318.6 8354.6 8604.2 8870.1 7485.8 7791.6 8010.6 8105.5 8701.0 06:34:31 INFO - PROCESS | 10894 | -------- Summary: end -------- 06:34:31 INFO - PROCESS | 10894 | 06:34:32 INFO - TEST-INFO | 10894: exit 0 06:34:32 INFO - TEST-OK | dromaeo_css | took 1121396ms 06:34:32 INFO - TEST-START | kraken 06:34:32 INFO - operating with platform_type : linux_ 06:34:32 INFO - Initialising browser for kraken test... 06:34:32 INFO - TEST-INFO | started process 11198 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRJ0j41/profile http://localhost:60957/getInfo.html) 06:34:37 INFO - PROCESS | 11198 | __metrics Screen width/height:1600/1200 06:34:37 INFO - PROCESS | 11198 | colorDepth:24 06:34:37 INFO - PROCESS | 11198 | Browser inner width/height: 1024/697 06:34:37 INFO - PROCESS | 11198 | __metrics 06:34:38 INFO - PROCESS | 11198 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:34:38 INFO - PROCESS | 11198 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:34:38 INFO - PROCESS | 11198 | console.error: PushService: 06:34:38 INFO - PROCESS | 11198 | stateChangeProcessEnqueue: Error transitioning state 06:34:38 INFO - PROCESS | 11198 | UnknownError 06:34:39 INFO - TEST-INFO | 11198: exit 0 06:34:39 INFO - Browser initialized. 06:34:39 INFO - Running cycle 1/1 for kraken test... 06:34:39 INFO - TEST-INFO | started process 11279 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRJ0j41/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:34:51 INFO - PROCESS | 11279 | RSS: Main: 162033664 06:34:51 INFO - PROCESS | 11279 | 06:34:54 INFO - PROCESS | 11279 | Cycle 1(1): loaded http://localhost:60957/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60957/tests/kraken/driver.html?testName=audio-beat-detection) 06:34:55 INFO - PROCESS | 11279 | RSS: Main: 209772544 06:34:55 INFO - PROCESS | 11279 | 06:34:57 INFO - PROCESS | 11279 | Cycle 1(1): loaded http://localhost:60957/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60957/tests/kraken/driver.html?testName=audio-dft) 06:34:58 INFO - PROCESS | 11279 | RSS: Main: 201089024 06:34:58 INFO - PROCESS | 11279 | 06:35:01 INFO - PROCESS | 11279 | Cycle 1(1): loaded http://localhost:60957/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60957/tests/kraken/driver.html?testName=audio-fft) 06:35:02 INFO - PROCESS | 11279 | RSS: Main: 282570752 06:35:02 INFO - PROCESS | 11279 | 06:35:04 INFO - PROCESS | 11279 | Cycle 1(1): loaded http://localhost:60957/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60957/tests/kraken/driver.html?testName=audio-oscillator) 06:35:05 INFO - PROCESS | 11279 | RSS: Main: 191885312 06:35:05 INFO - PROCESS | 11279 | 06:35:07 INFO - PROCESS | 11279 | Cycle 1(1): loaded http://localhost:60957/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60957/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:35:07 INFO - PROCESS | 11279 | RSS: Main: 196485120 06:35:07 INFO - PROCESS | 11279 | 06:35:11 INFO - PROCESS | 11279 | Cycle 1(1): loaded http://localhost:60957/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60957/tests/kraken/driver.html?testName=imaging-darkroom) 06:35:11 INFO - PROCESS | 11279 | RSS: Main: 233594880 06:35:11 INFO - PROCESS | 11279 | 06:35:15 INFO - PROCESS | 11279 | Cycle 1(1): loaded http://localhost:60957/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60957/tests/kraken/driver.html?testName=imaging-desaturate) 06:35:15 INFO - PROCESS | 11279 | RSS: Main: 232357888 06:35:15 INFO - PROCESS | 11279 | 06:35:18 INFO - PROCESS | 11279 | Cycle 1(1): loaded http://localhost:60957/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60957/tests/kraken/driver.html?testName=json-parse-financial) 06:35:19 INFO - PROCESS | 11279 | RSS: Main: 232218624 06:35:19 INFO - PROCESS | 11279 | 06:35:21 INFO - PROCESS | 11279 | Cycle 1(1): loaded http://localhost:60957/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60957/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:35:21 INFO - PROCESS | 11279 | RSS: Main: 194768896 06:35:21 INFO - PROCESS | 11279 | 06:35:23 INFO - PROCESS | 11279 | Cycle 1(1): loaded http://localhost:60957/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60957/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:35:24 INFO - PROCESS | 11279 | RSS: Main: 197570560 06:35:24 INFO - PROCESS | 11279 | 06:35:26 INFO - PROCESS | 11279 | Cycle 1(1): loaded http://localhost:60957/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60957/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:35:27 INFO - PROCESS | 11279 | RSS: Main: 195284992 06:35:27 INFO - PROCESS | 11279 | 06:35:29 INFO - PROCESS | 11279 | Cycle 1(1): loaded http://localhost:60957/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60957/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:35:30 INFO - PROCESS | 11279 | RSS: Main: 190312448 06:35:30 INFO - PROCESS | 11279 | 06:35:33 INFO - PROCESS | 11279 | Cycle 1(1): loaded http://localhost:60957/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60957/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:35:34 INFO - PROCESS | 11279 | RSS: Main: 193773568 06:35:34 INFO - PROCESS | 11279 | 06:35:35 INFO - PROCESS | 11279 | Cycle 1(1): loaded http://localhost:60957/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60957/tests/kraken/driver.html?testName=ai-astar) 06:35:36 INFO - PROCESS | 11279 | RSS: Main: 244875264 06:35:36 INFO - PROCESS | 11279 | 06:35:36 INFO - PROCESS | 11279 | __start_tp_report 06:35:36 INFO - PROCESS | 11279 | _x_x_mozilla_page_load 06:35:36 INFO - PROCESS | 11279 | _x_x_mozilla_page_load_details 06:35:36 INFO - PROCESS | 11279 | |i|pagename|runs| 06:35:36 INFO - PROCESS | 11279 | |0;ai-astar;106;116;113;115;109;114;115;114;114;115 06:35:36 INFO - PROCESS | 11279 | |1;audio-beat-detection;165;125;130;200;126;127;152;123;180;129 06:35:36 INFO - PROCESS | 11279 | |2;audio-dft;161;160;160;160;160;161;161;162;162;162 06:35:36 INFO - PROCESS | 11279 | |3;audio-fft;76;75;77;110;76;78;80;75;104;75 06:35:36 INFO - PROCESS | 11279 | |4;audio-oscillator;89;87;127;87;86;86;115;86;86;127 06:35:36 INFO - PROCESS | 11279 | |5;imaging-gaussian-blur;117;117;117;116;117;116;117;116;117;117 06:35:36 INFO - PROCESS | 11279 | |6;imaging-darkroom;147;147;146;147;146;147;147;146;146;146 06:35:36 INFO - PROCESS | 11279 | |7;imaging-desaturate;94;95;95;95;95;95;95;96;95;95 06:35:36 INFO - PROCESS | 11279 | |8;json-parse-financial;64;63;65;64;65;67;64;64;66;64 06:35:36 INFO - PROCESS | 11279 | |9;json-stringify-tinderbox;65;65;65;66;67;66;66;66;66;66 06:35:36 INFO - PROCESS | 11279 | |10;stanford-crypto-aes;82;82;88;85;82;82;92;82;82;82 06:35:36 INFO - PROCESS | 11279 | |11;stanford-crypto-ccm;132;127;132;131;130;140;130;135;145;138 06:35:36 INFO - PROCESS | 11279 | |12;stanford-crypto-pbkdf2;169;169;171;173;169;170;175;175;170;176 06:35:36 INFO - PROCESS | 11279 | |13;stanford-crypto-sha256-iterative;67;67;68;67;67;67;67;67;71;66 06:35:36 INFO - PROCESS | 11279 | __end_tp_report 06:35:36 INFO - PROCESS | 11279 | __start_cc_report 06:35:36 INFO - PROCESS | 11279 | _x_x_mozilla_cycle_collect,1941 06:35:36 INFO - PROCESS | 11279 | __end_cc_report 06:35:36 INFO - PROCESS | 11279 | __startTimestamp1471527336056__endTimestamp 06:35:36 INFO - PROCESS | 11279 | 06:35:36 INFO - PROCESS | 11279 | ------- Summary: start ------- 06:35:36 INFO - PROCESS | 11279 | Number of tests: 14 06:35:36 INFO - PROCESS | 11279 | 06:35:36 INFO - PROCESS | 11279 | [#0] ai-astar Cycles:10 Average:113.10 Median:114.50 stddev:3.14 (2.7%) stddev-sans-first:2.03 06:35:36 INFO - PROCESS | 11279 | Values: 106.0 116.0 113.0 115.0 109.0 114.0 115.0 114.0 114.0 115.0 06:35:36 INFO - PROCESS | 11279 | 06:35:36 INFO - PROCESS | 11279 | [#1] audio-beat-detection Cycles:10 Average:145.70 Median:141.00 stddev:27.37 (19.4%) stddev-sans-first:28.13 06:35:36 INFO - PROCESS | 11279 | Values: 165.0 125.0 130.0 200.0 126.0 127.0 152.0 123.0 180.0 129.0 06:35:36 INFO - PROCESS | 11279 | 06:35:36 INFO - PROCESS | 11279 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.88 (0.5%) stddev-sans-first:0.93 06:35:36 INFO - PROCESS | 11279 | Values: 161.0 160.0 160.0 160.0 160.0 161.0 161.0 162.0 162.0 162.0 06:35:36 INFO - PROCESS | 11279 | 06:35:36 INFO - PROCESS | 11279 | [#3] audio-fft Cycles:10 Average:82.60 Median:76.00 stddev:13.03 (17.1%) stddev-sans-first:13.60 06:35:36 INFO - PROCESS | 11279 | Values: 76.0 75.0 77.0 110.0 76.0 78.0 80.0 75.0 104.0 75.0 06:35:36 INFO - PROCESS | 11279 | 06:35:36 INFO - PROCESS | 11279 | [#4] audio-oscillator Cycles:10 Average:97.60 Median:86.00 stddev:17.85 (20.8%) stddev-sans-first:18.66 06:35:36 INFO - PROCESS | 11279 | Values: 89.0 87.0 127.0 87.0 86.0 86.0 115.0 86.0 86.0 127.0 06:35:36 INFO - PROCESS | 11279 | 06:35:36 INFO - PROCESS | 11279 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 06:35:36 INFO - PROCESS | 11279 | Values: 117.0 117.0 117.0 116.0 117.0 116.0 117.0 116.0 117.0 117.0 06:35:36 INFO - PROCESS | 11279 | 06:35:36 INFO - PROCESS | 11279 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:147.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 06:35:36 INFO - PROCESS | 11279 | Values: 147.0 147.0 146.0 147.0 146.0 147.0 147.0 146.0 146.0 146.0 06:35:36 INFO - PROCESS | 11279 | 06:35:36 INFO - PROCESS | 11279 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.33 06:35:36 INFO - PROCESS | 11279 | Values: 94.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 06:35:36 INFO - PROCESS | 11279 | 06:35:36 INFO - PROCESS | 11279 | [#8] json-parse-financial Cycles:10 Average:64.60 Median:64.50 stddev:1.17 (1.8%) stddev-sans-first:1.22 06:35:36 INFO - PROCESS | 11279 | Values: 64.0 63.0 65.0 64.0 65.0 67.0 64.0 64.0 66.0 64.0 06:35:36 INFO - PROCESS | 11279 | 06:35:36 INFO - PROCESS | 11279 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:66.00 stddev:0.63 (1.0%) stddev-sans-first:0.60 06:35:36 INFO - PROCESS | 11279 | Values: 65.0 65.0 65.0 66.0 67.0 66.0 66.0 66.0 66.0 66.0 06:35:36 INFO - PROCESS | 11279 | 06:35:36 INFO - PROCESS | 11279 | [#10] stanford-crypto-aes Cycles:10 Average:83.90 Median:82.00 stddev:3.48 (4.2%) stddev-sans-first:3.62 06:35:36 INFO - PROCESS | 11279 | Values: 82.0 82.0 88.0 85.0 82.0 82.0 92.0 82.0 82.0 82.0 06:35:36 INFO - PROCESS | 11279 | 06:35:36 INFO - PROCESS | 11279 | [#11] stanford-crypto-ccm Cycles:10 Average:134.00 Median:133.50 stddev:5.50 (4.1%) stddev-sans-first:5.78 06:35:36 INFO - PROCESS | 11279 | Values: 132.0 127.0 132.0 131.0 130.0 140.0 130.0 135.0 145.0 138.0 06:35:36 INFO - PROCESS | 11279 | 06:35:36 INFO - PROCESS | 11279 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.70 Median:172.00 stddev:2.79 (1.6%) stddev-sans-first:2.78 06:35:36 INFO - PROCESS | 11279 | Values: 169.0 169.0 171.0 173.0 169.0 170.0 175.0 175.0 170.0 176.0 06:35:36 INFO - PROCESS | 11279 | 06:35:36 INFO - PROCESS | 11279 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:67.00 stddev:1.35 (2.0%) stddev-sans-first:1.42 06:35:36 INFO - PROCESS | 11279 | Values: 67.0 67.0 68.0 67.0 67.0 67.0 67.0 67.0 71.0 66.0 06:35:36 INFO - PROCESS | 11279 | -------- Summary: end -------- 06:35:36 INFO - PROCESS | 11279 | 06:35:37 INFO - TEST-INFO | 11279: exit 0 06:35:37 INFO - TEST-OK | kraken | took 65001ms 06:35:37 INFO - SUITE-END | took 1186s 06:35:37 INFO - Completed test suite (00:19:46) 06:35:37 INFO - javascript benchmark 06:35:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2579.2133399313207, 2601.661783968084, 2612.947741045179, 2630.631711560382, 2633.3943193065593, 4614.746188959606, 4709.458412282588, 4732.4321081470225, 4793.424789025317, 4795.784189711462, 4637.628989680826, 4688.367070445497, 4718.9292160617615, 4739.620830333573, 4747.572718455338, 4304.225239456912, 4331.393604895317, 4335.176316499871, 4344.283193273106, 4380.934285985712, 4381.07997320563, 4392.912141757163, 4396.714213576123, 4404.647628189737, 4449.866504004885, 4266.1467706458525, 4320.308750599889, 4361.389405483234, 4374.431323927901, 4432.024954510002, 4282.314829627245, 4344.391785150082, 4354.0, 4377.7592232427205, 4422.0, 4318.1363727254675, 4339.0, 4351.651867850596, 4380.60574548289, 4392.253316936129, 4551.544845515455, 4702.27114797207, 4714.316424118484, 4743.7628118594275, 4764.118638051971, 4975.651704380694, 5051.722155281458, 5075.28945947564, 5153.201253805666, 5158.071547121516, 5179.637425380225, 5194.039102766001, 5197.948020519784, 5203.19350500673, 5206.427292997767, 4814.229723244299, 4815.735134567562, 4823.107725070873, 4823.3729615150505, 4831.275308703723, 5020.046191223656, 5134.614903882224, 5162.664426812245, 5184.066867963803, 5294.788208471695, 4989.0, 5019.899602007939, 5027.421846487628, 5034.11902916988, 5036.395632524121, 5089.134847076006, 5091.643584949018, 5128.410232823198, 5136.691798492103, 5138.07514647367, 4904.975475122602, 4928.211486162197, 5036.7733451994745, 5039.546440820343, 5048.066107770074, 4932.383452068491, 4984.2025275955675, 5002.924956125661, 5028.497150284942, 5051.4695956924, 4309.245881970643, 4339.175556644227, 4345.174416860849, 4346.217680817484, 4385.978070109692, 3727.5154229949935, 3758.530183727034, 3766.8116594170183, 3780.281746468162, 3810.0855794609647, 4267.0825772459075, 4281.486221653421, 4323.63249123828, 4348.869533913988, 4380.0, 5050.343455350854, 5080.822171224063, 5087.8982420351385, 5094.847154585368, 5103.030424219461, 4342.804573794124, 4344.087741574242, 4350.73895566267, 4352.608265256142, 4390.363397307373, 4446.5553444655925, 4533.818647254139, 4547.022390186125, 4608.078384323148, 4656.3946686930485, 4753.738544380092, 4783.976080119577, 4788.353572267699, 4789.0, 4835.2505361669, 5073.16292811682, 5077.466865979167, 5082.161443361952, 5089.185730283137, 5124.026434977342, 4632.675712700078, 4736.100140973342, 4768.976155119202, 4794.59245964083, 4856.344393506832, 4999.825006124768, 5008.84973450797, 5028.84913452597, 5031.371078615173, 5055.5955523557295, 5063.392392912874, 5068.391792984866, 5086.465921078234, 5100.846974590768, 5114.769835357492, 4795.544423279783, 4894.070126675921, 4920.6555541111775, 4939.110960027229, 4981.62637802159, 4948.15881300187, 4954.950450495449, 4959.330490383896, 4959.603231741524, 5005.099082165245, 4711.363965864564, 4828.637852161031, 4838.8790280241865, 4880.41435027799, 4883.902321953612, 4921.04039712267, 4922.92615610759, 4974.403071631283, 4977.377827771528, 5028.094942910311, 4891.119598472309, 4900.191468407673, 4901.950980490292, 4903.53416425439, 4944.1100601892], "value": 4655.164437421681, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.4408951272508, 574.6781802190773, 583.3379114704809, 586.5395664403443, 590.0352922970941, 29496.557551636717, 39705.80147099264, 40651.0, 40838.38742418866, 44440.11119777602, 41244.793776031074, 41643.37534936978, 41657.58342416575, 41675.791621041855, 41724.791376043075, 7594.658240379169, 7728.806779830521, 7735.033120859893, 7736.9613151934445, 7737.535747855119, 7643.503172293811, 7743.032120984877, 7745.109312429058, 7745.148033716287, 7746.0, 7743.18696536864, 7783.883241751379, 7786.0, 7789.221077892222, 7845.764627061198, 7686.923130768705, 7696.9615151924445, 7701.845963080763, 7702.46082774206, 7703.345215656648, 7461.552306861606, 7541.0, 7541.660625271821, 7544.812004518683, 7545.69817207311, 39171.804140979395, 39507.0, 39525.60474395248, 39544.80227598843, 39548.0, 3023.909282721522, 3049.2681756378533, 3066.8313242771637, 3143.921401964946, 3270.787398819093, 29339.85330073357, 29652.0, 29674.0, 29773.40453190924, 29950.0, 2919.5182794838825, 2921.3134913295357, 2922.8976985805607, 2934.501134807088, 2936.9853150734325, 251852.0, 252628.0, 252965.99999999817, 253032.0, 253146.0, 52157.0, 52482.0, 52500.21249681258, 52525.474745252825, 52575.0, 32727.345453090806, 33229.83385083059, 33244.1688957778, 35251.64748352535, 35289.8235508826, 14585.124892506483, 14789.408423662933, 14798.334074966653, 14800.259987000822, 14810.703785924437, 10222.591096356062, 10245.795084098276, 10246.129079028216, 10311.432871192157, 10344.37933723979, 18192.633767895873, 18216.362427315245, 18282.634347313244, 18344.357947471774, 18377.356792512197, 6599.505037122235, 6747.898781518183, 6875.759348422881, 7012.5792452453015, 7017.789466316019, 722.4148439763808, 751.7406494759299, 871.7428358634218, 994.6667866264737, 1020.9540570674337, 25985.740142598333, 27911.441771164464, 28160.71839281581, 28399.71600284012, 28501.857490712828, 812.5571563497908, 916.9541522923827, 929.9535023248945, 952.9279560494443, 955.073578628729, 13628.0, 14695.559133226021, 14704.558863233906, 15101.773473397909, 15107.4712385066, 413367.0, 416109.0, 416592.0, 416891.0, 419972.3009694646, 50963.0, 51124.74437627788, 51241.487585124414, 51498.0, 51557.0, 1045.2369770067742, 1058.6612284069179, 1116.0234794554765, 1117.1342209787479, 1128.1087940527057, 26316.57884475006, 28933.85533072321, 29287.26781830386, 29568.408631826813, 30149.0, 46880.29679554878, 48192.0, 48204.758976204896, 52208.47791522036, 52401.7379913098, 360102.0, 360861.0, 363095.0, 363687.0, 365127.0, 24578.754212458363, 25552.74447255578, 25663.615045774703, 26808.8659556701, 27076.1877143686, 42073.78963105165, 42475.787621061696, 42845.78577107094, 42914.57085429231, 43085.35371969486, 35056.0, 35059.47410788891, 35060.824695877374, 35362.29275414529, 35544.644553554135, 35348.116297092776, 35619.82190089033, 35713.821430892676, 36002.81998590095, 36100.63899361078, 34820.47769283412, 35287.82356088203, 35715.0, 35882.8205858969, 36170.45744313785, 30485.23786905345, 32533.511997320533, 33078.50382244316, 33177.66822331746, 33322.0, 24582.87708561446, 24765.62851557192, 25018.999240030196, 25222.873885630455, 25712.228633141036], "value": 17296.721106334033, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.9727964745587, 358.6664402106042, 364.64811456944045, 366.67915573872864, 379.5142217961011, 12484.375781210938, 16190.757138642915, 16580.088095154762, 16964.83035169648, 18176.818231817677, 16244.269007894643, 16295.103769292684, 16321.918390408062, 16434.260458279376, 16728.330866765315, 4998.125328067583, 5132.717700526469, 5140.408852981899, 5168.948310516903, 5182.740862956856, 3723.8882833514904, 3892.2799282132896, 4921.827736029221, 5110.386753589574, 5111.463296353885, 3929.5873933237026, 3934.4295077213787, 5477.534409575191, 5827.446392592697, 5859.912101318462, 5719.313682358102, 5922.733476993524, 5929.14027466015, 5930.614510056833, 5934.287885453773, 5257.658252213632, 5259.105951988133, 5264.578833693296, 5266.7366631668265, 5266.8419947401635, 20520.79479205204, 21144.788552114434, 21146.048427820784, 21232.78767212339, 21329.68005479919, 1811.1578116176042, 1855.4990152658706, 1861.1810803246528, 1910.8471322294183, 2322.2917010312017, 12747.61757147287, 13314.533991310258, 13415.262160581164, 13557.661058473519, 13867.0, 1695.033830716492, 1698.0, 1748.9304389161746, 1753.6668033073674, 1802.0426450421612, 68934.31065689329, 70720.2927970724, 70797.64601176961, 71900.0, 72072.0, 9467.52662366879, 9554.665586704432, 9707.6116955321, 9781.266405019653, 9999.35004224723, 13031.804522932163, 13032.283224422557, 13058.738825223443, 13062.216267024009, 13109.082364234411, 7281.417486601059, 7433.962830185815, 7580.507267027626, 7627.5042122261875, 7663.923360766433, 7280.745173918887, 7333.523320984119, 7344.045274114438, 7520.887186692204, 7550.735724249625, 5991.970040149771, 5993.190919225936, 6092.51259899207, 6128.724207410677, 6254.093156492374, 1619.1175809184024, 1703.7955445346638, 1720.9225584848712, 1770.433461292374, 1771.4154329071375, 818.4352796570347, 885.4819930340823, 895.5701263393478, 927.2720914082413, 961.6874515782399, 16553.10581491062, 16705.83294167067, 17645.117744112744, 17691.38080167188, 17947.910260448614, 796.2077732656045, 800.4836880212231, 812.813052997802, 814.621201141472, 820.8686610142468, 9673.177779888789, 9720.562574684156, 9932.255080868963, 10087.243456740627, 10150.593976240867, 362213.0, 378366.0, 378735.0, 380357.0, 381952.0, 70773.29226707667, 71715.64142179256, 72069.0, 72894.9065764014, 82163.5891820537, 934.2572654739498, 951.048951048951, 957.8850537935492, 957.9952100239454, 958.5446912716543, 15675.608109797346, 15920.283587238371, 16254.43109491162, 16267.3493060281, 16630.417935372687, 35602.46596301109, 35958.46062309015, 36326.818365908, 39567.40648890211, 39694.404583930685, 47406.0, 50446.74776626093, 50686.0, 51314.48685513097, 51917.76986761613, 6505.60966342002, 6575.408213260829, 6645.966770166118, 6809.965950170217, 7595.3164215220895, 7364.889526657212, 8819.735407937773, 11772.764544709229, 12028.578999734968, 12636.747265054832, 9812.754681133028, 10024.348417352849, 10498.737531561772, 10548.894511054792, 10801.945990269998, 4743.952560474351, 9838.193474233012, 10235.7441063974, 10387.68836934893, 11001.834972475259, 9768.462734549668, 10040.347377420445, 10671.519781609692, 10686.73283167927, 10753.46232688378, 6269.937300626935, 6560.967195164185, 6712.261651218264, 6857.759978400732, 6958.965205174144, 7696.307332340116, 9488.95255523718, 9766.902330976884, 9805.803883922425, 10012.799744004933], "value": 8197.010676726968, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1243.365883399466, 1332.3271747767378, 1392.5056604905258, 1414.7807089901069, 1600.9519714408564, 6394.488440924726, 7415.740449084283, 7693.653785579649, 7700.114486834016, 7851.842963140733, 7694.807629809257, 7752.0, 7758.0, 7758.0, 7759.0, 2129.5847309774563, 2146.624340740372, 2155.1594877997595, 2156.5471251037225, 2159.384575396012, 2129.1802655977403, 2170.4248374180856, 2177.5318306564163, 2178.6078505868936, 2184.563087382522, 2140.4969832089446, 2163.945901352471, 2167.6315026445463, 2174.369432864475, 2175.8912054397297, 2096.6540520814046, 2097.2974053692033, 2098.8635738677012, 2100.1389430333643, 2103.9474013149643, 1997.7902320256455, 2085.165933626546, 2087.832973362127, 2089.185217765073, 2094.49732064305, 7293.124825021031, 7743.148253692089, 7834.33408160307, 7843.372530197627, 7848.7645370638975, 4283.0, 4480.171168333836, 4500.774961251925, 4515.548445155491, 4517.322401639747, 2843.8720257588457, 2926.8536573171255, 2972.271793410628, 2978.091682036978, 2986.970130298691, 969.6412327438823, 977.6480467031863, 983.0759086458777, 994.5027486256871, 998.7403275148441, 10278.434686092261, 10341.224408169417, 10350.482475876175, 10384.844227336596, 10402.271840971134, 3844.865764599394, 3856.768593884376, 3892.065904182976, 3908.9609103909165, 3926.54844692864, 7722.613869306512, 7755.2632499912415, 7773.261540153676, 7778.299953004257, 7779.066512018594, 4273.829046838154, 4275.0, 4275.657947364203, 4278.593533614302, 4281.593248641374, 3561.554805649294, 3821.1593449441634, 3942.250972315251, 3950.328444164499, 3996.600339966038, 3380.7464440167087, 3388.2207092368894, 3521.7710848794745, 3522.559680039995, 3537.7877327360443, 1026.3995562595817, 1030.5414090729553, 1036.2435422141878, 1052.9052385285208, 1059.2743970380313, 699.2937133495207, 719.9280071992864, 725.0863911471583, 738.2174894611729, 766.0424469413233, 4809.038192361541, 4884.0476107159475, 4967.130752118364, 4984.27727979441, 5117.462666419972, 398.954120276172, 423.44105780369614, 426.70770522192396, 426.90608066226, 436.0885748784991, 3335.4163021471145, 3338.21551935295, 3351.0, 3419.863205471803, 3457.291255292659, 9216.354855160209, 9558.90441095594, 9609.375390599589, 9611.903880961241, 9651.565679544437, 9509.239260859253, 9514.714558563253, 9528.237740980705, 9545.713628591153, 9558.139767420966, 7165.0, 7221.386182174574, 7249.601271929989, 7298.671559779823, 7320.670569824264, 4790.161721698759, 4941.0, 4967.925481117858, 4977.303177555073, 5002.974985125051, 8848.778780530538, 8860.202581767671, 8873.689420870238, 8885.644574216958, 8956.865647015169, 8819.250363719155, 9094.499407597672, 9232.861507077534, 9279.118483744034, 9288.442693438416, 5747.19539264495, 6149.446549810538, 6405.583637063576, 6470.805875823733, 6486.5459417842185, 7350.558966462029, 7460.29127232912, 7512.924870751441, 7523.1348394935285, 7530.246975302426, 6358.77744278948, 6404.327545607644, 6467.514936379696, 6588.110605068255, 6662.933370666426, 6200.7519699211525, 6217.440430361289, 6377.298497165215, 6482.222133344029, 6528.9020664689115, 5853.209816674865, 5999.400059993965, 6002.369751176064, 6006.669633170218, 6009.06859436788, 4342.0, 4415.712978656377, 4436.179306828247, 4517.909641807079, 4587.311903214544, 4376.671749618855, 4377.321515165155, 4457.910841783211, 4567.908641827211, 4627.1439783640135], "value": 3908.6269001067444, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.5357768669719, 300.7503771869349, 304.3076999825397, 309.4182936080169, 309.4569031349981, 4870.780814863331, 4874.268859671051, 4893.849945262865, 4909.361782968218, 4926.876828079296, 4828.444728856174, 4829.323894654743, 4830.613550915927, 4831.613470922327, 4850.442199147099, 3631.509746184258, 3745.981270093646, 3750.4374343848367, 3752.699784017272, 3759.3233218020746, 3676.1177317443753, 3754.9436758448646, 3784.432335149722, 3849.1724279279947, 3874.9418758718643, 3797.411401232813, 3806.5622453417936, 3809.314323421783, 3810.6760925321382, 3814.4278358246206, 3541.8052007139595, 3738.149615940127, 3789.6399842014966, 3796.563395209545, 3815.923681526354, 3250.780957141072, 3265.6407795142513, 3268.1176082457605, 3278.852451639682, 3280.9671903281137, 4700.1539722849875, 4702.260163739438, 4707.340972263886, 4711.623070154414, 4715.528447155291, 4252.4046633471335, 4284.892877678052, 4292.0, 4296.5273819879785, 4301.354796780477, 4945.233488809205, 5008.974955125237, 5054.646174767768, 5082.491750824925, 5102.974485127587, 3598.229363390175, 3601.7658852174527, 3603.423452247654, 3605.5938184108218, 3609.4946707460977, 5312.335958005249, 5335.692755274982, 5340.504658695532, 5342.5993050521365, 5344.812931547416, 3477.2697733476252, 3651.0, 3659.8719044833833, 3711.4247291669835, 3714.551324983259, 3700.1119731264303, 3701.7408781385043, 3706.0, 3707.666310032056, 3723.2925744108534, 4223.746575205498, 4231.851885184004, 4252.213340532012, 4265.6563182598, 4266.442748396875, 4923.335349727812, 5087.440381558025, 5098.668586541863, 5133.383993920754, 5147.562457191106, 1283.640580637423, 1296.4036543190239, 1304.9021323400782, 1305.830242068544, 1308.2019967819622, 781.5193655901693, 815.388458656008, 822.5681517203516, 827.3174630929507, 827.9172082791673, 4286.949697324176, 4360.978195109067, 4421.695599798068, 4480.14877173336, 4494.011317510142, 5222.92165617516, 5245.0, 5250.422453530108, 5258.421573626898, 5288.233206185082, 4468.173387923244, 4477.731336119843, 4493.483249426358, 4497.123061003138, 4505.864824055284, 2658.322127857423, 2747.3818390861898, 2766.349907771672, 2773.972260277412, 2782.2627003844, 4908.435529914105, 4920.8769780755765, 4929.359183306147, 4931.4821943696115, 4953.23224900141, 5195.19474481456, 5343.572514198855, 5348.91976620351, 5362.249285100089, 5376.301080859464, 4849.830255941025, 4911.877203069952, 4925.448483727614, 4942.604591632589, 4943.134951383494, 5215.608829337891, 5216.817411390583, 5219.243209734643, 5229.581633469389, 5239.502247286502, 5000.974995125147, 5060.58305646204, 5104.1067813133295, 5105.744712764421, 5126.2054381570915, 4902.436219834764, 4913.508649135058, 4932.802687892444, 4954.281629163823, 4963.478834722447, 3774.094217387862, 3793.1844653399653, 3806.2577797329254, 3809.828557714965, 3812.904677383088, 4896.804127834989, 5138.3577052868395, 5154.407243167084, 5170.974145129401, 5193.636445448933, 4963.205887058053, 5000.199968005102, 5001.324821149098, 5025.648204625787, 5031.874203144951, 5039.672421292605, 5041.596672266283, 5042.974785126051, 5046.0, 5051.520105589963, 4712.316714076371, 4922.335484709519, 4943.728094954812, 4946.282788995503, 4947.678400903929, 4936.9259461107395, 5063.620228482952, 5126.179811230185, 5151.5106064923775, 5184.870378240575, 4882.60939124862, 4950.579200767975, 4958.677685950402, 4961.479044700254, 5053.29253904461], "value": 3812.2496851415985, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [572.5791543215736, 604.8085272014137, 607.9300880398755, 611.4222060153155, 616.9049936362956, 12986.54547090852, 13885.0, 14001.299935003248, 14022.158670479754, 14023.649408764786, 13070.215787052783, 13278.402471888765, 13715.725685486284, 13936.372863221153, 14410.639734006654, 625.7121724006962, 748.4386709967524, 760.3650951455528, 777.2848978939361, 779.2168870285384, 759.0246533204833, 779.6920216514474, 780.8945792318021, 782.0, 782.178712352031, 820.1347578304886, 821.1706176761484, 822.0874828939872, 822.1244374740912, 822.3873214455239, 652.5595223224352, 676.1041619853664, 819.4223072733708, 819.6147810529042, 819.737683941139, 496.54566072044037, 497.16228155558, 568.5252813900398, 583.5739909865779, 590.5128269177945, 12599.685007874785, 12616.369181540886, 12643.873561264454, 12649.494020239179, 12710.110292279544, 11314.943425282821, 12010.699732506757, 12059.698507537381, 12098.758024839542, 12372.072094592851, 10074.496275186284, 10254.53854576546, 11122.666320010412, 11222.0, 11332.49003794823, 3134.45147099259, 3222.1622378181605, 3342.5988881334397, 3469.0, 3473.11435583927, 53206.20190697143, 53650.0, 54183.18725219125, 61359.38640613581, 61536.69231653858, 9830.803383932282, 10466.058054775107, 10542.367457952547, 10632.46837658114, 10899.891001089887, 20215.595688086156, 20314.79685203129, 20414.38756837297, 20546.486337841674, 20607.690884636744, 6528.183977002875, 6641.302663220389, 6678.866422671616, 6798.8300292492095, 7048.224695283515, 3640.581333146669, 3651.8721844735305, 3653.397189463709, 3671.52270204877, 3687.078230442389, 2876.669183043977, 2951.4687356276077, 2959.9704002959693, 2973.8067025643263, 2977.2556860784803, 497.32363984980395, 498.4100718707312, 510.2601228219097, 511.4348644747575, 517.8265281130788, 416.61046921128843, 499.4206720204545, 507.8222622082242, 517.3145582103652, 518.8650950752756, 7416.851662966818, 7463.888041679379, 7703.460757747016, 7746.883796743054, 8010.71962481322, 477.11733293406917, 488.16280079663545, 489.9142650036229, 491.55268705477846, 501.7691861743639, 3439.94840077399, 3469.115375579211, 3475.5308033415167, 3487.5640544931885, 3551.6448355164794, 96840.0, 99902.50048749709, 103949.48025259825, 106530.0, 107781.0, 87322.0, 90317.54841225884, 92782.53608732048, 95409.0, 95550.0444995555, 72121.0, 79598.60200698959, 80064.99999999884, 80416.59791701005, 81166.18833811705, 6635.834104147435, 6648.7340506379205, 7117.252688467636, 7157.427405807419, 7212.098487689039, 45528.772356138004, 46050.30924536067, 46991.530084698716, 47434.288485672056, 48068.5193148078, 67001.0, 70609.64695176491, 70652.646736766, 71044.28955710377, 72416.27583724096, 9371.390859594105, 10118.494075296352, 10192.541335640062, 10219.897801022193, 10301.536430860479, 10709.0, 10752.354858708502, 10863.066971917697, 11288.0, 11294.04000659953, 9475.0, 9526.714198574055, 9620.182284505896, 10197.337173083726, 10206.285560010729, 9355.391899526508, 9538.761530961781, 9636.855447168438, 9952.203823694232, 9953.751156221153, 9633.207802480414, 9895.703128906145, 10032.799344012932, 10150.0, 10283.43441110746, 8138.87791683137, 8318.62566184511, 8354.582270886553, 8604.182602652738, 8870.068642792414, 7485.812854678677, 7791.610419479117, 8010.559419231999, 8105.513669179868, 8700.999385070798], "value": 6242.208092671592, "unit": "score"}], "name": "dromaeo_css", "value": 6280.943238427874, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 116.0, 113.0, 115.0, 109.0, 114.0, 115.0, 114.0, 114.0, 115.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 125.0, 130.0, 200.0, 126.0, 127.0, 152.0, 123.0, 180.0, 129.0], "value": 145.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 160.0, 160.0, 161.0, 161.0, 162.0, 162.0, 162.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 77.0, 110.0, 76.0, 78.0, 80.0, 75.0, 104.0, 75.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 127.0, 87.0, 86.0, 86.0, 115.0, 86.0, 86.0, 127.0], "value": 97.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 147.0, 146.0, 147.0, 147.0, 146.0, 146.0, 146.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 65.0, 64.0, 65.0, 67.0, 64.0, 64.0, 66.0, 64.0], "value": 64.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 66.0, 67.0, 66.0, 66.0, 66.0, 66.0, 66.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 88.0, 85.0, 82.0, 82.0, 92.0, 82.0, 82.0, 82.0], "value": 83.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 127.0, 132.0, 131.0, 130.0, 140.0, 130.0, 135.0, 145.0, 138.0], "value": 134.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 169.0, 171.0, 173.0, 169.0, 170.0, 175.0, 175.0, 170.0, 176.0], "value": 171.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 67.0, 67.0, 67.0, 67.0, 67.0, 71.0, 66.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1545.5000000000002, "alertThreshold": 2.0}]} 06:35:37 INFO - Return code: 0 06:35:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:35:37 INFO - # TBPL SUCCESS # 06:35:37 INFO - Running post-action listener: _resource_record_post_action 06:35:37 INFO - [mozharness: 2016-08-18 13:35:37.893982Z] Finished run-tests step (success) 06:35:37 INFO - Running post-run listener: _resource_record_post_run 06:35:37 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 49152; Write bytes: 405479424; Read time: 416; Write time: 552656 06:35:37 INFO - TinderboxPrint: CPU usage
13.0% 06:35:37 INFO - TinderboxPrint: I/O read bytes / time
49,152 / 416 06:35:37 INFO - TinderboxPrint: I/O write bytes / time
405,479,424 / 552,656 06:35:37 INFO - TinderboxPrint: CPU idle
8,353.6 (87.0%) 06:35:37 INFO - TinderboxPrint: CPU user
1,198.7 (12.5%) 06:35:37 INFO - TinderboxPrint: Swap in / out
0 / 0 06:35:37 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 113737728; Read time: 0; Write time: 184516 06:35:37 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 45056; Write bytes: 289406976; Read time: 392; Write time: 367944 06:35:38 INFO - Running post-run listener: _upload_blobber_files 06:35:38 INFO - Blob upload gear active. 06:35:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:35:38 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 06:35:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:35:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:35:38 INFO - (blobuploader) - INFO - Open directory for files ... 06:35:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:35:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:35:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:35:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:35:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:35:43 INFO - (blobuploader) - INFO - Done attempting. 06:35:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:35:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:35:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:35:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:35:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:35:44 INFO - (blobuploader) - INFO - Done attempting. 06:35:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:35:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:35:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:35:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:35:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:35:45 INFO - (blobuploader) - INFO - Done attempting. 06:35:45 INFO - (blobuploader) - INFO - Iteration through files over. 06:35:45 INFO - Return code: 0 06:35:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:35:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:35:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c1ce0e21a438df623d1af491ab1c5de137758e1b8651f954072a6201b29a4881c71ac5ed5d24b7d51ad25915738cd0445e8cdf20744bfa1e872835d28fd22bbc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e28d4f6217cb3ea277c4d7b0fd5f6c51f91f3c03592598871d7afa7b472d0764bcc96e52ba88691713d2d3d03ef670df281e062176ee5f90465c963a0b939296", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/52b84761874d5059c9e917a1f2d01a574765c9005374fcb50f5d97282e57f777a52250af82216c1d2d94ed8975aebe5c42b5c005fa0c5b0df315de43c07fe6e0"} 06:35:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:35:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:35:45 INFO - Contents: 06:35:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c1ce0e21a438df623d1af491ab1c5de137758e1b8651f954072a6201b29a4881c71ac5ed5d24b7d51ad25915738cd0445e8cdf20744bfa1e872835d28fd22bbc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e28d4f6217cb3ea277c4d7b0fd5f6c51f91f3c03592598871d7afa7b472d0764bcc96e52ba88691713d2d3d03ef670df281e062176ee5f90465c963a0b939296", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/52b84761874d5059c9e917a1f2d01a574765c9005374fcb50f5d97282e57f777a52250af82216c1d2d94ed8975aebe5c42b5c005fa0c5b0df315de43c07fe6e0"} 06:35:45 INFO - Running post-run listener: copy_logs_to_upload_dir 06:35:45 INFO - Copying logs to upload dir... 06:35:45 INFO - mkdir: /builds/slave/test/build/upload/logs 06:35:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.880090 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-08-18 06:35:45.365961) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 06:35:45.369081) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1471526094.107669-871665906 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c1ce0e21a438df623d1af491ab1c5de137758e1b8651f954072a6201b29a4881c71ac5ed5d24b7d51ad25915738cd0445e8cdf20744bfa1e872835d28fd22bbc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e28d4f6217cb3ea277c4d7b0fd5f6c51f91f3c03592598871d7afa7b472d0764bcc96e52ba88691713d2d3d03ef670df281e062176ee5f90465c963a0b939296", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/52b84761874d5059c9e917a1f2d01a574765c9005374fcb50f5d97282e57f777a52250af82216c1d2d94ed8975aebe5c42b5c005fa0c5b0df315de43c07fe6e0"} build_url:https://queue.taskcluster.net/v1/task/Xu9dstL-T5WrJDQdXmUGBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011378 build_url: 'https://queue.taskcluster.net/v1/task/Xu9dstL-T5WrJDQdXmUGBw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c1ce0e21a438df623d1af491ab1c5de137758e1b8651f954072a6201b29a4881c71ac5ed5d24b7d51ad25915738cd0445e8cdf20744bfa1e872835d28fd22bbc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e28d4f6217cb3ea277c4d7b0fd5f6c51f91f3c03592598871d7afa7b472d0764bcc96e52ba88691713d2d3d03ef670df281e062176ee5f90465c963a0b939296", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/52b84761874d5059c9e917a1f2d01a574765c9005374fcb50f5d97282e57f777a52250af82216c1d2d94ed8975aebe5c42b5c005fa0c5b0df315de43c07fe6e0"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 06:35:45.394046) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 06:35:45.394345) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1471526094.107669-871665906 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004485 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 06:35:45.479444) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 06:35:45.479773) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 06:35:45.480062) ========= ========= Total master_lag: 0.18 =========