builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-015 starttime: 1474993077.25 results: success (0) buildid: 20160927063018 builduid: 79a84203a1b34df09167cc1894e1c7f0 revision: 7c1929f35c5d11b0b921ebd03c197bdb5afbeaca ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-27 09:17:57.253027) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-27 09:17:57.253819) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-27 09:17:57.254136) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-27 09:17:57.305756) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 09:17:57.306060) ========= 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=20e666a3d4cfbb71505a330e000001b4-1474993076.426389-568394526 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-27 09:17:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 70.4M=0s 2016-09-27 09:17:57 (70.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.083709 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 09:17:57.408980) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 09:17:57.409335) ========= 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=20e666a3d4cfbb71505a330e000001b4-1474993076.426389-568394526 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022473 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 09:17:57.459931) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 09:17:57.460357) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 7c1929f35c5d11b0b921ebd03c197bdb5afbeaca --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 7c1929f35c5d11b0b921ebd03c197bdb5afbeaca --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=20e666a3d4cfbb71505a330e000001b4-1474993076.426389-568394526 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-27 09:17:57,697 truncating revision to first 12 chars 2016-09-27 09:17:57,697 Setting DEBUG logging. 2016-09-27 09:17:57,697 attempt 1/10 2016-09-27 09:17:57,697 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/7c1929f35c5d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-27 09:17:57,996 unpacking tar archive at: fx-team-7c1929f35c5d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.643181 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 09:17:58.118676) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-27 09:17:58.119060) ========= script_repo_revision: 7c1929f35c5d11b0b921ebd03c197bdb5afbeaca ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-27 09:17:58.119544) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-27 09:17:58.119864) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-27 09:17:58.130771) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-09-27 09:17:58.131132) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=20e666a3d4cfbb71505a330e000001b4-1474993076.426389-568394526 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:17:58 INFO - MultiFileLogger online at 20160927 09:17:58 in /builds/slave/test 09:17:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:17:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:17:58 INFO - {'append_to_log': False, 09:17:58 INFO - 'base_work_dir': '/builds/slave/test', 09:17:58 INFO - 'blob_upload_branch': 'Fx-Team', 09:17:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:17:58 INFO - 'branch': 'Fx-Team', 09:17:58 INFO - 'buildbot_json_path': 'buildprops.json', 09:17:58 INFO - 'config_files': ('talos/linux_config.py',), 09:17:58 INFO - 'default_actions': ('clobber', 09:17:58 INFO - 'read-buildbot-config', 09:17:58 INFO - 'download-and-extract', 09:17:58 INFO - 'populate-webroot', 09:17:58 INFO - 'create-virtualenv', 09:17:58 INFO - 'install', 09:17:58 INFO - 'run-tests'), 09:17:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:17:58 INFO - 'download_minidump_stackwalk': True, 09:17:58 INFO - 'download_symbols': 'ondemand', 09:17:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:17:58 INFO - 'tooltool.py': '/tools/tooltool.py', 09:17:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:17:58 INFO - '/tools/misc-python/virtualenv.py')}, 09:17:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:17:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:17:58 INFO - 'installer_path': 'installer.exe', 09:17:58 INFO - 'log_level': 'info', 09:17:58 INFO - 'log_name': 'talos', 09:17:58 INFO - 'log_to_console': True, 09:17:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:17:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:17:58 INFO - 'opt_config_files': (), 09:17:58 INFO - 'pip_index': False, 09:17:58 INFO - 'sps_profile': False, 09:17:58 INFO - 'sps_profile_interval': 0, 09:17:58 INFO - 'suite': 'dromaeojs', 09:17:58 INFO - 'system_bits': '32', 09:17:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:17:58 INFO - 'title': 'talos-linux64-ix-015', 09:17:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:17:58 INFO - 'use_talos_json': True, 09:17:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:17:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:17:58 INFO - 'work_dir': 'build'} 09:17:58 INFO - [mozharness: 2016-09-27 16:17:58.461037Z] Running clobber step. 09:17:58 INFO - Running pre-action listener: _resource_record_pre_action 09:17:58 INFO - Running main action method: clobber 09:17:58 INFO - rmtree: /builds/slave/test/build 09:17:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:17:59 INFO - Running post-action listener: _resource_record_post_action 09:17:59 INFO - [mozharness: 2016-09-27 16:17:59.437779Z] Finished clobber step (success) 09:17:59 INFO - [mozharness: 2016-09-27 16:17:59.437880Z] Running read-buildbot-config step. 09:17:59 INFO - Running pre-action listener: _resource_record_pre_action 09:17:59 INFO - Running main action method: read_buildbot_config 09:17:59 INFO - Using buildbot properties: 09:17:59 INFO - { 09:17:59 INFO - "project": "", 09:17:59 INFO - "product": "firefox", 09:17:59 INFO - "script_repo_revision": "production", 09:17:59 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 09:17:59 INFO - "repository": "", 09:17:59 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 09:17:59 INFO - "buildid": "20160927063018", 09:17:59 INFO - "pgo_build": "True", 09:17:59 INFO - "basedir": "/builds/slave/test", 09:17:59 INFO - "buildnumber": 481, 09:17:59 INFO - "slavename": "talos-linux64-ix-015", 09:17:59 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:17:59 INFO - "platform": "ubuntu64_hw", 09:17:59 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 09:17:59 INFO - "branch": "fx-team", 09:17:59 INFO - "repo_path": "integration/fx-team", 09:17:59 INFO - "revision": "7c1929f35c5d11b0b921ebd03c197bdb5afbeaca", 09:17:59 INFO - "stage_platform": "linux64-pgo", 09:17:59 INFO - "builduid": "79a84203a1b34df09167cc1894e1c7f0", 09:17:59 INFO - "slavebuilddir": "test" 09:17:59 INFO - } 09:17:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/Tz731C6cQJ-unKVkKathsw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 09:17:59 INFO - Running post-action listener: _resource_record_post_action 09:17:59 INFO - [mozharness: 2016-09-27 16:17:59.440301Z] Finished read-buildbot-config step (success) 09:17:59 INFO - [mozharness: 2016-09-27 16:17:59.440390Z] Running download-and-extract step. 09:17:59 INFO - Running pre-action listener: _resource_record_pre_action 09:17:59 INFO - Running main action method: download_and_extract 09:17:59 INFO - mkdir: /builds/slave/test/build/tests 09:17: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')]} 09:17:59 INFO - trying https://queue.taskcluster.net/v1/task/Tz731C6cQJ-unKVkKathsw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 09:17:59 INFO - Downloading https://queue.taskcluster.net/v1/task/Tz731C6cQJ-unKVkKathsw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 09:17:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Tz731C6cQJ-unKVkKathsw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:17:59 INFO - Downloaded 1361 bytes. 09:17:59 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 09:18:00 INFO - Using the following test package requirements: 09:18:00 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 09:18:00 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:18:00 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:18:00 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:18:00 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:18:00 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:18:00 INFO - u'jsshell-linux-x86_64.zip'], 09:18:00 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:18:00 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:18:00 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 09:18:00 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:18:00 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:18:00 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:18:00 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:18:00 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:18:00 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:18:00 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:18:00 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:18:00 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:18:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Tz731C6cQJ-unKVkKathsw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 09:18:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tz731C6cQJ-unKVkKathsw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:18:00 INFO - Fetch https://queue.taskcluster.net/v1/task/Tz731C6cQJ-unKVkKathsw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:18:00 INFO - Expected file size: 21984008 09:18:04 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:18:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Tz731C6cQJ-unKVkKathsw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 09:18:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tz731C6cQJ-unKVkKathsw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:18:04 INFO - Fetch https://queue.taskcluster.net/v1/task/Tz731C6cQJ-unKVkKathsw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:18:05 INFO - Expected file size: 21984008 09:18:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Tz731C6cQJ-unKVkKathsw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 09:18:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Tz731C6cQJ-unKVkKathsw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:18:10 INFO - Fetch https://queue.taskcluster.net/v1/task/Tz731C6cQJ-unKVkKathsw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 09:18:10 INFO - Expected file size: 14414062 09:18:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:11 INFO - trying https://queue.taskcluster.net/v1/task/Tz731C6cQJ-unKVkKathsw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 09:18:11 INFO - Downloading https://queue.taskcluster.net/v1/task/Tz731C6cQJ-unKVkKathsw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 09:18:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Tz731C6cQJ-unKVkKathsw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:18:12 INFO - Downloaded 59725255 bytes. 09:18:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Tz731C6cQJ-unKVkKathsw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 09:18:12 INFO - mkdir: /builds/slave/test/properties 09:18:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:18:12 INFO - Writing to file /builds/slave/test/properties/build_url 09:18:12 INFO - Contents: 09:18:12 INFO - build_url:https://queue.taskcluster.net/v1/task/Tz731C6cQJ-unKVkKathsw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 09:18:13 INFO - Running post-action listener: _resource_record_post_action 09:18:13 INFO - Running post-action listener: set_extra_try_arguments 09:18:13 INFO - [mozharness: 2016-09-27 16:18:13.162417Z] Finished download-and-extract step (success) 09:18:13 INFO - [mozharness: 2016-09-27 16:18:13.162645Z] Running populate-webroot step. 09:18:13 INFO - Running pre-action listener: _resource_record_pre_action 09:18:13 INFO - Running main action method: populate_webroot 09:18:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:18:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:18:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:18:13 INFO - '--tppagecycles', 09:18:13 INFO - '7'], 09:18:13 INFO - 'tests': ['tsvgm']}}, 09:18:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:18:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:18:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:18:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:18:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:18:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:18:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:18:13 INFO - 'talos_options': ['--disable-e10s'], 09:18:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:18:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:18:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:18:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:18:13 INFO - 'talos_options': ['--disable-e10s'], 09:18:13 INFO - 'tests': ['damp', 'tps']}, 09:18:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:18:13 INFO - 'tests': ['damp', 'tps']}, 09:18:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:18:13 INFO - 'tests': ['dromaeo_dom']}, 09:18:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:18:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:18:13 INFO - 'tests': ['basic_compositor_video']}, 09:18:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:18:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:18:13 INFO - 'tests': ['a11yr', 09:18:13 INFO - 'ts_paint', 09:18:13 INFO - 'tpaint', 09:18:13 INFO - 'sessionrestore', 09:18:13 INFO - 'sessionrestore_no_auto_restore', 09:18:13 INFO - 'tabpaint']}, 09:18:13 INFO - 'other-e10s': {'tests': ['a11yr', 09:18:13 INFO - 'ts_paint', 09:18:13 INFO - 'tpaint', 09:18:13 INFO - 'sessionrestore', 09:18:13 INFO - 'sessionrestore_no_auto_restore', 09:18:13 INFO - 'tabpaint']}, 09:18:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:18:13 INFO - 'ts_paint', 09:18:13 INFO - 'tpaint', 09:18:13 INFO - 'sessionrestore', 09:18:13 INFO - 'sessionrestore_no_auto_restore', 09:18:13 INFO - 'tabpaint']}, 09:18:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:18:13 INFO - 'ts_paint', 09:18:13 INFO - 'tpaint', 09:18:13 INFO - 'sessionrestore', 09:18:13 INFO - 'sessionrestore_no_auto_restore', 09:18:13 INFO - 'tabpaint']}, 09:18:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:18:13 INFO - 'tests': ['a11yr', 09:18:13 INFO - 'ts_paint', 09:18:13 INFO - 'tpaint', 09:18:13 INFO - 'sessionrestore', 09:18:13 INFO - 'sessionrestore_no_auto_restore', 09:18:13 INFO - 'tabpaint']}, 09:18:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:18:13 INFO - 'tests': ['a11yr', 09:18:13 INFO - 'ts_paint', 09:18:13 INFO - 'tpaint', 09:18:13 INFO - 'sessionrestore', 09:18:13 INFO - 'sessionrestore_no_auto_restore', 09:18:13 INFO - 'tabpaint']}, 09:18:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:18:13 INFO - 'tests': ['tsvgx', 09:18:13 INFO - 'tsvgr_opacity', 09:18:13 INFO - 'tart', 09:18:13 INFO - 'tscrollx', 09:18:13 INFO - 'cart']}, 09:18:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:18:13 INFO - 'tsvgr_opacity', 09:18:13 INFO - 'tart', 09:18:13 INFO - 'tscrollx', 09:18:13 INFO - 'cart']}, 09:18:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:18:13 INFO - 'talos_options': ['--disable-e10s'], 09:18:13 INFO - 'tests': ['tp5o']}, 09:18:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:18:13 INFO - 'tests': ['tp5o']}, 09:18:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:18:13 INFO - 'talos_options': ['--disable-e10s', 09:18:13 INFO - '--xperf_path', 09:18:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:18:13 INFO - 'tests': ['tp5n']}, 09:18:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:18:13 INFO - 'talos_options': ['--xperf_path', 09:18:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:18:13 INFO - 'tests': ['tp5n']}}, 09:18:13 INFO - 'talos.zip': {'path': '', 09:18:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:18:13 INFO - Running post-action listener: _resource_record_post_action 09:18:13 INFO - [mozharness: 2016-09-27 16:18:13.180026Z] Finished populate-webroot step (success) 09:18:13 INFO - [mozharness: 2016-09-27 16:18:13.180114Z] Running create-virtualenv step. 09:18:13 INFO - Running pre-action listener: _resource_record_pre_action 09:18:13 INFO - Running main action method: create_virtualenv 09:18:13 INFO - Creating virtualenv /builds/slave/test/build/venv 09:18:13 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:18:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:18:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:18:13 INFO - Using real prefix '/usr' 09:18:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:18:13 INFO - Installing distribute.............................................................................................................................................................................................done. 09:18:15 INFO - Installing pip.................done. 09:18:15 INFO - Return code: 0 09:18:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:18:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:18:15 INFO - Reading from file tmpfile_stdout 09:18:15 INFO - Output received: 09:18:15 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:18:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:18: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')]} 09:18:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18: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')]} 09:18:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18: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 0x7f051ad41f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x238b790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2388930>, '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 0x2389830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2389cc0>, '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': '20e666a3d4cfbb71505a330e000001b4-1474993076.426389-568394526', '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 09:18: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 09:18: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 09:18:15 INFO - Using env: {'DISPLAY': ':0', 09:18:15 INFO - 'HOME': '/home/cltbld', 09:18:15 INFO - 'LANG': 'en_US.UTF-8', 09:18:15 INFO - 'LANGUAGE': 'en_US:en', 09:18:15 INFO - 'LOGNAME': 'cltbld', 09:18:15 INFO - 'MAIL': '/var/mail/cltbld', 09:18:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:15 INFO - 'MOZ_NO_REMOTE': '1', 09:18:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:18:15 INFO - 'NO_EM_RESTART': '1', 09:18:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:18:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:15 INFO - 'PWD': '/builds/slave/test', 09:18:15 INFO - 'SHELL': '/bin/bash', 09:18:15 INFO - 'SHLVL': '1', 09:18:15 INFO - 'TERM': 'linux', 09:18:15 INFO - 'TMOUT': '86400', 09:18:15 INFO - 'USER': 'cltbld', 09:18:15 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1474993076.426389-568394526', 09:18:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:15 INFO - '_': '/tools/buildbot/bin/python'} 09:18:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:16 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:18:16 INFO - Cleaning up... 09:18:16 INFO - Return code: 0 09:18:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:18:16 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')]} 09:18:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:16 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')]} 09:18:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:16 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 0x7f051ad41f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x238b790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2388930>, '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 0x2389830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2389cc0>, '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': '20e666a3d4cfbb71505a330e000001b4-1474993076.426389-568394526', '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 09:18:16 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 09:18:16 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 09:18:16 INFO - Using env: {'DISPLAY': ':0', 09:18:16 INFO - 'HOME': '/home/cltbld', 09:18:16 INFO - 'LANG': 'en_US.UTF-8', 09:18:16 INFO - 'LANGUAGE': 'en_US:en', 09:18:16 INFO - 'LOGNAME': 'cltbld', 09:18:16 INFO - 'MAIL': '/var/mail/cltbld', 09:18:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:16 INFO - 'MOZ_NO_REMOTE': '1', 09:18:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:18:16 INFO - 'NO_EM_RESTART': '1', 09:18:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:18:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:16 INFO - 'PWD': '/builds/slave/test', 09:18:16 INFO - 'SHELL': '/bin/bash', 09:18:16 INFO - 'SHLVL': '1', 09:18:16 INFO - 'TERM': 'linux', 09:18:16 INFO - 'TMOUT': '86400', 09:18:16 INFO - 'USER': 'cltbld', 09:18:16 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1474993076.426389-568394526', 09:18:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:16 INFO - '_': '/tools/buildbot/bin/python'} 09:18:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:16 INFO - Downloading/unpacking psutil>=3.1.1 09:18:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:18:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:18:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:18:18 INFO - Installing collected packages: psutil 09:18:18 INFO - Running setup.py install for psutil 09:18:18 INFO - building 'psutil._psutil_linux' extension 09:18:18 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 09:18: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 09:18:18 INFO - building 'psutil._psutil_posix' extension 09:18: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 09:18: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 09:18:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:18:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:18:18 INFO - Successfully installed psutil 09:18:18 INFO - Cleaning up... 09:18:18 INFO - Return code: 0 09:18:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:18: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')]} 09:18:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18: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')]} 09:18:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18: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 0x7f051ad41f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x238b790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2388930>, '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 0x2389830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2389cc0>, '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': '20e666a3d4cfbb71505a330e000001b4-1474993076.426389-568394526', '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 09:18: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 09:18: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 09:18:18 INFO - Using env: {'DISPLAY': ':0', 09:18:18 INFO - 'HOME': '/home/cltbld', 09:18:18 INFO - 'LANG': 'en_US.UTF-8', 09:18:18 INFO - 'LANGUAGE': 'en_US:en', 09:18:18 INFO - 'LOGNAME': 'cltbld', 09:18:18 INFO - 'MAIL': '/var/mail/cltbld', 09:18:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:18 INFO - 'MOZ_NO_REMOTE': '1', 09:18:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:18:18 INFO - 'NO_EM_RESTART': '1', 09:18:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:18:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:18 INFO - 'PWD': '/builds/slave/test', 09:18:18 INFO - 'SHELL': '/bin/bash', 09:18:18 INFO - 'SHLVL': '1', 09:18:18 INFO - 'TERM': 'linux', 09:18:18 INFO - 'TMOUT': '86400', 09:18:18 INFO - 'USER': 'cltbld', 09:18:18 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1474993076.426389-568394526', 09:18:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:18 INFO - '_': '/tools/buildbot/bin/python'} 09:18:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:18:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:18:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:18:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:18:21 INFO - Installing collected packages: mozsystemmonitor 09:18:21 INFO - Running setup.py install for mozsystemmonitor 09:18:21 INFO - Successfully installed mozsystemmonitor 09:18:21 INFO - Cleaning up... 09:18:21 INFO - Return code: 0 09:18:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:18:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f051ad41f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x238b790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2388930>, '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 0x2389830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2389cc0>, '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': '20e666a3d4cfbb71505a330e000001b4-1474993076.426389-568394526', '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 09:18:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:18:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:18:21 INFO - Using env: {'DISPLAY': ':0', 09:18:21 INFO - 'HOME': '/home/cltbld', 09:18:21 INFO - 'LANG': 'en_US.UTF-8', 09:18:21 INFO - 'LANGUAGE': 'en_US:en', 09:18:21 INFO - 'LOGNAME': 'cltbld', 09:18:21 INFO - 'MAIL': '/var/mail/cltbld', 09:18:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:21 INFO - 'MOZ_NO_REMOTE': '1', 09:18:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:18:21 INFO - 'NO_EM_RESTART': '1', 09:18:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:18:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:21 INFO - 'PWD': '/builds/slave/test', 09:18:21 INFO - 'SHELL': '/bin/bash', 09:18:21 INFO - 'SHLVL': '1', 09:18:21 INFO - 'TERM': 'linux', 09:18:21 INFO - 'TMOUT': '86400', 09:18:21 INFO - 'USER': 'cltbld', 09:18:21 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1474993076.426389-568394526', 09:18:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:21 INFO - '_': '/tools/buildbot/bin/python'} 09:18:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:21 INFO - Downloading/unpacking jsonschema==2.5.1 09:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:18:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:18: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 09:18: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 09:18: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 09:18: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 09:18: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 09:18: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 09:18:23 INFO - Downloading functools32-3.2.3-2.tar.gz 09:18:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:18:23 INFO - warning: no files found matching '*.txt' 09:18:23 INFO - no previously-included directories found matching 'build' 09:18:23 INFO - no previously-included directories found matching 'dist' 09:18:23 INFO - no previously-included directories found matching '.git*' 09:18:23 INFO - Installing collected packages: jsonschema, functools32 09:18:23 INFO - Running setup.py install for functools32 09:18:23 INFO - warning: no files found matching '*.txt' 09:18:23 INFO - no previously-included directories found matching 'build' 09:18:23 INFO - no previously-included directories found matching 'dist' 09:18:23 INFO - no previously-included directories found matching '.git*' 09:18:23 INFO - Successfully installed jsonschema functools32 09:18:23 INFO - Cleaning up... 09:18:23 INFO - Return code: 0 09:18:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:18: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')]} 09:18:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18: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')]} 09:18:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18: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 0x7f051ad41f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x238b790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2388930>, '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 0x2389830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2389cc0>, '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': '20e666a3d4cfbb71505a330e000001b4-1474993076.426389-568394526', '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 09:18: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 09:18: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 09:18:23 INFO - Using env: {'DISPLAY': ':0', 09:18:23 INFO - 'HOME': '/home/cltbld', 09:18:23 INFO - 'LANG': 'en_US.UTF-8', 09:18:23 INFO - 'LANGUAGE': 'en_US:en', 09:18:23 INFO - 'LOGNAME': 'cltbld', 09:18:23 INFO - 'MAIL': '/var/mail/cltbld', 09:18:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:23 INFO - 'MOZ_NO_REMOTE': '1', 09:18:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:18:23 INFO - 'NO_EM_RESTART': '1', 09:18:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:18:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:23 INFO - 'PWD': '/builds/slave/test', 09:18:23 INFO - 'SHELL': '/bin/bash', 09:18:23 INFO - 'SHLVL': '1', 09:18:23 INFO - 'TERM': 'linux', 09:18:23 INFO - 'TMOUT': '86400', 09:18:23 INFO - 'USER': 'cltbld', 09:18:23 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1474993076.426389-568394526', 09:18:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:23 INFO - '_': '/tools/buildbot/bin/python'} 09:18:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:18:24 INFO - Cleaning up... 09:18:24 INFO - Return code: 0 09:18:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:18:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:24 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 0x7f051ad41f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x238b790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2388930>, '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 0x2389830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2389cc0>, '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': '20e666a3d4cfbb71505a330e000001b4-1474993076.426389-568394526', '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 09:18:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:18:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:18:24 INFO - Using env: {'DISPLAY': ':0', 09:18:24 INFO - 'HOME': '/home/cltbld', 09:18:24 INFO - 'LANG': 'en_US.UTF-8', 09:18:24 INFO - 'LANGUAGE': 'en_US:en', 09:18:24 INFO - 'LOGNAME': 'cltbld', 09:18:24 INFO - 'MAIL': '/var/mail/cltbld', 09:18:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:24 INFO - 'MOZ_NO_REMOTE': '1', 09:18:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:18:24 INFO - 'NO_EM_RESTART': '1', 09:18:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:18:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:24 INFO - 'PWD': '/builds/slave/test', 09:18:24 INFO - 'SHELL': '/bin/bash', 09:18:24 INFO - 'SHLVL': '1', 09:18:24 INFO - 'TERM': 'linux', 09:18:24 INFO - 'TMOUT': '86400', 09:18:24 INFO - 'USER': 'cltbld', 09:18:24 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1474993076.426389-568394526', 09:18:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:24 INFO - '_': '/tools/buildbot/bin/python'} 09:18:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:24 INFO - Downloading/unpacking blobuploader==1.2.4 09:18:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:26 INFO - Downloading blobuploader-1.2.4.tar.gz 09:18:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:18:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:18:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:27 INFO - Downloading docopt-0.6.1.tar.gz 09:18:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:18:27 INFO - Installing collected packages: blobuploader, requests, docopt 09:18:27 INFO - Running setup.py install for blobuploader 09:18:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:18:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:18:27 INFO - Running setup.py install for requests 09:18:27 INFO - Running setup.py install for docopt 09:18:27 INFO - Successfully installed blobuploader requests docopt 09:18:27 INFO - Cleaning up... 09:18:27 INFO - Return code: 0 09:18:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:18:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:27 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 0x7f051ad41f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x238b790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2388930>, '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 0x2389830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2389cc0>, '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': '20e666a3d4cfbb71505a330e000001b4-1474993076.426389-568394526', '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 09:18:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:18:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:18:27 INFO - Using env: {'DISPLAY': ':0', 09:18:27 INFO - 'HOME': '/home/cltbld', 09:18:27 INFO - 'LANG': 'en_US.UTF-8', 09:18:27 INFO - 'LANGUAGE': 'en_US:en', 09:18:27 INFO - 'LOGNAME': 'cltbld', 09:18:27 INFO - 'MAIL': '/var/mail/cltbld', 09:18:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:27 INFO - 'MOZ_NO_REMOTE': '1', 09:18:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:18:27 INFO - 'NO_EM_RESTART': '1', 09:18:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:18:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:27 INFO - 'PWD': '/builds/slave/test', 09:18:27 INFO - 'SHELL': '/bin/bash', 09:18:27 INFO - 'SHLVL': '1', 09:18:27 INFO - 'TERM': 'linux', 09:18:27 INFO - 'TMOUT': '86400', 09:18:27 INFO - 'USER': 'cltbld', 09:18:27 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1474993076.426389-568394526', 09:18:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:27 INFO - '_': '/tools/buildbot/bin/python'} 09:18:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:18:27 INFO - Running setup.py (path:/tmp/pip-DIxqaj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:18:28 INFO - Running setup.py (path:/tmp/pip-4GiTBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:18:28 INFO - Running setup.py (path:/tmp/pip-QcGuB6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:18:28 INFO - Running setup.py (path:/tmp/pip-5Cbof2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:18:28 INFO - Running setup.py (path:/tmp/pip-gyXoEG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:18:28 INFO - Running setup.py (path:/tmp/pip-aA5l94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:18:28 INFO - Running setup.py (path:/tmp/pip-mxaUD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:18:28 INFO - Running setup.py (path:/tmp/pip-G_38Wo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:18:28 INFO - Running setup.py (path:/tmp/pip-etSfTK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:18:28 INFO - Running setup.py (path:/tmp/pip-c2vFQW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:18:28 INFO - Running setup.py (path:/tmp/pip-3wF8jm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:18:28 INFO - Running setup.py (path:/tmp/pip-rbNuSJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:18:28 INFO - Running setup.py (path:/tmp/pip-6Ed_ag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:18:28 INFO - Running setup.py (path:/tmp/pip-bcMH2v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:18:28 INFO - Running setup.py (path:/tmp/pip-_uuS2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:18:29 INFO - Running setup.py (path:/tmp/pip-I_N_wE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:18:29 INFO - Running setup.py (path:/tmp/pip-CGnUbl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:18:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:18:29 INFO - Running setup.py install for manifestparser 09:18:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:18:29 INFO - Running setup.py install for mozcrash 09:18:29 INFO - Running setup.py install for mozdebug 09:18:29 INFO - Running setup.py install for mozdevice 09:18:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:18:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:18:29 INFO - Running setup.py install for mozfile 09:18:29 INFO - Running setup.py install for mozhttpd 09:18:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:18:29 INFO - Running setup.py install for mozinfo 09:18:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:18:29 INFO - Running setup.py install for mozInstall 09:18:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:18:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:18:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:18:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:18:29 INFO - Running setup.py install for mozleak 09:18:30 INFO - Running setup.py install for mozlog 09:18:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:18:30 INFO - Running setup.py install for moznetwork 09:18:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:18:30 INFO - Running setup.py install for mozprocess 09:18:30 INFO - Running setup.py install for mozprofile 09:18:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:18:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:18:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:18:30 INFO - Running setup.py install for mozrunner 09:18:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:18:30 INFO - Running setup.py install for mozscreenshot 09:18:30 INFO - Running setup.py install for moztest 09:18:30 INFO - Running setup.py install for mozversion 09:18:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:18:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:18:30 INFO - Cleaning up... 09:18:30 INFO - Return code: 0 09:18:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:18: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')]} 09:18:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18: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')]} 09:18:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18: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 0x7f051ad41f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x238b790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2388930>, '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 0x2389830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2389cc0>, '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': '20e666a3d4cfbb71505a330e000001b4-1474993076.426389-568394526', '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 09:18: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 09:18: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 09:18:30 INFO - Using env: {'DISPLAY': ':0', 09:18:30 INFO - 'HOME': '/home/cltbld', 09:18:30 INFO - 'LANG': 'en_US.UTF-8', 09:18:30 INFO - 'LANGUAGE': 'en_US:en', 09:18:30 INFO - 'LOGNAME': 'cltbld', 09:18:30 INFO - 'MAIL': '/var/mail/cltbld', 09:18:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:30 INFO - 'MOZ_NO_REMOTE': '1', 09:18:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:18:30 INFO - 'NO_EM_RESTART': '1', 09:18:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:18:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:30 INFO - 'PWD': '/builds/slave/test', 09:18:30 INFO - 'SHELL': '/bin/bash', 09:18:30 INFO - 'SHLVL': '1', 09:18:30 INFO - 'TERM': 'linux', 09:18:30 INFO - 'TMOUT': '86400', 09:18:30 INFO - 'USER': 'cltbld', 09:18:30 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1474993076.426389-568394526', 09:18:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:30 INFO - '_': '/tools/buildbot/bin/python'} 09:18:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:18:31 INFO - Running setup.py (path:/tmp/pip-pBJQrl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:18:31 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:18:31 INFO - Running setup.py (path:/tmp/pip-FD0w_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:18:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:18:31 INFO - Running setup.py (path:/tmp/pip-nBmTiE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:18:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:18:31 INFO - Running setup.py (path:/tmp/pip-UhX8Rj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:18:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:18:31 INFO - Running setup.py (path:/tmp/pip-TlqIb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:18:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:18:31 INFO - Running setup.py (path:/tmp/pip-YIrIHJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:18:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:18:31 INFO - Running setup.py (path:/tmp/pip-IfJudd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:18:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:18:31 INFO - Running setup.py (path:/tmp/pip-PKzKG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:18:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:18:31 INFO - Running setup.py (path:/tmp/pip-QrlSjs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:18:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:18:31 INFO - Running setup.py (path:/tmp/pip-CrJz33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:18:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:18:31 INFO - Running setup.py (path:/tmp/pip-gmSDdS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:18: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)) 09:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:18:32 INFO - Running setup.py (path:/tmp/pip-VEouaH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:18:32 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)) 09:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:18:32 INFO - Running setup.py (path:/tmp/pip-8ZXgXq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:18:32 INFO - Running setup.py (path:/tmp/pip-XZcJhu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:18:32 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)) 09:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:18:32 INFO - Running setup.py (path:/tmp/pip-Sp_yQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:18:32 INFO - Running setup.py (path:/tmp/pip-H1WfLp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:18:32 INFO - Running setup.py (path:/tmp/pip-lxGMhX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:18:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:34 INFO - Downloading blessings-1.6.tar.gz 09:18:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:18:34 INFO - Installing collected packages: blessings 09:18:34 INFO - Running setup.py install for blessings 09:18:34 INFO - Successfully installed blessings 09:18:34 INFO - Cleaning up... 09:18:34 INFO - Return code: 0 09:18:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:18:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:18:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:18:34 INFO - Reading from file tmpfile_stdout 09:18:34 INFO - Current package versions: 09:18:34 INFO - argparse == 1.2.1 09:18:34 INFO - blessings == 1.6 09:18:34 INFO - blobuploader == 1.2.4 09:18:34 INFO - docopt == 0.6.1 09:18:34 INFO - functools32 == 3.2.3-2 09:18:34 INFO - jsonschema == 2.5.1 09:18:34 INFO - manifestparser == 1.1 09:18:34 INFO - mozInstall == 1.12 09:18:34 INFO - mozcrash == 0.17 09:18:34 INFO - mozdebug == 0.1 09:18:34 INFO - mozdevice == 0.48 09:18:34 INFO - mozfile == 1.2 09:18:34 INFO - mozhttpd == 0.7 09:18:34 INFO - mozinfo == 0.9 09:18:34 INFO - mozleak == 0.1 09:18:34 INFO - mozlog == 3.4 09:18:34 INFO - moznetwork == 0.27 09:18:34 INFO - mozprocess == 0.23 09:18:34 INFO - mozprofile == 0.28 09:18:34 INFO - mozrunner == 6.12 09:18:34 INFO - mozscreenshot == 0.1 09:18:34 INFO - mozsystemmonitor == 0.3 09:18:34 INFO - moztest == 0.7 09:18:34 INFO - mozversion == 1.4 09:18:34 INFO - psutil == 3.1.1 09:18:34 INFO - requests == 1.2.3 09:18:34 INFO - wsgiref == 0.1.2 09:18:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:18: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')]} 09:18:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18: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')]} 09:18:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:34 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 0x7f051ad41f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x238b790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2388930>, '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 0x2389830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2389cc0>, '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': '20e666a3d4cfbb71505a330e000001b4-1474993076.426389-568394526', '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 09:18:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:18:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:18:34 INFO - Using env: {'DISPLAY': ':0', 09:18:34 INFO - 'HOME': '/home/cltbld', 09:18:34 INFO - 'LANG': 'en_US.UTF-8', 09:18:34 INFO - 'LANGUAGE': 'en_US:en', 09:18:34 INFO - 'LOGNAME': 'cltbld', 09:18:34 INFO - 'MAIL': '/var/mail/cltbld', 09:18:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:34 INFO - 'MOZ_NO_REMOTE': '1', 09:18:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:18:34 INFO - 'NO_EM_RESTART': '1', 09:18:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:18:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:34 INFO - 'PWD': '/builds/slave/test', 09:18:34 INFO - 'SHELL': '/bin/bash', 09:18:34 INFO - 'SHLVL': '1', 09:18:34 INFO - 'TERM': 'linux', 09:18:34 INFO - 'TMOUT': '86400', 09:18:34 INFO - 'USER': 'cltbld', 09:18:34 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1474993076.426389-568394526', 09:18:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:34 INFO - '_': '/tools/buildbot/bin/python'} 09:18:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18: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)) 09:18: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)) 09:18: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)) 09:18: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)) 09:18: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)) 09:18: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)) 09:18: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)) 09:18: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)) 09:18: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)) 09:18: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)) 09:18: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)) 09:18:34 INFO - Cleaning up... 09:18:35 INFO - Return code: 0 09:18:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:18:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:35 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 0x7f051ad41f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22be8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x238b790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2388930>, '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 0x2389830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2389cc0>, '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': '20e666a3d4cfbb71505a330e000001b4-1474993076.426389-568394526', '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 09:18:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:18:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:18:35 INFO - Using env: {'DISPLAY': ':0', 09:18:35 INFO - 'HOME': '/home/cltbld', 09:18:35 INFO - 'LANG': 'en_US.UTF-8', 09:18:35 INFO - 'LANGUAGE': 'en_US:en', 09:18:35 INFO - 'LOGNAME': 'cltbld', 09:18:35 INFO - 'MAIL': '/var/mail/cltbld', 09:18:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:35 INFO - 'MOZ_NO_REMOTE': '1', 09:18:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:18:35 INFO - 'NO_EM_RESTART': '1', 09:18:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:18:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:35 INFO - 'PWD': '/builds/slave/test', 09:18:35 INFO - 'SHELL': '/bin/bash', 09:18:35 INFO - 'SHLVL': '1', 09:18:35 INFO - 'TERM': 'linux', 09:18:35 INFO - 'TMOUT': '86400', 09:18:35 INFO - 'USER': 'cltbld', 09:18:35 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1474993076.426389-568394526', 09:18:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:35 INFO - '_': '/tools/buildbot/bin/python'} 09:18:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:18:35 INFO - Cleaning up... 09:18:35 INFO - Return code: 0 09:18:35 INFO - Running post-action listener: _resource_record_post_action 09:18:35 INFO - Running post-action listener: _start_resource_monitoring 09:18:35 INFO - Starting resource monitoring. 09:18:35 INFO - [mozharness: 2016-09-27 16:18:35.242556Z] Finished create-virtualenv step (success) 09:18:35 INFO - [mozharness: 2016-09-27 16:18:35.243058Z] Running install step. 09:18:35 INFO - Running pre-action listener: _resource_record_pre_action 09:18:35 INFO - Running main action method: install 09:18:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:18:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:18:35 INFO - Reading from file tmpfile_stdout 09:18:35 INFO - Detecting whether we're running mozinstall >=1.0... 09:18:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:18:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:18:35 INFO - Reading from file tmpfile_stdout 09:18:35 INFO - Output received: 09:18:35 INFO - Usage: mozinstall [options] installer 09:18:35 INFO - Options: 09:18:35 INFO - -h, --help show this help message and exit 09:18:35 INFO - -d DEST, --destination=DEST 09:18:35 INFO - Directory to install application into. [default: 09:18:35 INFO - "/builds/slave/test"] 09:18:35 INFO - --app=APP Application being installed. [default: firefox] 09:18:35 INFO - mkdir: /builds/slave/test/build/application 09:18:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:18:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:18:52 INFO - Reading from file tmpfile_stdout 09:18:52 INFO - Output received: 09:18:52 INFO - /builds/slave/test/build/application/firefox/firefox 09:18:52 INFO - Running post-action listener: _resource_record_post_action 09:18:52 INFO - [mozharness: 2016-09-27 16:18:52.553501Z] Finished install step (success) 09:18:52 INFO - [mozharness: 2016-09-27 16:18:52.553838Z] Running run-tests step. 09:18:52 INFO - Running pre-action listener: _resource_record_pre_action 09:18:52 INFO - Running main action method: run_tests 09:18:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:18:52 INFO - Python 2.7.3 09:18:52 INFO - Return code: 0 09:18:52 INFO - grabbing minidump binary from tooltool 09:18:52 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')]} 09:18:52 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 0x2388930>, '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 0x2389830>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2389cc0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:18:52 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 09:18:52 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 09:18:52 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 09:18:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:18:52 INFO - Return code: 0 09:18:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:18:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:18:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:18:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:18:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:18:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Tz731C6cQJ-unKVkKathsw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Tz731C6cQJ-unKVkKathsw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-015 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:18:52 INFO - Using env: {'DISPLAY': ':0', 09:18:52 INFO - 'HOME': '/home/cltbld', 09:18:52 INFO - 'LANG': 'en_US.UTF-8', 09:18:52 INFO - 'LANGUAGE': 'en_US:en', 09:18:52 INFO - 'LOGNAME': 'cltbld', 09:18:52 INFO - 'MAIL': '/var/mail/cltbld', 09:18:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:18:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:18:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:52 INFO - 'MOZ_NO_REMOTE': '1', 09:18:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:18:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:18:52 INFO - 'NO_EM_RESTART': '1', 09:18:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:18:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:52 INFO - 'PWD': '/builds/slave/test', 09:18:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:18:52 INFO - 'SHELL': '/bin/bash', 09:18:52 INFO - 'SHLVL': '1', 09:18:52 INFO - 'TERM': 'linux', 09:18:52 INFO - 'TMOUT': '86400', 09:18:52 INFO - 'USER': 'cltbld', 09:18:52 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1474993076.426389-568394526', 09:18:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:52 INFO - '_': '/tools/buildbot/bin/python'} 09:18:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Tz731C6cQJ-unKVkKathsw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:18:52 INFO - mozversion application_buildid: 20160927063018 09:18:52 INFO - mozversion application_changeset: 7c1929f35c5d11b0b921ebd03c197bdb5afbeaca 09:18:52 INFO - mozversion application_display_name: Nightly 09:18:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:18:52 INFO - mozversion application_name: Firefox 09:18:52 INFO - mozversion application_remotingname: firefox 09:18:52 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:18:52 INFO - mozversion application_vendor: Mozilla 09:18:52 INFO - mozversion application_version: 52.0a1 09:18:52 INFO - mozversion platform_buildid: 20160927063018 09:18:52 INFO - mozversion platform_changeset: 7c1929f35c5d11b0b921ebd03c197bdb5afbeaca 09:18:52 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:18:52 INFO - mozversion platform_version: 52.0a1 09:18:52 INFO - using testdate: 1474993132 09:18:52 INFO - actual date: 1474993132 09:18:52 INFO - starting webserver on 'localhost:33630' 09:18:52 INFO - SUITE-START | Running 2 tests 09:18:52 INFO - TEST-START | dromaeo_css 09:18:52 INFO - operating with platform_type : linux_ 09:18:52 INFO - Initialising browser for dromaeo_css test... 09:18:52 INFO - TEST-INFO | started process 11566 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9ZT_pQ/profile http://localhost:33630/getInfo.html) 09:18:57 INFO - PROCESS | 11566 | __metrics Screen width/height:1600/1200 09:18:57 INFO - PROCESS | 11566 | colorDepth:24 09:18:57 INFO - PROCESS | 11566 | Browser inner width/height: 1024/697 09:18:57 INFO - PROCESS | 11566 | __metrics 09:18:57 INFO - PROCESS | 11566 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:18:57 INFO - PROCESS | 11566 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:18:58 INFO - PROCESS | 11566 | console.error: PushService: 09:18:58 INFO - PROCESS | 11566 | stateChangeProcessEnqueue: Error transitioning state 09:18:58 INFO - PROCESS | 11566 | UnknownError 09:18:58 INFO - TEST-INFO | 11566: exit 0 09:18:58 INFO - Browser initialized. 09:18:58 INFO - Running cycle 1/1 for dromaeo_css test... 09:18:58 INFO - TEST-INFO | started process 11648 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9ZT_pQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:19:10 INFO - PROCESS | 11648 | RSS: Main: 173453312 09:19:10 INFO - PROCESS | 11648 | 09:20:02 INFO - PROCESS | 11648 | 1474993202087 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:22:11 INFO - PROCESS | 11648 | Cycle 1(1): loaded http://localhost:33630/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33630/tests/dromaeo/cssquery-ext.html) 09:22:12 INFO - PROCESS | 11648 | RSS: Main: 281473024 09:22:12 INFO - PROCESS | 11648 | 09:25:15 INFO - PROCESS | 11648 | Cycle 1(1): loaded http://localhost:33630/tests/dromaeo/cssquery-ext.html (next: http://localhost:33630/tests/dromaeo/cssquery-jquery.html) 09:25:16 INFO - PROCESS | 11648 | RSS: Main: 274415616 09:25:16 INFO - PROCESS | 11648 | 09:28:17 INFO - PROCESS | 11648 | Cycle 1(1): loaded http://localhost:33630/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33630/tests/dromaeo/cssquery-mootools.html) 09:28:18 INFO - PROCESS | 11648 | RSS: Main: 273264640 09:28:18 INFO - PROCESS | 11648 | 09:31:20 INFO - PROCESS | 11648 | Cycle 1(1): loaded http://localhost:33630/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33630/tests/dromaeo/cssquery-prototype.html) 09:31:21 INFO - PROCESS | 11648 | RSS: Main: 216551424 09:31:21 INFO - PROCESS | 11648 | 09:34:23 INFO - PROCESS | 11648 | Cycle 1(1): loaded http://localhost:33630/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33630/tests/dromaeo/cssquery-yui.html) 09:34:24 INFO - PROCESS | 11648 | RSS: Main: 216027136 09:34:24 INFO - PROCESS | 11648 | 09:37:27 INFO - PROCESS | 11648 | Cycle 1(1): loaded http://localhost:33630/tests/dromaeo/cssquery-yui.html (next: http://localhost:33630/tests/dromaeo/cssquery-dojo.html) 09:37:27 INFO - PROCESS | 11648 | RSS: Main: 306601984 09:37:27 INFO - PROCESS | 11648 | 09:37:27 INFO - PROCESS | 11648 | __start_tp_report 09:37:27 INFO - PROCESS | 11648 | _x_x_mozilla_page_load 09:37:27 INFO - PROCESS | 11648 | _x_x_mozilla_page_load_details 09:37:27 INFO - PROCESS | 11648 | |i|pagename|runs| 09:37:27 INFO - PROCESS | 11648 | |0;dojo.html;3014.7136022077907;3044.3606842563063;3050.4356694011603;3086.0741777466783;3095.962852444431;5223.947760522394;5234.633575649701;5297.814576489819;5336.306280183576;5346.599005074616;5183.041137389586;5227.712475813829;5235.293235413218;5323.707196104213;5343;4789.7365644889605;4812.374391329122;4816.62726123055;4827.493113223114;4937.65436419449;4900.338454308659;4917.237828136645;4933.1120398328285;4945.851624451272;4957.801687932497;4872.853814385574;4918.852434426955;4921.434035085975;4924.187509061001;4965.255211718253;4763.666543341968;4806.014766972762;4884.682495637808;4888.926666100011;4915.041566894457;4768.952310476886;4802.807887684488;4813.638977076734;4821.300911367867;4883.584895283905;5282.603804714662;5315.627906046579;5319.095753721838;5322.2282768998275;5398.838034858922;5512.80705175321;5669.206311116447;5670.68811215383;5678.488935995739;5681.460261275172;5580.972095139539;5644.181593668936;5669.518090962273;5688.544916406719;5716.514096301819;5332.466753324714;5338.199270109437;5339.385970613429;5395.05586522357;5403.108487099624;5833.82498525045;5836.970815145897;5842.707864606838;5843.853903652443;5848.210491583582;5446.618736688473;5455.809046683347;5517.806876759374;5599.32808063035;5611.691356975324;5617.831465056055;5647.858803529944;5663.801766938137;5733.483986441241;5736.368999410061;5610.13042978339;5619.606627536034;5623.128415095667;5625.71871406428;5659.037963546207;5469.288992430958;5577.163425486209;5650.886982260332;5653;5723.255976723082;4334.761588112621;4344.370066340363;4349.891252718708;4384.780760961939;4416.24923762961;3878.670313023368;3891.766494010312;3892.5718171001167;3932.3511620582285;3938.487996560486;5370.677759334452;5373.408925018245;5379.327584051994;5381.434949330264;5424.864378390572;5559.972200139055;5560.944390556124;5576.302962129734;5589.888202235932;5638.85902852432;5298.655587386885;5352.197170424468;5373.247745315659;5386.084365657847;5435;4834.7824347904425;4871.853844384674;4878.463369029404;4922.778474968635;4938.234573641092;5844.503217226498;5850.912236316459;5856.824295271149;5857.15071314657;5858.970705146447;5549.250851135206;5619.297587801525;5659.292588426447;5661.547076233809;5665.745041473226;5804.941950580609;5816.0694288915265;5862.970685146547;5866.530677545701;5874.794382196603;5587.832365029056;5589.189567512606;5589.916151257653;5595.63628364155;5597.3003374578175;5584.609077364708;5603;5619.185218143428;5620.18507316445;5660.660360378391;5507.807226747044;5528.032594295902;5550.583706221969;5608.523275521627;5610.102383618601;5531.280933478622;5531.474509921551;5533.806316779054;5535.501804837584;5536.806211782729;5358.490943360374;5415.566754659539;5473.698946557977;5484.575566260709;5545.972270138624;5538.6399884007415;5616.297962754656;5633;5650.491455769001;5703.058995265735;5522.834314970557;5523.917141242804;5530.142827861688;5530.972345138249;5611.3266408031295 09:37:27 INFO - PROCESS | 11648 | |1;ext.html;558.501878380249;576.2048373244922;604.9395060493952;610.6702380714418;616.5622408090255;31075.378492430165;42701.57298427015;43539;43750;43817.12365752683;42783.144337113314;43107.13785724284;43142.784286078524;43205;43245;7945.284924356807;8121.918780812175;8830.24942879856;8973.775655608597;8980.46117232965;10351.37891726495;10635;10640.0955918747;11022.283551569162;11046.668599942015;10709.785804283909;10793.352398856094;10805.5677772889;10807.297525660806;10819.242653014293;10666.466676666174;10703.625373111981;10709.410982395964;10731.248812583122;10747.999999999962;10489.423081730502;10550.314229575053;10551;10568.260221784478;10569.735756606146;40933.181336373105;41206.38190427146;41223.38164927528;41240.793796030826;41299.793501032604;3380.2732412531423;3392.9830350848333;3406.3527929693278;3539.026767638894;3582.570091589026;31449.31768572458;31640;31880.202994925312;32763.67236327654;33829.323413531594;3912.6478616924614;3927.6661483773914;4054.9051756026;4135.7518548887165;4175.3945677876845;337344;337899;338330;338453;338692;59414.70292648509;59748.10377844249;59808;59863.02054691744;59892;36496.27007459836;37356.43965340522;37399.62600374016;38888;38919.221615568094;17095.401660942058;17234;17238.051907144978;17245.827541724422;17269.654606908043;12227.327496987751;12487.687807804696;12548.560800371943;12623.242605443518;12629.052821038458;18327.450176494727;18503.259869605066;18607;18667.90666046661;18679.346222882134;6759.729610815611;7148.499605027599;7184.389326907271;7216.855662886818;7305.926940730631;917.9908200918039;1038.6053299746047;1075.725689949058;1076.8923107689168;1089.727568107973;30656.38687226243;32122.67877321197;32504.674953250164;32643.347133057207;33074.3385132296;1070.4915165296422;1121.61864965912;1189.167582692119;1189.2923710392304;1206.1556910162922;17619.647607048042;18055.452425857864;18191.545211369557;18761.624767504574;18985.33551325697;483830;484785;485517.5792968312;490053.19893601927;493775;56984;57357.42642573521;57415;57468;57492.71253643705;1203.6750077479207;1255.8869701726887;1317.4993502468808;1353.7360214758226;1374.0313079279085;28318.716812831608;33253.50119748157;33367.83316083404;33573.32853342968;34080;48762.756186218845;49455.75272123616;49474.25788613246;49529.08476474269;49588;374986;378384;379517;379957;380121;24843.254702358958;24900;25395.492090158463;25696.871515642302;28556;43312.566874332115;44899.77550112228;45698.31452528148;46121.769391152826;46365.30452043154;38063.42904856374;38968;39817.40273895952;39908.40137397999;40905.59094409137;38656.2268754629;39510.80244598759;39953.80023099866;40025.79987100046;40675.59324406718;39727.40408893926;40063;40123.39814902837;40138.39792403058;40230.39654405245;32957.67042329546;35476.113097172776;35930.8203458981;36067;36296.81851590725;27022.189334320003;27444.413340797666;27467.86266068657;27494.312642183304;27580.310492237855 09:37:27 INFO - PROCESS | 11648 | |2;jquery.html;383.93665045267534;400.2834925483385;400.63342042031536;402.3160626934212;422.53943201909914;13328.466861325547;15428.382864685413;19097;19407.805921940777;19880;15514.689706205869;15587.610309742262;15880.602984925383;15900.920495397508;19498.902505487487;5609.607327487078;5859.209006784094;5867.735951882176;5871.031279838821;5871.765129394819;5445.673259604417;5688.146777983295;5701.800436984707;5734.541236701075;5737.053386191274;5691.51622112121;5700.23046888671;5702.059160238577;5702.686352250625;5706.201131841545;5522.751476183561;5678.34699009613;5687.857803554903;5708.08670612702;5737.110747834093;4960.23116416956;5122.538971492546;5131.486851314876;5133.768980395854;5141.303369887928;22428.215012474648;22619.773802262094;22823.201187958508;23049.617022978677;23444.76555234443;2254.819614430858;2299.66654835048;2379.9881000594887;2976.6130403047464;3141.8900338488043;13864.514741984083;14025.579232622935;14087.295635218301;14271.14373137615;14764.48324308644;1846.3353192850564;1853.2401715296664;1896.2888916656254;1944.931927382549;1960.323688327539;75650;78395.6080219601;78541.21458785396;78663.21336786616;79452.60273698595;10279.229057820543;10280.794384112276;10300.845487317696;10491;10860.239783215098;14942.850571494146;15084.773728394082;15108.697826043417;15167.924160379127;15187.620309492351;7850.921490785133;8306.127856575093;8338.916610833936;8368.121347258571;8487;7945.721899733481;8007.674896193966;8028.4781489203015;8201.13888041759;8220.876686849702;6380.840478988062;6474.773382931575;6562.409383155539;6575.868482630321;6651.800445986628;1784.7412125241767;1822.2893071702047;1878.5867109235946;1881.1346780480858;1926.7206255092933;863.3611127765533;890.9688160914337;952.1525842000625;957.4255446731905;1026.7022563456662;18916.527086822938;18921;19850.60298794016;20011.59976800456;21118.366449006553;936.5176933879058;1006.8842083160383;1012.6152062216458;1026.2816028779885;1028.187731691955;10557.208209384327;10737.409442480583;11058.170637202242;11165.330080195214;11177.664670059748;465014;476359.9704549535;482842;484084;485784;88308;91690.54154729316;92658;93259;93924.53037734768;997.3118548201718;999.4852650884945;1036.9792604148026;1044.2272718188638;1046.5186014433446;16645.91677041607;17036;17593;17868.46394608164;18028.36900708469;41448.37827432651;41728;42364;43372.783136084116;43420.348694768974;57660.42339576551;59413;59772.70113649578;60764.6961765206;61253.08120378109;8004.279614834692;8261.710840120568;8424.410291279552;8518.275946544612;9011.944624918682;12392.132550721362;12523.311217883103;12625.936870315589;12820.871791281968;12911.54680610681;10903.2367734261;11267.549298027987;11334.94332528332;11510.59712910044;11777.057835373322;8196.188772220947;10537.367757934548;11401.201915866142;11611.767764644828;11969.940150299193;8018.558979256197;8375.748727537939;8380.815648974793;8447.8732819009;8721.56392180401;11439.714007149887;12174.939125304316;12608.558700445441;12726.68183295425;12793.8080928788;11746.471408786454;11874.643760687193;11946.880531194924;12648.873511265137;12822.166559173624 09:37:27 INFO - PROCESS | 11648 | |3;mootools.html;1325.0923117664395;1427.5075099090816;1473.1676602719465;1564.4915402494191;1702.7105392083345;7690.26942440468;8508.234258916696;8841;8878.689245876396;9183.540822958843;8933.731988040368;8994.055624159451;9007.59465824038;9011.684591039315;9018.68434604789;2337.6142936415513;2363.2083252110565;2363.61000434928;2366.8698221597806;2370.5851475991717;2288.668143119247;2326.7556906524824;2350.753170917055;2357.8467399619058;2359.221456919221;2303.67748515208;2307.042577330406;2320.6751054852334;2338.859668419892;2341.192288660418;2272.8977196026217;2298.5632729781287;2310.249169020067;2311.768823117683;2317.1194945920442;2278.384836094262;2280;2281.8060464860505;2285.211601997325;2286.942826429336;8281.089080201173;8556.144385561456;8566.914330856736;8575.185357391038;8854.955725221333;5029.899402011939;5247.317848679685;5256.08018596744;5259.395169555473;5278;3233.304839459504;3392.2537041850755;3415.470602056685;3449.6722811332884;3467.7225821934435;1128.3963079752375;1161.2393882007298;1168.275669085164;1172.78889799836;1175.7883580955424;12773.169743966706;12781.360931953459;12794.552190673376;12807.359632018455;12841.614751557468;4360.236958532244;4468.910621787546;4636.304554316879;4641.443026836801;4646.163690535668;9869.309148359689;9884.752881178028;9889.554970026222;9898.10917017472;9904.752381190528;5120.795168193231;5123.87190320245;5126.384833819965;5142.897142057138;5156.63903526757;4275.615194632498;4338.698390482842;4388.27593447084;4711.646626503026;4860.562549370574;3772.5095737553943;3775.867844625425;3891.3579259422445;3921.647051765298;3935.4293627423863;1170.309517384747;1191.3090407563593;1197.3055627735891;1205.6804946689135;1206.7827790997528;913.7395842184945;964.233434419624;972.6692924405736;981.239539357004;998.6005597760953;5840.474357307778;5841.707914604338;5873.823785286449;6025.0661147522205;6072.574919755663;461.3333732756202;474.25305144397714;476.60441833278986;481.24684868181185;483.193067577147;4073.877783666495;4081.673466122703;4097.795110244476;4104.856330028435;4141.254574176677;10755.516001779783;11318.660440186808;11319.320840749582;11339.603113891138;11374;11157.163212758913;11178.0498657615;11180.720481988015;11185.608503702331;11194.608188713519;7799.805004874924;8106.067802202822;8109.351251899951;8286.295664868554;8375.078741338448;6000.8799824004145;6028.306744724413;6045.78839740607;6158.969205153946;6246.562740608114;10084.193264538966;10099.343542669703;10116.29185956976;10117.696469105938;10228.07947284748;9851.261155413229;10244.385336879513;10257.794844103226;10591.682249532527;10604.469776511298;7133.750318738819;7321.890171647323;7762.223777622193;7925.167857375123;7936.2857342839425;8163.1428699985645;8282.792930176794;8362.916370836214;8540.231379175904;8560.357973152162;7315.6342182889575;7434.293742094493;7515.173330933702;7567.205443428581;7608.39132869358;7152.640998210323;7231.674574644048;7238.746643867439;7251.5286506376915;7355.963220183865;6755.898661520179;6759.425448836707;6786.0838786765125;6795.218549866829;6833.4191593715095;5300.80016793042;5300.893982120413;5312.856594728957;5376.489233522809;5377.865553361041;4974.104661161025;5025.3718285214345;5030.874228144326;5058.9494105058475;5068 09:37:27 INFO - PROCESS | 11648 | |4;prototype.html;331.9983400082999;340.04617049177057;347.49613061061467;347.88693674555776;349.4792758789404;5309.362876454826;5328.973355133224;5333.626646134767;5338.27933229014;5341.1187154119525;5246.291750613666;5261.710605916673;5290.576753859682;5293;5296.708681022542;4064.2074795414906;4086.7139300248996;4119.732217405874;4191.958080419195;4200;4132.917341653165;4137.131202447475;4140.805373152234;4143.979280103596;4147.5645057269;4070.124923141524;4091.897702557445;4098.8360465581345;4106.835726570934;4111.239420707164;3930.0960779020697;4041.3937909313545;4048.8987775305854;4050.3721923101966;4117.773522456264;3663.230721548478;3663.56037275526;3678.282734866702;3681.4597927429986;3682.834272457746;5047.788530752629;5086.262491938685;5094.974525127351;5104.25988231708;5151.021305951895;4366.323219900889;4474.2393793054935;4643.698159619647;4677.508861569554;4749.168895443284;5453;5461.208124821878;5466.617336786427;5470;5484.862878428032;3980.0845805464614;3983.621555952209;3986.481757371523;3986.86702614103;3988.441618173458;5419.620626556142;5654.85862853432;5666.433356664342;5697.743601537941;5704.71476426177;4033.3143365627916;4037.5962403759977;4048.028473166478;4054.5134583850127;4059.106996460774;4075.144219713893;4086.8569600063856;4095.2014357200655;4095.9590404096175;4103.179364127186;4454.465464144323;4650.279206722963;4667.743274119888;4679.040796636681;4684.414448193976;5489.64317319373;5498.51540084183;5517.531009864207;5519.172124181325;5527.613067085265;1400.4538230090272;1423.665438621923;1423.686788906439;1424.7221791750717;1426.6290764401124;863.451708165307;914.1635403605759;920.40173886974;926.9304802139867;931.3015238571072;4704.553067458654;4708.599769019604;4744.454387745384;4756.5956893664425;4762.499687663935;4982.327385802871;4986.678530189502;4996.325496058058;5006.549410553068;5007.974960125249;4747;4747.454042785055;4768.380110585671;4773.427188737373;4954.529319714621;2966.6736658967498;3009.593704849861;3013.9547906781418;3014.80403773754;3014.8643311051055;5845.415458454206;5846.561507886926;5852.795152169653;5857.414258574108;5873.911891321633;5547.805826796042;5758.3090029196765;5778.5377169826315;5785.623934444247;5797.536197104221;5790.942090579125;5791.362950075489;5819.039858423313;5825.970870145622;5828.475437210672;5443.972780136074;5608.691521966168;5641.548676105819;5660.547156227574;5662.301309607019;5604.215409842707;5630.254621067482;5633.324001119728;5635.408282130481;5638.4925356718095;5817.970910145422;5852.678102704393;5890;5894.616849904743;5905;3897.629725176104;4053.3717273822604;4075.1034772350035;4088.6320231178147;4121.711480196357;5598.356189038313;5607.579431542569;5610;5613;5622.662640241435;5430.185472179205;5431.6740995540395;5461.726913654222;5479.917801232905;5488.259085023471;5222.955408918156;5458.781648734006;5472.151816468454;5477.863053423696;5480.945190548044;5415.864603384947;5437.673739575638;5453.509184173444;5456.890862182655;5463.699496527729;5291.417944026231;5431;5465.617406781646;5578.972105139448;5585.245991791218;5404.216388623706;5462.781488740406;5463.617546771688;5468.168163665057;5476 09:37:27 INFO - PROCESS | 11648 | |5;yui.html;675.2572170612327;679.5820570349235;681.4173881331461;704.7040243097898;779.9665443287641;15373.538793836175;15915.761263581042;16203.594910127253;16518.091504967222;16550;14013.08914920532;14077.296135193224;14078.929605351985;14101;14109.294535273246;659.3966520633614;769.9461037727348;785.4894318692839;787.7242964962246;787.9881801772977;663.2670898656987;668.084723928219;668.3850857211377;764.3693952489191;770.2374649097382;811.7686459359054;824.3693574415577;825.1212458731462;825.8719898415756;825.9297959673435;813.369638530138;813.4265342933218;814.5194335342147;814.7352110564091;814.8777683347486;498.3596079038455;505.89376230991536;583.2913010691988;597.0536699331565;601.1163589523437;14182.503612373517;14754.336054877449;14767.113973161537;14767.926160369238;14783.113013219241;13350.399232034582;13505.797413038812;13786.724265514735;13788.4531818931;14088.506902258474;11893.345865977368;11916.34460104694;11934.28394296345;12058.336791476466;12143.757124857453;3654.7258955578172;4155.522114956789;4171.499420069581;4339.349097635349;4358.36803663467;74759;75426.24573754302;75532.84569348629;75591.62204188944;75629;10903;10951.233413660968;11015.173861960386;11019.83470247947;11089.944550277198;20155.294564690164;20274.290399835936;20397.796022039885;20458.897705511674;21091.789082109288;7772.611369431505;8119.269265765991;8131.634076466573;8200;8601.22588966996;4617.861464156081;4661.230896902041;4695.718256904528;4717.315989181549;4747.643926705442;3197.0728488738005;3316.6185888622636;3317.2204531934976;3322.1362445763793;3324.25204329024;588.4027711872453;594.485769809112;597.4145337569206;601.4136217188207;601.666075328197;552.3868505958383;619.8822223777559;702.3152426384235;702.8032150997728;718.421670555208;9878.407295562289;10200.285979981478;10290.618837822776;10364.896351036394;10417.010384013507;636.5252276496238;696.8675951569186;699.1854489519754;702.8830116749966;706.4560288577767;4850.80596776125;4873.610111191097;4920.803167873245;4934.3338649282605;4935.062338155739;128068;129848.35075824561;130924;131674.34162829316;135695;109395;111182.44087795579;111998.44000780107;114645;114927;95882;95927;98241.50879245558;99624.00375996293;100147;9221.631134754803;9381.906180938104;9546.761330966781;9585.089416505421;9607.615695372386;51395.463929893536;51714;52347.21479177892;53064;54436.45563544472;75855.4144585537;79895.20104798878;81356;82196.89015549318;85760.57119714361;12218.75562488763;12317.630471085882;12425.565105221274;12793.42429590652;12913.418896149507;13644.317784110954;13779.724405512034;14162.645933851736;14186.787198191829;14424.783628245375;12038.458269378074;12076;12176;12284.20915842804;12410.689732756753;11176.944115279697;12806.61580152597;13064.281464519543;13299;13589.796153057514;13296.734065318833;13706.177629342272;13737.175769453865;13936;14500.057496262709;11053.834192486958;11120.443977800916;11275.210735248782;11306.83039754388;11831.40842957866;10909.39998300101;11341.603043893425;11582.768344633228;11606.361650109326;11626.476808543466 09:37:27 INFO - PROCESS | 11648 | __end_tp_report 09:37:27 INFO - PROCESS | 11648 | __start_cc_report 09:37:27 INFO - PROCESS | 11648 | _x_x_mozilla_cycle_collect,1149 09:37:27 INFO - PROCESS | 11648 | __end_cc_report 09:37:27 INFO - PROCESS | 11648 | __startTimestamp1474994247434__endTimestamp 09:37:27 INFO - PROCESS | 11648 | 09:37:27 INFO - PROCESS | 11648 | ------- Summary: start ------- 09:37:27 INFO - PROCESS | 11648 | Number of tests: 6 09:37:27 INFO - PROCESS | 11648 | 09:37:27 INFO - PROCESS | 11648 | [#0] dojo.html Cycles:165 Average:5273.09 Median:5507.81 stddev:585.00 (10.6%) stddev-sans-first:559.32 09:37:27 INFO - PROCESS | 11648 | Values: 3014.7 3044.4 3050.4 3086.1 3096.0 5223.9 5234.6 5297.8 5336.3 5346.6 5183.0 5227.7 5235.3 5323.7 5343.0 4789.7 4812.4 4816.6 4827.5 4937.7 4900.3 4917.2 4933.1 4945.9 4957.8 4872.9 4918.9 4921.4 4924.2 4965.3 4763.7 4806.0 4884.7 4888.9 4915.0 4769.0 4802.8 4813.6 4821.3 4883.6 5282.6 5315.6 5319.1 5322.2 5398.8 5512.8 5669.2 5670.7 5678.5 5681.5 5581.0 5644.2 5669.5 5688.5 5716.5 5332.5 5338.2 5339.4 5395.1 5403.1 5833.8 5837.0 5842.7 5843.9 5848.2 5446.6 5455.8 5517.8 5599.3 5611.7 5617.8 5647.9 5663.8 5733.5 5736.4 5610.1 5619.6 5623.1 5625.7 5659.0 5469.3 5577.2 5650.9 5653.0 5723.3 4334.8 4344.4 4349.9 4384.8 4416.2 3878.7 3891.8 3892.6 3932.4 3938.5 5370.7 5373.4 5379.3 5381.4 5424.9 5560.0 5560.9 5576.3 5589.9 5638.9 5298.7 5352.2 5373.2 5386.1 5435.0 4834.8 4871.9 4878.5 4922.8 4938.2 5844.5 5850.9 5856.8 5857.2 5859.0 5549.3 5619.3 5659.3 5661.5 5665.7 5804.9 5816.1 5863.0 5866.5 5874.8 5587.8 5589.2 5589.9 5595.6 5597.3 5584.6 5603.0 5619.2 5620.2 5660.7 5507.8 5528.0 5550.6 5608.5 5610.1 5531.3 5531.5 5533.8 5535.5 5536.8 5358.5 5415.6 5473.7 5484.6 5546.0 5538.6 5616.3 5633.0 5650.5 5703.1 5522.8 5523.9 5530.1 5531.0 5611.3 09:37:27 INFO - PROCESS | 11648 | 09:37:27 INFO - PROCESS | 11648 | [#1] ext.html Cycles:180 Average:56133.42 Median:19529.34 stddev:107323.00 (549.5%) stddev-sans-first:107542.95 09:37:27 INFO - PROCESS | 11648 | Values: 558.5 576.2 604.9 610.7 616.6 31075.4 42701.6 43539.0 43750.0 43817.1 42783.1 43107.1 43142.8 43205.0 43245.0 7945.3 8121.9 8830.2 8973.8 8980.5 10351.4 10635.0 10640.1 11022.3 11046.7 10709.8 10793.4 10805.6 10807.3 10819.2 10666.5 10703.6 10709.4 10731.2 10748.0 10489.4 10550.3 10551.0 10568.3 10569.7 40933.2 41206.4 41223.4 41240.8 41299.8 3380.3 3393.0 3406.4 3539.0 3582.6 31449.3 31640.0 31880.2 32763.7 33829.3 3912.6 3927.7 4054.9 4135.8 4175.4 337344.0 337899.0 338330.0 338453.0 338692.0 59414.7 59748.1 59808.0 59863.0 59892.0 36496.3 37356.4 37399.6 38888.0 38919.2 17095.4 17234.0 17238.1 17245.8 17269.7 12227.3 12487.7 12548.6 12623.2 12629.1 18327.5 18503.3 18607.0 18667.9 18679.3 6759.7 7148.5 7184.4 7216.9 7305.9 918.0 1038.6 1075.7 1076.9 1089.7 30656.4 32122.7 32504.7 32643.3 33074.3 1070.5 1121.6 1189.2 1189.3 1206.2 17619.6 18055.5 18191.5 18761.6 18985.3 483830.0 484785.0 485517.6 490053.2 493775.0 56984.0 57357.4 57415.0 57468.0 57492.7 1203.7 1255.9 1317.5 1353.7 1374.0 28318.7 33253.5 33367.8 33573.3 34080.0 48762.8 49455.8 49474.3 49529.1 49588.0 374986.0 378384.0 379517.0 379957.0 380121.0 24843.3 24900.0 25395.5 25696.9 28556.0 43312.6 44899.8 45698.3 46121.8 46365.3 38063.4 38968.0 39817.4 39908.4 40905.6 38656.2 39510.8 39953.8 40025.8 40675.6 39727.4 40063.0 40123.4 40138.4 40230.4 32957.7 35476.1 35930.8 36067.0 36296.8 27022.2 27444.4 27467.9 27494.3 27580.3 09:37:27 INFO - PROCESS | 11648 | 09:37:27 INFO - PROCESS | 11648 | [#2] jquery.html Cycles:180 Average:28681.36 Median:3059.25 stddev:78980.77 (2581.7%) stddev-sans-first:79173.76 09:37:27 INFO - PROCESS | 11648 | Values: 383.9 400.3 400.6 402.3 422.5 13328.5 15428.4 19097.0 19407.8 19880.0 15514.7 15587.6 15880.6 15900.9 19498.9 5609.6 5859.2 5867.7 5871.0 5871.8 5445.7 5688.1 5701.8 5734.5 5737.1 5691.5 5700.2 5702.1 5702.7 5706.2 5522.8 5678.3 5687.9 5708.1 5737.1 4960.2 5122.5 5131.5 5133.8 5141.3 22428.2 22619.8 22823.2 23049.6 23444.8 2254.8 2299.7 2380.0 2976.6 3141.9 13864.5 14025.6 14087.3 14271.1 14764.5 1846.3 1853.2 1896.3 1944.9 1960.3 75650.0 78395.6 78541.2 78663.2 79452.6 10279.2 10280.8 10300.8 10491.0 10860.2 14942.9 15084.8 15108.7 15167.9 15187.6 7850.9 8306.1 8338.9 8368.1 8487.0 7945.7 8007.7 8028.5 8201.1 8220.9 6380.8 6474.8 6562.4 6575.9 6651.8 1784.7 1822.3 1878.6 1881.1 1926.7 863.4 891.0 952.2 957.4 1026.7 18916.5 18921.0 19850.6 20011.6 21118.4 936.5 1006.9 1012.6 1026.3 1028.2 10557.2 10737.4 11058.2 11165.3 11177.7 465014.0 476360.0 482842.0 484084.0 485784.0 88308.0 91690.5 92658.0 93259.0 93924.5 997.3 999.5 1037.0 1044.2 1046.5 16645.9 17036.0 17593.0 17868.5 18028.4 41448.4 41728.0 42364.0 43372.8 43420.3 57660.4 59413.0 59772.7 60764.7 61253.1 8004.3 8261.7 8424.4 8518.3 9011.9 12392.1 12523.3 12625.9 12820.9 12911.5 10903.2 11267.5 11334.9 11510.6 11777.1 8196.2 10537.4 11401.2 11611.8 11969.9 8018.6 8375.7 8380.8 8447.9 8721.6 11439.7 12174.9 12608.6 12726.7 12793.8 11746.5 11874.6 11946.9 12648.9 12822.2 09:37:27 INFO - PROCESS | 11648 | 09:37:27 INFO - PROCESS | 11648 | [#3] mootools.html Cycles:180 Average:5761.63 Median:478.93 stddev:3350.14 (699.5%) stddev-sans-first:3342.95 09:37:27 INFO - PROCESS | 11648 | Values: 1325.1 1427.5 1473.2 1564.5 1702.7 7690.3 8508.2 8841.0 8878.7 9183.5 8933.7 8994.1 9007.6 9011.7 9018.7 2337.6 2363.2 2363.6 2366.9 2370.6 2288.7 2326.8 2350.8 2357.8 2359.2 2303.7 2307.0 2320.7 2338.9 2341.2 2272.9 2298.6 2310.2 2311.8 2317.1 2278.4 2280.0 2281.8 2285.2 2286.9 8281.1 8556.1 8566.9 8575.2 8855.0 5029.9 5247.3 5256.1 5259.4 5278.0 3233.3 3392.3 3415.5 3449.7 3467.7 1128.4 1161.2 1168.3 1172.8 1175.8 12773.2 12781.4 12794.6 12807.4 12841.6 4360.2 4468.9 4636.3 4641.4 4646.2 9869.3 9884.8 9889.6 9898.1 9904.8 5120.8 5123.9 5126.4 5142.9 5156.6 4275.6 4338.7 4388.3 4711.6 4860.6 3772.5 3775.9 3891.4 3921.6 3935.4 1170.3 1191.3 1197.3 1205.7 1206.8 913.7 964.2 972.7 981.2 998.6 5840.5 5841.7 5873.8 6025.1 6072.6 461.3 474.3 476.6 481.2 483.2 4073.9 4081.7 4097.8 4104.9 4141.3 10755.5 11318.7 11319.3 11339.6 11374.0 11157.2 11178.0 11180.7 11185.6 11194.6 7799.8 8106.1 8109.4 8286.3 8375.1 6000.9 6028.3 6045.8 6159.0 6246.6 10084.2 10099.3 10116.3 10117.7 10228.1 9851.3 10244.4 10257.8 10591.7 10604.5 7133.8 7321.9 7762.2 7925.2 7936.3 8163.1 8282.8 8362.9 8540.2 8560.4 7315.6 7434.3 7515.2 7567.2 7608.4 7152.6 7231.7 7238.7 7251.5 7356.0 6755.9 6759.4 6786.1 6795.2 6833.4 5300.8 5300.9 5312.9 5376.5 5377.9 4974.1 5025.4 5030.9 5058.9 5068.0 09:37:27 INFO - PROCESS | 11648 | 09:37:27 INFO - PROCESS | 11648 | [#4] prototype.html Cycles:180 Average:4592.91 Median:5234.62 stddev:1340.55 (25.6%) stddev-sans-first:1305.60 09:37:27 INFO - PROCESS | 11648 | Values: 332.0 340.0 347.5 347.9 349.5 5309.4 5329.0 5333.6 5338.3 5341.1 5246.3 5261.7 5290.6 5293.0 5296.7 4064.2 4086.7 4119.7 4192.0 4200.0 4132.9 4137.1 4140.8 4144.0 4147.6 4070.1 4091.9 4098.8 4106.8 4111.2 3930.1 4041.4 4048.9 4050.4 4117.8 3663.2 3663.6 3678.3 3681.5 3682.8 5047.8 5086.3 5095.0 5104.3 5151.0 4366.3 4474.2 4643.7 4677.5 4749.2 5453.0 5461.2 5466.6 5470.0 5484.9 3980.1 3983.6 3986.5 3986.9 3988.4 5419.6 5654.9 5666.4 5697.7 5704.7 4033.3 4037.6 4048.0 4054.5 4059.1 4075.1 4086.9 4095.2 4096.0 4103.2 4454.5 4650.3 4667.7 4679.0 4684.4 5489.6 5498.5 5517.5 5519.2 5527.6 1400.5 1423.7 1423.7 1424.7 1426.6 863.5 914.2 920.4 926.9 931.3 4704.6 4708.6 4744.5 4756.6 4762.5 4982.3 4986.7 4996.3 5006.5 5008.0 4747.0 4747.5 4768.4 4773.4 4954.5 2966.7 3009.6 3014.0 3014.8 3014.9 5845.4 5846.6 5852.8 5857.4 5873.9 5547.8 5758.3 5778.5 5785.6 5797.5 5790.9 5791.4 5819.0 5826.0 5828.5 5444.0 5608.7 5641.5 5660.5 5662.3 5604.2 5630.3 5633.3 5635.4 5638.5 5818.0 5852.7 5890.0 5894.6 5905.0 3897.6 4053.4 4075.1 4088.6 4121.7 5598.4 5607.6 5610.0 5613.0 5622.7 5430.2 5431.7 5461.7 5479.9 5488.3 5223.0 5458.8 5472.2 5477.9 5480.9 5415.9 5437.7 5453.5 5456.9 5463.7 5291.4 5431.0 5465.6 5579.0 5585.2 5404.2 5462.8 5463.6 5468.2 5476.0 09:37:27 INFO - PROCESS | 11648 | 09:37:27 INFO - PROCESS | 11648 | [#5] yui.html Cycles:180 Average:21998.58 Median:3905.12 stddev:33459.60 (856.8%) stddev-sans-first:33515.16 09:37:27 INFO - PROCESS | 11648 | Values: 675.3 679.6 681.4 704.7 780.0 15373.5 15915.8 16203.6 16518.1 16550.0 14013.1 14077.3 14078.9 14101.0 14109.3 659.4 769.9 785.5 787.7 788.0 663.3 668.1 668.4 764.4 770.2 811.8 824.4 825.1 825.9 825.9 813.4 813.4 814.5 814.7 814.9 498.4 505.9 583.3 597.1 601.1 14182.5 14754.3 14767.1 14767.9 14783.1 13350.4 13505.8 13786.7 13788.5 14088.5 11893.3 11916.3 11934.3 12058.3 12143.8 3654.7 4155.5 4171.5 4339.3 4358.4 74759.0 75426.2 75532.8 75591.6 75629.0 10903.0 10951.2 11015.2 11019.8 11089.9 20155.3 20274.3 20397.8 20458.9 21091.8 7772.6 8119.3 8131.6 8200.0 8601.2 4617.9 4661.2 4695.7 4717.3 4747.6 3197.1 3316.6 3317.2 3322.1 3324.3 588.4 594.5 597.4 601.4 601.7 552.4 619.9 702.3 702.8 718.4 9878.4 10200.3 10290.6 10364.9 10417.0 636.5 696.9 699.2 702.9 706.5 4850.8 4873.6 4920.8 4934.3 4935.1 128068.0 129848.4 130924.0 131674.3 135695.0 109395.0 111182.4 111998.4 114645.0 114927.0 95882.0 95927.0 98241.5 99624.0 100147.0 9221.6 9381.9 9546.8 9585.1 9607.6 51395.5 51714.0 52347.2 53064.0 54436.5 75855.4 79895.2 81356.0 82196.9 85760.6 12218.8 12317.6 12425.6 12793.4 12913.4 13644.3 13779.7 14162.6 14186.8 14424.8 12038.5 12076.0 12176.0 12284.2 12410.7 11176.9 12806.6 13064.3 13299.0 13589.8 13296.7 13706.2 13737.2 13936.0 14500.1 11053.8 11120.4 11275.2 11306.8 11831.4 10909.4 11341.6 11582.8 11606.4 11626.5 09:37:27 INFO - PROCESS | 11648 | -------- Summary: end -------- 09:37:27 INFO - PROCESS | 11648 | 09:37:28 INFO - TEST-INFO | 11648: exit 0 09:37:28 INFO - TEST-OK | dromaeo_css | took 1115545ms 09:37:28 INFO - TEST-START | kraken 09:37:28 INFO - operating with platform_type : linux_ 09:37:28 INFO - Initialising browser for kraken test... 09:37:28 INFO - TEST-INFO | started process 11955 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFHe_h4/profile http://localhost:33630/getInfo.html) 09:37:32 INFO - PROCESS | 11955 | __metrics Screen width/height:1600/1200 09:37:32 INFO - PROCESS | 11955 | colorDepth:24 09:37:32 INFO - PROCESS | 11955 | Browser inner width/height: 1024/697 09:37:32 INFO - PROCESS | 11955 | __metrics 09:37:32 INFO - PROCESS | 11955 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:37:32 INFO - PROCESS | 11955 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:37:33 INFO - PROCESS | 11955 | console.error: PushService: 09:37:33 INFO - PROCESS | 11955 | stateChangeProcessEnqueue: Error transitioning state 09:37:33 INFO - PROCESS | 11955 | UnknownError 09:37:34 INFO - TEST-INFO | 11955: exit 0 09:37:34 INFO - Browser initialized. 09:37:34 INFO - Running cycle 1/1 for kraken test... 09:37:34 INFO - TEST-INFO | started process 12039 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFHe_h4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:37:46 INFO - PROCESS | 12039 | RSS: Main: 173748224 09:37:46 INFO - PROCESS | 12039 | 09:37:48 INFO - PROCESS | 12039 | Cycle 1(1): loaded http://localhost:33630/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33630/tests/kraken/driver.html?testName=audio-beat-detection) 09:37:49 INFO - PROCESS | 12039 | RSS: Main: 240947200 09:37:49 INFO - PROCESS | 12039 | 09:37:52 INFO - PROCESS | 12039 | Cycle 1(1): loaded http://localhost:33630/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33630/tests/kraken/driver.html?testName=audio-dft) 09:37:52 INFO - PROCESS | 12039 | RSS: Main: 216678400 09:37:52 INFO - PROCESS | 12039 | 09:37:56 INFO - PROCESS | 12039 | Cycle 1(1): loaded http://localhost:33630/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33630/tests/kraken/driver.html?testName=audio-fft) 09:37:56 INFO - PROCESS | 12039 | RSS: Main: 298414080 09:37:56 INFO - PROCESS | 12039 | 09:37:58 INFO - PROCESS | 12039 | Cycle 1(1): loaded http://localhost:33630/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33630/tests/kraken/driver.html?testName=audio-oscillator) 09:37:59 INFO - PROCESS | 12039 | RSS: Main: 208584704 09:37:59 INFO - PROCESS | 12039 | 09:38:01 INFO - PROCESS | 12039 | Cycle 1(1): loaded http://localhost:33630/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33630/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:38:02 INFO - PROCESS | 12039 | RSS: Main: 213164032 09:38:02 INFO - PROCESS | 12039 | 09:38:05 INFO - PROCESS | 12039 | Cycle 1(1): loaded http://localhost:33630/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33630/tests/kraken/driver.html?testName=imaging-darkroom) 09:38:05 INFO - PROCESS | 12039 | RSS: Main: 249516032 09:38:05 INFO - PROCESS | 12039 | 09:38:09 INFO - PROCESS | 12039 | Cycle 1(1): loaded http://localhost:33630/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33630/tests/kraken/driver.html?testName=imaging-desaturate) 09:38:09 INFO - PROCESS | 12039 | RSS: Main: 248086528 09:38:09 INFO - PROCESS | 12039 | 09:38:12 INFO - PROCESS | 12039 | Cycle 1(1): loaded http://localhost:33630/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33630/tests/kraken/driver.html?testName=json-parse-financial) 09:38:13 INFO - PROCESS | 12039 | RSS: Main: 248524800 09:38:13 INFO - PROCESS | 12039 | 09:38:14 INFO - PROCESS | 12039 | Cycle 1(1): loaded http://localhost:33630/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33630/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:38:15 INFO - PROCESS | 12039 | RSS: Main: 210415616 09:38:15 INFO - PROCESS | 12039 | 09:38:16 INFO - PROCESS | 12039 | Cycle 1(1): loaded http://localhost:33630/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33630/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:38:17 INFO - PROCESS | 12039 | RSS: Main: 217833472 09:38:17 INFO - PROCESS | 12039 | 09:38:19 INFO - PROCESS | 12039 | Cycle 1(1): loaded http://localhost:33630/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33630/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:38:20 INFO - PROCESS | 12039 | RSS: Main: 222056448 09:38:20 INFO - PROCESS | 12039 | 09:38:22 INFO - PROCESS | 12039 | Cycle 1(1): loaded http://localhost:33630/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33630/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:38:23 INFO - PROCESS | 12039 | RSS: Main: 223809536 09:38:23 INFO - PROCESS | 12039 | 09:38:26 INFO - PROCESS | 12039 | Cycle 1(1): loaded http://localhost:33630/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33630/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:38:26 INFO - PROCESS | 12039 | RSS: Main: 220921856 09:38:26 INFO - PROCESS | 12039 | 09:38:28 INFO - PROCESS | 12039 | Cycle 1(1): loaded http://localhost:33630/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33630/tests/kraken/driver.html?testName=ai-astar) 09:38:28 INFO - PROCESS | 12039 | RSS: Main: 272556032 09:38:28 INFO - PROCESS | 12039 | 09:38:28 INFO - PROCESS | 12039 | __start_tp_report 09:38:28 INFO - PROCESS | 12039 | _x_x_mozilla_page_load 09:38:28 INFO - PROCESS | 12039 | _x_x_mozilla_page_load_details 09:38:28 INFO - PROCESS | 12039 | |i|pagename|runs| 09:38:28 INFO - PROCESS | 12039 | |0;ai-astar;111;112;113;114;114;112;112;113;112;112 09:38:28 INFO - PROCESS | 12039 | |1;audio-beat-detection;157;117;118;161;120;123;119;119;152;118 09:38:28 INFO - PROCESS | 12039 | |2;audio-dft;162;161;161;162;160;161;161;163;162;161 09:38:28 INFO - PROCESS | 12039 | |3;audio-fft;74;75;75;123;76;76;75;75;113;76 09:38:28 INFO - PROCESS | 12039 | |4;audio-oscillator;84;82;129;82;82;82;102;82;82;115 09:38:28 INFO - PROCESS | 12039 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;116;116;117 09:38:28 INFO - PROCESS | 12039 | |6;imaging-darkroom;146;147;147;146;147;147;146;145;146;146 09:38:28 INFO - PROCESS | 12039 | |7;imaging-desaturate;95;95;95;95;95;94;95;96;100;95 09:38:28 INFO - PROCESS | 12039 | |8;json-parse-financial;53;52;51;52;54;52;52;52;51;52 09:38:28 INFO - PROCESS | 12039 | |9;json-stringify-tinderbox;60;60;59;60;62;63;59;60;59;60 09:38:28 INFO - PROCESS | 12039 | |10;stanford-crypto-aes;88;81;78;78;79;80;78;78;78;78 09:38:28 INFO - PROCESS | 12039 | |11;stanford-crypto-ccm;135;124;122;128;122;134;132;123;164;133 09:38:28 INFO - PROCESS | 12039 | |12;stanford-crypto-pbkdf2;171;168;166;167;164;168;163;161;172;168 09:38:28 INFO - PROCESS | 12039 | |13;stanford-crypto-sha256-iterative;65;66;65;66;66;65;65;67;64;64 09:38:28 INFO - PROCESS | 12039 | __end_tp_report 09:38:28 INFO - PROCESS | 12039 | __start_cc_report 09:38:28 INFO - PROCESS | 12039 | _x_x_mozilla_cycle_collect,1651 09:38:28 INFO - PROCESS | 12039 | __end_cc_report 09:38:28 INFO - PROCESS | 12039 | __startTimestamp1474994308851__endTimestamp 09:38:28 INFO - PROCESS | 12039 | 09:38:28 INFO - PROCESS | 12039 | ------- Summary: start ------- 09:38:28 INFO - PROCESS | 12039 | Number of tests: 14 09:38:28 INFO - PROCESS | 12039 | 09:38:28 INFO - PROCESS | 12039 | [#0] ai-astar Cycles:10 Average:112.50 Median:112.50 stddev:0.97 (0.9%) stddev-sans-first:0.87 09:38:28 INFO - PROCESS | 12039 | Values: 111.0 112.0 113.0 114.0 114.0 112.0 112.0 113.0 112.0 112.0 09:38:28 INFO - PROCESS | 12039 | 09:38:28 INFO - PROCESS | 12039 | [#1] audio-beat-detection Cycles:10 Average:130.40 Median:121.50 stddev:18.32 (15.1%) stddev-sans-first:16.71 09:38:28 INFO - PROCESS | 12039 | Values: 157.0 117.0 118.0 161.0 120.0 123.0 119.0 119.0 152.0 118.0 09:38:28 INFO - PROCESS | 12039 | 09:38:28 INFO - PROCESS | 12039 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.50 stddev:0.84 (0.5%) stddev-sans-first:0.87 09:38:28 INFO - PROCESS | 12039 | Values: 162.0 161.0 161.0 162.0 160.0 161.0 161.0 163.0 162.0 161.0 09:38:28 INFO - PROCESS | 12039 | 09:38:28 INFO - PROCESS | 12039 | [#3] audio-fft Cycles:10 Average:83.80 Median:75.00 stddev:18.19 (24.3%) stddev-sans-first:18.94 09:38:28 INFO - PROCESS | 12039 | Values: 74.0 75.0 75.0 123.0 76.0 76.0 75.0 75.0 113.0 76.0 09:38:28 INFO - PROCESS | 12039 | 09:38:28 INFO - PROCESS | 12039 | [#4] audio-oscillator Cycles:10 Average:92.20 Median:82.00 stddev:17.20 (21.0%) stddev-sans-first:17.98 09:38:28 INFO - PROCESS | 12039 | Values: 84.0 82.0 129.0 82.0 82.0 82.0 102.0 82.0 82.0 115.0 09:38:28 INFO - PROCESS | 12039 | 09:38:28 INFO - PROCESS | 12039 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 09:38:28 INFO - PROCESS | 12039 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 116.0 117.0 09:38:28 INFO - PROCESS | 12039 | 09:38:28 INFO - PROCESS | 12039 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.50 stddev:0.67 (0.5%) stddev-sans-first:0.71 09:38:28 INFO - PROCESS | 12039 | Values: 146.0 147.0 147.0 146.0 147.0 147.0 146.0 145.0 146.0 146.0 09:38:28 INFO - PROCESS | 12039 | 09:38:28 INFO - PROCESS | 12039 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.00 stddev:1.65 (1.7%) stddev-sans-first:1.74 09:38:28 INFO - PROCESS | 12039 | Values: 95.0 95.0 95.0 95.0 95.0 94.0 95.0 96.0 100.0 95.0 09:38:28 INFO - PROCESS | 12039 | 09:38:28 INFO - PROCESS | 12039 | [#8] json-parse-financial Cycles:10 Average:52.10 Median:52.00 stddev:0.88 (1.7%) stddev-sans-first:0.87 09:38:28 INFO - PROCESS | 12039 | Values: 53.0 52.0 51.0 52.0 54.0 52.0 52.0 52.0 51.0 52.0 09:38:28 INFO - PROCESS | 12039 | 09:38:28 INFO - PROCESS | 12039 | [#9] json-stringify-tinderbox Cycles:10 Average:60.20 Median:60.00 stddev:1.32 (2.2%) stddev-sans-first:1.39 09:38:28 INFO - PROCESS | 12039 | Values: 60.0 60.0 59.0 60.0 62.0 63.0 59.0 60.0 59.0 60.0 09:38:28 INFO - PROCESS | 12039 | 09:38:28 INFO - PROCESS | 12039 | [#10] stanford-crypto-aes Cycles:10 Average:79.60 Median:78.50 stddev:3.13 (4.0%) stddev-sans-first:1.12 09:38:28 INFO - PROCESS | 12039 | Values: 88.0 81.0 78.0 78.0 79.0 80.0 78.0 78.0 78.0 78.0 09:38:28 INFO - PROCESS | 12039 | 09:38:28 INFO - PROCESS | 12039 | [#11] stanford-crypto-ccm Cycles:10 Average:131.70 Median:132.50 stddev:12.46 (9.4%) stddev-sans-first:13.16 09:38:28 INFO - PROCESS | 12039 | Values: 135.0 124.0 122.0 128.0 122.0 134.0 132.0 123.0 164.0 133.0 09:38:28 INFO - PROCESS | 12039 | 09:38:28 INFO - PROCESS | 12039 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.80 Median:168.00 stddev:3.43 (2.0%) stddev-sans-first:3.28 09:38:28 INFO - PROCESS | 12039 | Values: 171.0 168.0 166.0 167.0 164.0 168.0 163.0 161.0 172.0 168.0 09:38:28 INFO - PROCESS | 12039 | 09:38:28 INFO - PROCESS | 12039 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.30 Median:65.50 stddev:0.95 (1.4%) stddev-sans-first:1.00 09:38:28 INFO - PROCESS | 12039 | Values: 65.0 66.0 65.0 66.0 66.0 65.0 65.0 67.0 64.0 64.0 09:38:28 INFO - PROCESS | 12039 | -------- Summary: end -------- 09:38:28 INFO - PROCESS | 12039 | 09:38:29 INFO - TEST-INFO | 12039: exit 0 09:38:29 INFO - TEST-OK | kraken | took 61593ms 09:38:29 INFO - SUITE-END | took 1177s 09:38:30 INFO - Completed test suite (00:19:37) 09:38:30 INFO - javascript benchmark 09:38:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3014.7136022077907, 3044.3606842563063, 3050.4356694011603, 3086.0741777466783, 3095.962852444431, 5223.947760522394, 5234.633575649701, 5297.814576489819, 5336.306280183576, 5346.599005074616, 5183.041137389586, 5227.712475813829, 5235.293235413218, 5323.707196104213, 5343.0, 4789.7365644889605, 4812.374391329122, 4816.62726123055, 4827.493113223114, 4937.65436419449, 4900.338454308659, 4917.237828136645, 4933.1120398328285, 4945.851624451272, 4957.801687932497, 4872.853814385574, 4918.852434426955, 4921.434035085975, 4924.187509061001, 4965.255211718253, 4763.666543341968, 4806.014766972762, 4884.682495637808, 4888.926666100011, 4915.041566894457, 4768.952310476886, 4802.807887684488, 4813.638977076734, 4821.300911367867, 4883.584895283905, 5282.603804714662, 5315.627906046579, 5319.095753721838, 5322.2282768998275, 5398.838034858922, 5512.80705175321, 5669.206311116447, 5670.68811215383, 5678.488935995739, 5681.460261275172, 5580.972095139539, 5644.181593668936, 5669.518090962273, 5688.544916406719, 5716.514096301819, 5332.466753324714, 5338.199270109437, 5339.385970613429, 5395.05586522357, 5403.108487099624, 5833.82498525045, 5836.970815145897, 5842.707864606838, 5843.853903652443, 5848.210491583582, 5446.618736688473, 5455.809046683347, 5517.806876759374, 5599.32808063035, 5611.691356975324, 5617.831465056055, 5647.858803529944, 5663.801766938137, 5733.483986441241, 5736.368999410061, 5610.13042978339, 5619.606627536034, 5623.128415095667, 5625.71871406428, 5659.037963546207, 5469.288992430958, 5577.163425486209, 5650.886982260332, 5653.0, 5723.255976723082, 4334.761588112621, 4344.370066340363, 4349.891252718708, 4384.780760961939, 4416.24923762961, 3878.670313023368, 3891.766494010312, 3892.5718171001167, 3932.3511620582285, 3938.487996560486, 5370.677759334452, 5373.408925018245, 5379.327584051994, 5381.434949330264, 5424.864378390572, 5559.972200139055, 5560.944390556124, 5576.302962129734, 5589.888202235932, 5638.85902852432, 5298.655587386885, 5352.197170424468, 5373.247745315659, 5386.084365657847, 5435.0, 4834.7824347904425, 4871.853844384674, 4878.463369029404, 4922.778474968635, 4938.234573641092, 5844.503217226498, 5850.912236316459, 5856.824295271149, 5857.15071314657, 5858.970705146447, 5549.250851135206, 5619.297587801525, 5659.292588426447, 5661.547076233809, 5665.745041473226, 5804.941950580609, 5816.0694288915265, 5862.970685146547, 5866.530677545701, 5874.794382196603, 5587.832365029056, 5589.189567512606, 5589.916151257653, 5595.63628364155, 5597.3003374578175, 5584.609077364708, 5603.0, 5619.185218143428, 5620.18507316445, 5660.660360378391, 5507.807226747044, 5528.032594295902, 5550.583706221969, 5608.523275521627, 5610.102383618601, 5531.280933478622, 5531.474509921551, 5533.806316779054, 5535.501804837584, 5536.806211782729, 5358.490943360374, 5415.566754659539, 5473.698946557977, 5484.575566260709, 5545.972270138624, 5538.6399884007415, 5616.297962754656, 5633.0, 5650.491455769001, 5703.058995265735, 5522.834314970557, 5523.917141242804, 5530.142827861688, 5530.972345138249, 5611.3266408031295], "value": 5234.08031622859, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.501878380249, 576.2048373244922, 604.9395060493952, 610.6702380714418, 616.5622408090255, 31075.378492430165, 42701.57298427015, 43539.0, 43750.0, 43817.12365752683, 42783.144337113314, 43107.13785724284, 43142.784286078524, 43205.0, 43245.0, 7945.284924356807, 8121.918780812175, 8830.24942879856, 8973.775655608597, 8980.46117232965, 10351.37891726495, 10635.0, 10640.0955918747, 11022.283551569162, 11046.668599942015, 10709.785804283909, 10793.352398856094, 10805.5677772889, 10807.297525660806, 10819.242653014293, 10666.466676666174, 10703.625373111981, 10709.410982395964, 10731.248812583122, 10747.999999999962, 10489.423081730502, 10550.314229575053, 10551.0, 10568.260221784478, 10569.735756606146, 40933.181336373105, 41206.38190427146, 41223.38164927528, 41240.793796030826, 41299.793501032604, 3380.2732412531423, 3392.9830350848333, 3406.3527929693278, 3539.026767638894, 3582.570091589026, 31449.31768572458, 31640.0, 31880.202994925312, 32763.67236327654, 33829.323413531594, 3912.6478616924614, 3927.6661483773914, 4054.9051756026, 4135.7518548887165, 4175.3945677876845, 337344.0, 337899.0, 338330.0, 338453.0, 338692.0, 59414.70292648509, 59748.10377844249, 59808.0, 59863.02054691744, 59892.0, 36496.27007459836, 37356.43965340522, 37399.62600374016, 38888.0, 38919.221615568094, 17095.401660942058, 17234.0, 17238.051907144978, 17245.827541724422, 17269.654606908043, 12227.327496987751, 12487.687807804696, 12548.560800371943, 12623.242605443518, 12629.052821038458, 18327.450176494727, 18503.259869605066, 18607.0, 18667.90666046661, 18679.346222882134, 6759.729610815611, 7148.499605027599, 7184.389326907271, 7216.855662886818, 7305.926940730631, 917.9908200918039, 1038.6053299746047, 1075.725689949058, 1076.8923107689168, 1089.727568107973, 30656.38687226243, 32122.67877321197, 32504.674953250164, 32643.347133057207, 33074.3385132296, 1070.4915165296422, 1121.61864965912, 1189.167582692119, 1189.2923710392304, 1206.1556910162922, 17619.647607048042, 18055.452425857864, 18191.545211369557, 18761.624767504574, 18985.33551325697, 483830.0, 484785.0, 485517.5792968312, 490053.19893601927, 493775.0, 56984.0, 57357.42642573521, 57415.0, 57468.0, 57492.71253643705, 1203.6750077479207, 1255.8869701726887, 1317.4993502468808, 1353.7360214758226, 1374.0313079279085, 28318.716812831608, 33253.50119748157, 33367.83316083404, 33573.32853342968, 34080.0, 48762.756186218845, 49455.75272123616, 49474.25788613246, 49529.08476474269, 49588.0, 374986.0, 378384.0, 379517.0, 379957.0, 380121.0, 24843.254702358958, 24900.0, 25395.492090158463, 25696.871515642302, 28556.0, 43312.566874332115, 44899.77550112228, 45698.31452528148, 46121.769391152826, 46365.30452043154, 38063.42904856374, 38968.0, 39817.40273895952, 39908.40137397999, 40905.59094409137, 38656.2268754629, 39510.80244598759, 39953.80023099866, 40025.79987100046, 40675.59324406718, 39727.40408893926, 40063.0, 40123.39814902837, 40138.39792403058, 40230.39654405245, 32957.67042329546, 35476.113097172776, 35930.8203458981, 36067.0, 36296.81851590725, 27022.189334320003, 27444.413340797666, 27467.86266068657, 27494.312642183304, 27580.310492237855], "value": 19860.884280816612, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.93665045267534, 400.2834925483385, 400.63342042031536, 402.3160626934212, 422.53943201909914, 13328.466861325547, 15428.382864685413, 19097.0, 19407.805921940777, 19880.0, 15514.689706205869, 15587.610309742262, 15880.602984925383, 15900.920495397508, 19498.902505487487, 5609.607327487078, 5859.209006784094, 5867.735951882176, 5871.031279838821, 5871.765129394819, 5445.673259604417, 5688.146777983295, 5701.800436984707, 5734.541236701075, 5737.053386191274, 5691.51622112121, 5700.23046888671, 5702.059160238577, 5702.686352250625, 5706.201131841545, 5522.751476183561, 5678.34699009613, 5687.857803554903, 5708.08670612702, 5737.110747834093, 4960.23116416956, 5122.538971492546, 5131.486851314876, 5133.768980395854, 5141.303369887928, 22428.215012474648, 22619.773802262094, 22823.201187958508, 23049.617022978677, 23444.76555234443, 2254.819614430858, 2299.66654835048, 2379.9881000594887, 2976.6130403047464, 3141.8900338488043, 13864.514741984083, 14025.579232622935, 14087.295635218301, 14271.14373137615, 14764.48324308644, 1846.3353192850564, 1853.2401715296664, 1896.2888916656254, 1944.931927382549, 1960.323688327539, 75650.0, 78395.6080219601, 78541.21458785396, 78663.21336786616, 79452.60273698595, 10279.229057820543, 10280.794384112276, 10300.845487317696, 10491.0, 10860.239783215098, 14942.850571494146, 15084.773728394082, 15108.697826043417, 15167.924160379127, 15187.620309492351, 7850.921490785133, 8306.127856575093, 8338.916610833936, 8368.121347258571, 8487.0, 7945.721899733481, 8007.674896193966, 8028.4781489203015, 8201.13888041759, 8220.876686849702, 6380.840478988062, 6474.773382931575, 6562.409383155539, 6575.868482630321, 6651.800445986628, 1784.7412125241767, 1822.2893071702047, 1878.5867109235946, 1881.1346780480858, 1926.7206255092933, 863.3611127765533, 890.9688160914337, 952.1525842000625, 957.4255446731905, 1026.7022563456662, 18916.527086822938, 18921.0, 19850.60298794016, 20011.59976800456, 21118.366449006553, 936.5176933879058, 1006.8842083160383, 1012.6152062216458, 1026.2816028779885, 1028.187731691955, 10557.208209384327, 10737.409442480583, 11058.170637202242, 11165.330080195214, 11177.664670059748, 465014.0, 476359.9704549535, 482842.0, 484084.0, 485784.0, 88308.0, 91690.54154729316, 92658.0, 93259.0, 93924.53037734768, 997.3118548201718, 999.4852650884945, 1036.9792604148026, 1044.2272718188638, 1046.5186014433446, 16645.91677041607, 17036.0, 17593.0, 17868.46394608164, 18028.36900708469, 41448.37827432651, 41728.0, 42364.0, 43372.783136084116, 43420.348694768974, 57660.42339576551, 59413.0, 59772.70113649578, 60764.6961765206, 61253.08120378109, 8004.279614834692, 8261.710840120568, 8424.410291279552, 8518.275946544612, 9011.944624918682, 12392.132550721362, 12523.311217883103, 12625.936870315589, 12820.871791281968, 12911.54680610681, 10903.2367734261, 11267.549298027987, 11334.94332528332, 11510.59712910044, 11777.057835373322, 8196.188772220947, 10537.367757934548, 11401.201915866142, 11611.767764644828, 11969.940150299193, 8018.558979256197, 8375.748727537939, 8380.815648974793, 8447.8732819009, 8721.56392180401, 11439.714007149887, 12174.939125304316, 12608.558700445441, 12726.68183295425, 12793.8080928788, 11746.471408786454, 11874.643760687193, 11946.880531194924, 12648.873511265137, 12822.166559173624], "value": 9279.886043327895, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1325.0923117664395, 1427.5075099090816, 1473.1676602719465, 1564.4915402494191, 1702.7105392083345, 7690.26942440468, 8508.234258916696, 8841.0, 8878.689245876396, 9183.540822958843, 8933.731988040368, 8994.055624159451, 9007.59465824038, 9011.684591039315, 9018.68434604789, 2337.6142936415513, 2363.2083252110565, 2363.61000434928, 2366.8698221597806, 2370.5851475991717, 2288.668143119247, 2326.7556906524824, 2350.753170917055, 2357.8467399619058, 2359.221456919221, 2303.67748515208, 2307.042577330406, 2320.6751054852334, 2338.859668419892, 2341.192288660418, 2272.8977196026217, 2298.5632729781287, 2310.249169020067, 2311.768823117683, 2317.1194945920442, 2278.384836094262, 2280.0, 2281.8060464860505, 2285.211601997325, 2286.942826429336, 8281.089080201173, 8556.144385561456, 8566.914330856736, 8575.185357391038, 8854.955725221333, 5029.899402011939, 5247.317848679685, 5256.08018596744, 5259.395169555473, 5278.0, 3233.304839459504, 3392.2537041850755, 3415.470602056685, 3449.6722811332884, 3467.7225821934435, 1128.3963079752375, 1161.2393882007298, 1168.275669085164, 1172.78889799836, 1175.7883580955424, 12773.169743966706, 12781.360931953459, 12794.552190673376, 12807.359632018455, 12841.614751557468, 4360.236958532244, 4468.910621787546, 4636.304554316879, 4641.443026836801, 4646.163690535668, 9869.309148359689, 9884.752881178028, 9889.554970026222, 9898.10917017472, 9904.752381190528, 5120.795168193231, 5123.87190320245, 5126.384833819965, 5142.897142057138, 5156.63903526757, 4275.615194632498, 4338.698390482842, 4388.27593447084, 4711.646626503026, 4860.562549370574, 3772.5095737553943, 3775.867844625425, 3891.3579259422445, 3921.647051765298, 3935.4293627423863, 1170.309517384747, 1191.3090407563593, 1197.3055627735891, 1205.6804946689135, 1206.7827790997528, 913.7395842184945, 964.233434419624, 972.6692924405736, 981.239539357004, 998.6005597760953, 5840.474357307778, 5841.707914604338, 5873.823785286449, 6025.0661147522205, 6072.574919755663, 461.3333732756202, 474.25305144397714, 476.60441833278986, 481.24684868181185, 483.193067577147, 4073.877783666495, 4081.673466122703, 4097.795110244476, 4104.856330028435, 4141.254574176677, 10755.516001779783, 11318.660440186808, 11319.320840749582, 11339.603113891138, 11374.0, 11157.163212758913, 11178.0498657615, 11180.720481988015, 11185.608503702331, 11194.608188713519, 7799.805004874924, 8106.067802202822, 8109.351251899951, 8286.295664868554, 8375.078741338448, 6000.8799824004145, 6028.306744724413, 6045.78839740607, 6158.969205153946, 6246.562740608114, 10084.193264538966, 10099.343542669703, 10116.29185956976, 10117.696469105938, 10228.07947284748, 9851.261155413229, 10244.385336879513, 10257.794844103226, 10591.682249532527, 10604.469776511298, 7133.750318738819, 7321.890171647323, 7762.223777622193, 7925.167857375123, 7936.2857342839425, 8163.1428699985645, 8282.792930176794, 8362.916370836214, 8540.231379175904, 8560.357973152162, 7315.6342182889575, 7434.293742094493, 7515.173330933702, 7567.205443428581, 7608.39132869358, 7152.640998210323, 7231.674574644048, 7238.746643867439, 7251.5286506376915, 7355.963220183865, 6755.898661520179, 6759.425448836707, 6786.0838786765125, 6795.218549866829, 6833.4191593715095, 5300.80016793042, 5300.893982120413, 5312.856594728957, 5376.489233522809, 5377.865553361041, 4974.104661161025, 5025.3718285214345, 5030.874228144326, 5058.9494105058475, 5068.0], "value": 4521.389481925678, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.9983400082999, 340.04617049177057, 347.49613061061467, 347.88693674555776, 349.4792758789404, 5309.362876454826, 5328.973355133224, 5333.626646134767, 5338.27933229014, 5341.1187154119525, 5246.291750613666, 5261.710605916673, 5290.576753859682, 5293.0, 5296.708681022542, 4064.2074795414906, 4086.7139300248996, 4119.732217405874, 4191.958080419195, 4200.0, 4132.917341653165, 4137.131202447475, 4140.805373152234, 4143.979280103596, 4147.5645057269, 4070.124923141524, 4091.897702557445, 4098.8360465581345, 4106.835726570934, 4111.239420707164, 3930.0960779020697, 4041.3937909313545, 4048.8987775305854, 4050.3721923101966, 4117.773522456264, 3663.230721548478, 3663.56037275526, 3678.282734866702, 3681.4597927429986, 3682.834272457746, 5047.788530752629, 5086.262491938685, 5094.974525127351, 5104.25988231708, 5151.021305951895, 4366.323219900889, 4474.2393793054935, 4643.698159619647, 4677.508861569554, 4749.168895443284, 5453.0, 5461.208124821878, 5466.617336786427, 5470.0, 5484.862878428032, 3980.0845805464614, 3983.621555952209, 3986.481757371523, 3986.86702614103, 3988.441618173458, 5419.620626556142, 5654.85862853432, 5666.433356664342, 5697.743601537941, 5704.71476426177, 4033.3143365627916, 4037.5962403759977, 4048.028473166478, 4054.5134583850127, 4059.106996460774, 4075.144219713893, 4086.8569600063856, 4095.2014357200655, 4095.9590404096175, 4103.179364127186, 4454.465464144323, 4650.279206722963, 4667.743274119888, 4679.040796636681, 4684.414448193976, 5489.64317319373, 5498.51540084183, 5517.531009864207, 5519.172124181325, 5527.613067085265, 1400.4538230090272, 1423.665438621923, 1423.686788906439, 1424.7221791750717, 1426.6290764401124, 863.451708165307, 914.1635403605759, 920.40173886974, 926.9304802139867, 931.3015238571072, 4704.553067458654, 4708.599769019604, 4744.454387745384, 4756.5956893664425, 4762.499687663935, 4982.327385802871, 4986.678530189502, 4996.325496058058, 5006.549410553068, 5007.974960125249, 4747.0, 4747.454042785055, 4768.380110585671, 4773.427188737373, 4954.529319714621, 2966.6736658967498, 3009.593704849861, 3013.9547906781418, 3014.80403773754, 3014.8643311051055, 5845.415458454206, 5846.561507886926, 5852.795152169653, 5857.414258574108, 5873.911891321633, 5547.805826796042, 5758.3090029196765, 5778.5377169826315, 5785.623934444247, 5797.536197104221, 5790.942090579125, 5791.362950075489, 5819.039858423313, 5825.970870145622, 5828.475437210672, 5443.972780136074, 5608.691521966168, 5641.548676105819, 5660.547156227574, 5662.301309607019, 5604.215409842707, 5630.254621067482, 5633.324001119728, 5635.408282130481, 5638.4925356718095, 5817.970910145422, 5852.678102704393, 5890.0, 5894.616849904743, 5905.0, 3897.629725176104, 4053.3717273822604, 4075.1034772350035, 4088.6320231178147, 4121.711480196357, 5598.356189038313, 5607.579431542569, 5610.0, 5613.0, 5622.662640241435, 5430.185472179205, 5431.6740995540395, 5461.726913654222, 5479.917801232905, 5488.259085023471, 5222.955408918156, 5458.781648734006, 5472.151816468454, 5477.863053423696, 5480.945190548044, 5415.864603384947, 5437.673739575638, 5453.509184173444, 5456.890862182655, 5463.699496527729, 5291.417944026231, 5431.0, 5465.617406781646, 5578.972105139448, 5585.245991791218, 5404.216388623706, 5462.781488740406, 5463.617546771688, 5468.168163665057, 5476.0], "value": 4170.368935004833, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [675.2572170612327, 679.5820570349235, 681.4173881331461, 704.7040243097898, 779.9665443287641, 15373.538793836175, 15915.761263581042, 16203.594910127253, 16518.091504967222, 16550.0, 14013.08914920532, 14077.296135193224, 14078.929605351985, 14101.0, 14109.294535273246, 659.3966520633614, 769.9461037727348, 785.4894318692839, 787.7242964962246, 787.9881801772977, 663.2670898656987, 668.084723928219, 668.3850857211377, 764.3693952489191, 770.2374649097382, 811.7686459359054, 824.3693574415577, 825.1212458731462, 825.8719898415756, 825.9297959673435, 813.369638530138, 813.4265342933218, 814.5194335342147, 814.7352110564091, 814.8777683347486, 498.3596079038455, 505.89376230991536, 583.2913010691988, 597.0536699331565, 601.1163589523437, 14182.503612373517, 14754.336054877449, 14767.113973161537, 14767.926160369238, 14783.113013219241, 13350.399232034582, 13505.797413038812, 13786.724265514735, 13788.4531818931, 14088.506902258474, 11893.345865977368, 11916.34460104694, 11934.28394296345, 12058.336791476466, 12143.757124857453, 3654.7258955578172, 4155.522114956789, 4171.499420069581, 4339.349097635349, 4358.36803663467, 74759.0, 75426.24573754302, 75532.84569348629, 75591.62204188944, 75629.0, 10903.0, 10951.233413660968, 11015.173861960386, 11019.83470247947, 11089.944550277198, 20155.294564690164, 20274.290399835936, 20397.796022039885, 20458.897705511674, 21091.789082109288, 7772.611369431505, 8119.269265765991, 8131.634076466573, 8200.0, 8601.22588966996, 4617.861464156081, 4661.230896902041, 4695.718256904528, 4717.315989181549, 4747.643926705442, 3197.0728488738005, 3316.6185888622636, 3317.2204531934976, 3322.1362445763793, 3324.25204329024, 588.4027711872453, 594.485769809112, 597.4145337569206, 601.4136217188207, 601.666075328197, 552.3868505958383, 619.8822223777559, 702.3152426384235, 702.8032150997728, 718.421670555208, 9878.407295562289, 10200.285979981478, 10290.618837822776, 10364.896351036394, 10417.010384013507, 636.5252276496238, 696.8675951569186, 699.1854489519754, 702.8830116749966, 706.4560288577767, 4850.80596776125, 4873.610111191097, 4920.803167873245, 4934.3338649282605, 4935.062338155739, 128068.0, 129848.35075824561, 130924.0, 131674.34162829316, 135695.0, 109395.0, 111182.44087795579, 111998.44000780107, 114645.0, 114927.0, 95882.0, 95927.0, 98241.50879245558, 99624.00375996293, 100147.0, 9221.631134754803, 9381.906180938104, 9546.761330966781, 9585.089416505421, 9607.615695372386, 51395.463929893536, 51714.0, 52347.21479177892, 53064.0, 54436.45563544472, 75855.4144585537, 79895.20104798878, 81356.0, 82196.89015549318, 85760.57119714361, 12218.75562488763, 12317.630471085882, 12425.565105221274, 12793.42429590652, 12913.418896149507, 13644.317784110954, 13779.724405512034, 14162.645933851736, 14186.787198191829, 14424.783628245375, 12038.458269378074, 12076.0, 12176.0, 12284.20915842804, 12410.689732756753, 11176.944115279697, 12806.61580152597, 13064.281464519543, 13299.0, 13589.796153057514, 13296.734065318833, 13706.177629342272, 13737.175769453865, 13936.0, 14500.057496262709, 11053.834192486958, 11120.443977800916, 11275.210735248782, 11306.83039754388, 11831.40842957866, 10909.39998300101, 11341.603043893425, 11582.768344633228, 11606.361650109326, 11626.476808543466], "value": 7463.953790995423, "unit": "score"}], "name": "dromaeo_css", "value": 7169.253126085362, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 112.0, 113.0, 114.0, 114.0, 112.0, 112.0, 113.0, 112.0, 112.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 117.0, 118.0, 161.0, 120.0, 123.0, 119.0, 119.0, 152.0, 118.0], "value": 130.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 162.0, 160.0, 161.0, 161.0, 163.0, 162.0, 161.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 123.0, 76.0, 76.0, 75.0, 75.0, 113.0, 76.0], "value": 83.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 129.0, 82.0, 82.0, 82.0, 102.0, 82.0, 82.0, 115.0], "value": 92.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 146.0, 147.0, 147.0, 146.0, 145.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 96.0, 100.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 51.0, 52.0, 54.0, 52.0, 52.0, 52.0, 51.0, 52.0], "value": 52.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 59.0, 60.0, 62.0, 63.0, 59.0, 60.0, 59.0, 60.0], "value": 60.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 81.0, 78.0, 78.0, 79.0, 80.0, 78.0, 78.0, 78.0, 78.0], "value": 79.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 124.0, 122.0, 128.0, 122.0, 134.0, 132.0, 123.0, 164.0, 133.0], "value": 131.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 168.0, 166.0, 167.0, 164.0, 168.0, 163.0, 161.0, 172.0, 168.0], "value": 166.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 66.0, 66.0, 65.0, 65.0, 67.0, 64.0, 64.0], "value": 65.3, "unit": "score"}], "name": "kraken", "value": 1494.6, "alertThreshold": 2.0}]} 09:38:30 INFO - Return code: 0 09:38:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:38:30 INFO - # TBPL SUCCESS # 09:38:30 INFO - Running post-action listener: _resource_record_post_action 09:38:30 INFO - [mozharness: 2016-09-27 16:38:30.152773Z] Finished run-tests step (success) 09:38:30 INFO - Running post-run listener: _resource_record_post_run 09:38:30 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 0; Write bytes: 399147008; Read time: 0; Write time: 287528 09:38:30 INFO - TinderboxPrint: CPU usage
13.1% 09:38:30 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 09:38:30 INFO - TinderboxPrint: I/O write bytes / time
399,147,008 / 287,528 09:38:30 INFO - TinderboxPrint: CPU idle
8,282.6 (86.9%) 09:38:30 INFO - TinderboxPrint: CPU user
1,195.3 (12.5%) 09:38:30 INFO - TinderboxPrint: Swap in / out
0 / 0 09:38:30 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 110628864; Read time: 0; Write time: 84232 09:38:30 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 288518144; Read time: 0; Write time: 203296 09:38:30 INFO - Running post-run listener: _upload_blobber_files 09:38:30 INFO - Blob upload gear active. 09:38:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:38:30 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:38:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:38:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:38:31 INFO - (blobuploader) - INFO - Open directory for files ... 09:38:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:38:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:38:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:38:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:38:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:38:34 INFO - (blobuploader) - INFO - Done attempting. 09:38:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:38:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:38:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:38:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:38:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:38:35 INFO - (blobuploader) - INFO - Done attempting. 09:38:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:38:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:38:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:38:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:38:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:38:35 INFO - (blobuploader) - INFO - Done attempting. 09:38:35 INFO - (blobuploader) - INFO - Iteration through files over. 09:38:35 INFO - Return code: 0 09:38:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:38:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:38:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7173e37adcf2371457e7815cdcc8222ab4edf47764002bb487f34d8b21b7912076ac0eb731c06d5ea2bc096ad57fde8e2ea3aa8269dd2b28229f33feef8bd68b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ccbbebcc951b4814dc961f08a4d376454bbe38f175c4118e03d1d10d2c499b8222428f8018085d83bf27c8594c0702851215195e1b04ca0e753b0a9c1badca0f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2eef365465b95b333489275921165582f03ba396c49e22cf51cbd421adba6c8fd17f32593c19d7efbfa0ce09c69a11a9aee44995c3c9d20bee077e8cd06fd8fa"} 09:38:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:38:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:38:35 INFO - Contents: 09:38:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7173e37adcf2371457e7815cdcc8222ab4edf47764002bb487f34d8b21b7912076ac0eb731c06d5ea2bc096ad57fde8e2ea3aa8269dd2b28229f33feef8bd68b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ccbbebcc951b4814dc961f08a4d376454bbe38f175c4118e03d1d10d2c499b8222428f8018085d83bf27c8594c0702851215195e1b04ca0e753b0a9c1badca0f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2eef365465b95b333489275921165582f03ba396c49e22cf51cbd421adba6c8fd17f32593c19d7efbfa0ce09c69a11a9aee44995c3c9d20bee077e8cd06fd8fa"} 09:38:35 INFO - Running post-run listener: copy_logs_to_upload_dir 09:38:35 INFO - Copying logs to upload dir... 09:38:35 INFO - mkdir: /builds/slave/test/build/upload/logs 09:38:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.680832 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-09-27 09:38:35.838765) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-27 09:38:35.842668) ========= 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=20e666a3d4cfbb71505a330e000001b4-1474993076.426389-568394526 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/sha512/7173e37adcf2371457e7815cdcc8222ab4edf47764002bb487f34d8b21b7912076ac0eb731c06d5ea2bc096ad57fde8e2ea3aa8269dd2b28229f33feef8bd68b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ccbbebcc951b4814dc961f08a4d376454bbe38f175c4118e03d1d10d2c499b8222428f8018085d83bf27c8594c0702851215195e1b04ca0e753b0a9c1badca0f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2eef365465b95b333489275921165582f03ba396c49e22cf51cbd421adba6c8fd17f32593c19d7efbfa0ce09c69a11a9aee44995c3c9d20bee077e8cd06fd8fa"} build_url:https://queue.taskcluster.net/v1/task/Tz731C6cQJ-unKVkKathsw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011253 build_url: 'https://queue.taskcluster.net/v1/task/Tz731C6cQJ-unKVkKathsw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7173e37adcf2371457e7815cdcc8222ab4edf47764002bb487f34d8b21b7912076ac0eb731c06d5ea2bc096ad57fde8e2ea3aa8269dd2b28229f33feef8bd68b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ccbbebcc951b4814dc961f08a4d376454bbe38f175c4118e03d1d10d2c499b8222428f8018085d83bf27c8594c0702851215195e1b04ca0e753b0a9c1badca0f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2eef365465b95b333489275921165582f03ba396c49e22cf51cbd421adba6c8fd17f32593c19d7efbfa0ce09c69a11a9aee44995c3c9d20bee077e8cd06fd8fa"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-27 09:38:35.868598) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 09:38:35.868938) ========= 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=20e666a3d4cfbb71505a330e000001b4-1474993076.426389-568394526 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004391 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 09:38:35.954987) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-27 09:38:35.955808) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-27 09:38:35.956553) ========= ========= Total master_lag: 0.19 =========