builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-043 starttime: 1475234264.49 results: success (0) buildid: 20160930013320 builduid: aa5c9bca12eb4c9481f5747ff57287d7 revision: 805d5fc3d66c4fa585a3de407b1e0b055f78eb58 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-30 04:17:44.492249) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-30 04:17:44.492801) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-30 04:17:44.493085) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-30 04:17:44.539507) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 04:17:44.539777) ========= 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=d2a8c569965ab8367867b893000001a1-1475233861.210016-279485443 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-30 04:17:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 37.8M=0s 2016-09-30 04:17:45 (37.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.151961 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 04:17:44.712948) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 04:17:44.713239) ========= 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=d2a8c569965ab8367867b893000001a1-1475233861.210016-279485443 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015601 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 04:17:44.762245) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 04:17:44.762570) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 805d5fc3d66c4fa585a3de407b1e0b055f78eb58 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 805d5fc3d66c4fa585a3de407b1e0b055f78eb58 --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=d2a8c569965ab8367867b893000001a1-1475233861.210016-279485443 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-30 04:17:45,520 truncating revision to first 12 chars 2016-09-30 04:17:45,520 Setting DEBUG logging. 2016-09-30 04:17:45,520 attempt 1/10 2016-09-30 04:17:45,520 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/805d5fc3d66c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-30 04:17:45,808 unpacking tar archive at: mozilla-aurora-805d5fc3d66c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.631309 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 04:17:45.406652) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-30 04:17:45.406987) ========= script_repo_revision: 805d5fc3d66c4fa585a3de407b1e0b055f78eb58 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-30 04:17:45.407397) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-30 04:17:45.407687) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-30 04:17:45.417665) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-09-30 04:17:45.417957) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=d2a8c569965ab8367867b893000001a1-1475233861.210016-279485443 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:17:46 INFO - MultiFileLogger online at 20160930 04:17:46 in /builds/slave/test 04:17:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 04:17:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:17:46 INFO - {'append_to_log': False, 04:17:46 INFO - 'base_work_dir': '/builds/slave/test', 04:17:46 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 04:17:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:17:46 INFO - 'branch': 'Mozilla-Aurora', 04:17:46 INFO - 'buildbot_json_path': 'buildprops.json', 04:17:46 INFO - 'config_files': ('talos/linux_config.py',), 04:17:46 INFO - 'default_actions': ('clobber', 04:17:46 INFO - 'read-buildbot-config', 04:17:46 INFO - 'download-and-extract', 04:17:46 INFO - 'populate-webroot', 04:17:46 INFO - 'create-virtualenv', 04:17:46 INFO - 'install', 04:17:46 INFO - 'run-tests'), 04:17:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:17:46 INFO - 'download_minidump_stackwalk': True, 04:17:46 INFO - 'download_symbols': 'ondemand', 04:17:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:17:46 INFO - 'tooltool.py': '/tools/tooltool.py', 04:17:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:17:46 INFO - '/tools/misc-python/virtualenv.py')}, 04:17:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:17:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:17:46 INFO - 'installer_path': 'installer.exe', 04:17:46 INFO - 'log_level': 'info', 04:17:46 INFO - 'log_name': 'talos', 04:17:46 INFO - 'log_to_console': True, 04:17:46 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:17:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:17:46 INFO - 'opt_config_files': (), 04:17:46 INFO - 'pip_index': False, 04:17:46 INFO - 'sps_profile': False, 04:17:46 INFO - 'sps_profile_interval': 0, 04:17:46 INFO - 'suite': 'dromaeojs', 04:17:46 INFO - 'system_bits': '32', 04:17:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:17:46 INFO - 'title': 'talos-linux64-ix-043', 04:17:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:17:46 INFO - 'use_talos_json': True, 04:17:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:17:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:17:46 INFO - 'work_dir': 'build'} 04:17:46 INFO - [mozharness: 2016-09-30 11:17:46.269287Z] Running clobber step. 04:17:46 INFO - Running pre-action listener: _resource_record_pre_action 04:17:46 INFO - Running main action method: clobber 04:17:46 INFO - rmtree: /builds/slave/test/build 04:17:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:17:46 INFO - Running post-action listener: _resource_record_post_action 04:17:46 INFO - [mozharness: 2016-09-30 11:17:46.733713Z] Finished clobber step (success) 04:17:46 INFO - [mozharness: 2016-09-30 11:17:46.733815Z] Running read-buildbot-config step. 04:17:46 INFO - Running pre-action listener: _resource_record_pre_action 04:17:46 INFO - Running main action method: read_buildbot_config 04:17:46 INFO - Using buildbot properties: 04:17:46 INFO - { 04:17:46 INFO - "project": "", 04:17:46 INFO - "product": "firefox", 04:17:46 INFO - "script_repo_revision": "production", 04:17:46 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 04:17:46 INFO - "repository": "", 04:17:46 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 04:17:46 INFO - "buildid": "20160930013320", 04:17:46 INFO - "pgo_build": "True", 04:17:46 INFO - "basedir": "/builds/slave/test", 04:17:46 INFO - "buildnumber": 540, 04:17:46 INFO - "slavename": "talos-linux64-ix-043", 04:17:46 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 04:17:46 INFO - "platform": "ubuntu64_hw", 04:17:46 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 04:17:46 INFO - "branch": "mozilla-aurora", 04:17:46 INFO - "repo_path": "releases/mozilla-aurora", 04:17:46 INFO - "revision": "805d5fc3d66c4fa585a3de407b1e0b055f78eb58", 04:17:46 INFO - "stage_platform": "linux64-pgo", 04:17:46 INFO - "builduid": "aa5c9bca12eb4c9481f5747ff57287d7", 04:17:46 INFO - "slavebuilddir": "test" 04:17:46 INFO - } 04:17:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/BIWz9CtSSRKSlfmg2m3New/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 04:17:46 INFO - Running post-action listener: _resource_record_post_action 04:17:46 INFO - [mozharness: 2016-09-30 11:17:46.736276Z] Finished read-buildbot-config step (success) 04:17:46 INFO - [mozharness: 2016-09-30 11:17:46.736365Z] Running download-and-extract step. 04:17:46 INFO - Running pre-action listener: _resource_record_pre_action 04:17:46 INFO - Running main action method: download_and_extract 04:17:46 INFO - mkdir: /builds/slave/test/build/tests 04:17:46 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')]} 04:17:46 INFO - trying https://queue.taskcluster.net/v1/task/BIWz9CtSSRKSlfmg2m3New/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 04:17:46 INFO - Downloading https://queue.taskcluster.net/v1/task/BIWz9CtSSRKSlfmg2m3New/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 04:17:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BIWz9CtSSRKSlfmg2m3New/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 04:17:47 INFO - Downloaded 1361 bytes. 04:17:47 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 04:17:47 INFO - Using the following test package requirements: 04:17:47 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 04:17:47 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 04:17:47 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 04:17:47 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 04:17:47 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 04:17:47 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 04:17:47 INFO - u'jsshell-linux-x86_64.zip'], 04:17:47 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 04:17:47 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 04:17:47 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 04:17:47 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 04:17:47 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 04:17:47 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 04:17:47 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 04:17:47 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 04:17:47 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 04:17:47 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 04:17:47 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 04:17:47 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:17:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BIWz9CtSSRKSlfmg2m3New/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 04:17:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BIWz9CtSSRKSlfmg2m3New/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:17:47 INFO - Fetch https://queue.taskcluster.net/v1/task/BIWz9CtSSRKSlfmg2m3New/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 04:17:47 INFO - Expected file size: 21901358 04:17:52 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 04:17:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BIWz9CtSSRKSlfmg2m3New/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 04:17:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BIWz9CtSSRKSlfmg2m3New/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:17:52 INFO - Fetch https://queue.taskcluster.net/v1/task/BIWz9CtSSRKSlfmg2m3New/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 04:17:52 INFO - Expected file size: 21901358 04:17:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BIWz9CtSSRKSlfmg2m3New/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 04:17:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BIWz9CtSSRKSlfmg2m3New/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:17:57 INFO - Fetch https://queue.taskcluster.net/v1/task/BIWz9CtSSRKSlfmg2m3New/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 04:17:58 INFO - Expected file size: 14414059 04:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:17:58 INFO - trying https://queue.taskcluster.net/v1/task/BIWz9CtSSRKSlfmg2m3New/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 04:17:58 INFO - Downloading https://queue.taskcluster.net/v1/task/BIWz9CtSSRKSlfmg2m3New/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 04:17:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BIWz9CtSSRKSlfmg2m3New/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:18:00 INFO - Downloaded 63921663 bytes. 04:18:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BIWz9CtSSRKSlfmg2m3New/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 04:18:00 INFO - mkdir: /builds/slave/test/properties 04:18:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:18:00 INFO - Writing to file /builds/slave/test/properties/build_url 04:18:00 INFO - Contents: 04:18:00 INFO - build_url:https://queue.taskcluster.net/v1/task/BIWz9CtSSRKSlfmg2m3New/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 04:18:01 INFO - Running post-action listener: _resource_record_post_action 04:18:01 INFO - Running post-action listener: set_extra_try_arguments 04:18:01 INFO - [mozharness: 2016-09-30 11:18:01.106001Z] Finished download-and-extract step (success) 04:18:01 INFO - [mozharness: 2016-09-30 11:18:01.106230Z] Running populate-webroot step. 04:18:01 INFO - Running pre-action listener: _resource_record_pre_action 04:18:01 INFO - Running main action method: populate_webroot 04:18:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:18:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:18:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:18:01 INFO - '--tppagecycles', 04:18:01 INFO - '7'], 04:18:01 INFO - 'tests': ['tsvgm']}}, 04:18:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:18:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:18:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:18:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:18:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:18:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:18:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:18:01 INFO - 'talos_options': ['--disable-e10s'], 04:18:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:18:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:18:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:18:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:18:01 INFO - 'talos_options': ['--disable-e10s'], 04:18:01 INFO - 'tests': ['damp', 'tps']}, 04:18:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:18:01 INFO - 'tests': ['damp', 'tps']}, 04:18:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:18:01 INFO - 'tests': ['dromaeo_dom']}, 04:18:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:18:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:18:01 INFO - 'tests': ['basic_compositor_video']}, 04:18:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:18:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:18:01 INFO - 'tests': ['a11yr', 04:18:01 INFO - 'ts_paint', 04:18:01 INFO - 'tpaint', 04:18:01 INFO - 'sessionrestore', 04:18:01 INFO - 'sessionrestore_no_auto_restore', 04:18:01 INFO - 'tabpaint']}, 04:18:01 INFO - 'other-e10s': {'tests': ['a11yr', 04:18:01 INFO - 'ts_paint', 04:18:01 INFO - 'tpaint', 04:18:01 INFO - 'sessionrestore', 04:18:01 INFO - 'sessionrestore_no_auto_restore', 04:18:01 INFO - 'tabpaint']}, 04:18:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:18:01 INFO - 'ts_paint', 04:18:01 INFO - 'tpaint', 04:18:01 INFO - 'sessionrestore', 04:18:01 INFO - 'sessionrestore_no_auto_restore', 04:18:01 INFO - 'tabpaint']}, 04:18:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:18:01 INFO - 'ts_paint', 04:18:01 INFO - 'tpaint', 04:18:01 INFO - 'sessionrestore', 04:18:01 INFO - 'sessionrestore_no_auto_restore', 04:18:01 INFO - 'tabpaint']}, 04:18:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:18:01 INFO - 'tests': ['a11yr', 04:18:01 INFO - 'ts_paint', 04:18:01 INFO - 'tpaint', 04:18:01 INFO - 'sessionrestore', 04:18:01 INFO - 'sessionrestore_no_auto_restore', 04:18:01 INFO - 'tabpaint']}, 04:18:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:18:01 INFO - 'tests': ['a11yr', 04:18:01 INFO - 'ts_paint', 04:18:01 INFO - 'tpaint', 04:18:01 INFO - 'sessionrestore', 04:18:01 INFO - 'sessionrestore_no_auto_restore', 04:18:01 INFO - 'tabpaint']}, 04:18:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:18:01 INFO - 'tests': ['tsvgx', 04:18:01 INFO - 'tsvgr_opacity', 04:18:01 INFO - 'tart', 04:18:01 INFO - 'tscrollx', 04:18:01 INFO - 'cart']}, 04:18:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:18:01 INFO - 'tsvgr_opacity', 04:18:01 INFO - 'tart', 04:18:01 INFO - 'tscrollx', 04:18:01 INFO - 'cart']}, 04:18:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:18:01 INFO - 'talos_options': ['--disable-e10s'], 04:18:01 INFO - 'tests': ['tp5o']}, 04:18:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:18:01 INFO - 'tests': ['tp5o']}, 04:18:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:18:01 INFO - 'talos_options': ['--disable-e10s', 04:18:01 INFO - '--xperf_path', 04:18:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:18:01 INFO - 'tests': ['tp5n']}, 04:18:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:18:01 INFO - 'talos_options': ['--xperf_path', 04:18:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:18:01 INFO - 'tests': ['tp5n']}}, 04:18:01 INFO - 'talos.zip': {'path': '', 04:18:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:18:01 INFO - Running post-action listener: _resource_record_post_action 04:18:01 INFO - [mozharness: 2016-09-30 11:18:01.122701Z] Finished populate-webroot step (success) 04:18:01 INFO - [mozharness: 2016-09-30 11:18:01.122789Z] Running create-virtualenv step. 04:18:01 INFO - Running pre-action listener: _resource_record_pre_action 04:18:01 INFO - Running main action method: create_virtualenv 04:18:01 INFO - Creating virtualenv /builds/slave/test/build/venv 04:18:01 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 04:18:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:18:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:18:01 INFO - Using real prefix '/usr' 04:18:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:18:01 INFO - Installing distribute.............................................................................................................................................................................................done. 04:18:03 INFO - Installing pip.................done. 04:18:03 INFO - Return code: 0 04:18:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:18:03 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')]} 04:18:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:18:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:03 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')]} 04:18:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:18:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:03 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 0x7f28eb6b2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25edca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25eaf60>, '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 0x25ebd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ec1d0>, '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': 'd2a8c569965ab8367867b893000001a1-1475233861.210016-279485443', '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 04:18:03 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 04:18:03 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 04:18:03 INFO - Using env: {'DISPLAY': ':0', 04:18:03 INFO - 'HOME': '/home/cltbld', 04:18:03 INFO - 'LANG': 'en_US.UTF-8', 04:18:03 INFO - 'LANGUAGE': 'en_US:en', 04:18:03 INFO - 'LOGNAME': 'cltbld', 04:18:03 INFO - 'MAIL': '/var/mail/cltbld', 04:18:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:03 INFO - 'MOZ_NO_REMOTE': '1', 04:18:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:18:03 INFO - 'NO_EM_RESTART': '1', 04:18:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:18:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:03 INFO - 'PWD': '/builds/slave/test', 04:18:03 INFO - 'SHELL': '/bin/bash', 04:18:03 INFO - 'SHLVL': '1', 04:18:03 INFO - 'TERM': 'linux', 04:18:03 INFO - 'TMOUT': '86400', 04:18:03 INFO - 'USER': 'cltbld', 04:18:03 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1475233861.210016-279485443', 04:18:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:03 INFO - '_': '/tools/buildbot/bin/python'} 04:18:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:18:03 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 04:18:03 INFO - Cleaning up... 04:18:03 INFO - Return code: 0 04:18:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:18:03 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')]} 04:18:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:18:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:03 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')]} 04:18:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:18:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:03 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 0x7f28eb6b2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25edca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25eaf60>, '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 0x25ebd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ec1d0>, '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': 'd2a8c569965ab8367867b893000001a1-1475233861.210016-279485443', '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 04:18:03 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 04:18:03 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 04:18:03 INFO - Using env: {'DISPLAY': ':0', 04:18:03 INFO - 'HOME': '/home/cltbld', 04:18:03 INFO - 'LANG': 'en_US.UTF-8', 04:18:03 INFO - 'LANGUAGE': 'en_US:en', 04:18:03 INFO - 'LOGNAME': 'cltbld', 04:18:03 INFO - 'MAIL': '/var/mail/cltbld', 04:18:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:03 INFO - 'MOZ_NO_REMOTE': '1', 04:18:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:18:03 INFO - 'NO_EM_RESTART': '1', 04:18:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:18:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:03 INFO - 'PWD': '/builds/slave/test', 04:18:03 INFO - 'SHELL': '/bin/bash', 04:18:03 INFO - 'SHLVL': '1', 04:18:03 INFO - 'TERM': 'linux', 04:18:03 INFO - 'TMOUT': '86400', 04:18:03 INFO - 'USER': 'cltbld', 04:18:03 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1475233861.210016-279485443', 04:18:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:03 INFO - '_': '/tools/buildbot/bin/python'} 04:18:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:18:04 INFO - Downloading/unpacking psutil>=3.1.1 04:18:04 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 04:18:04 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 04:18:04 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 04:18:04 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 04:18:04 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 04:18:04 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 04:18:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:18:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:18:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:18:06 INFO - Installing collected packages: psutil 04:18:06 INFO - Running setup.py install for psutil 04:18:06 INFO - building 'psutil._psutil_linux' extension 04:18:06 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 04:18:06 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 04:18:06 INFO - building 'psutil._psutil_posix' extension 04:18:06 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 04:18:06 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 04:18:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:18:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:18:06 INFO - Successfully installed psutil 04:18:06 INFO - Cleaning up... 04:18:06 INFO - Return code: 0 04:18:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:18:06 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')]} 04:18:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:18:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:06 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')]} 04:18:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:18:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:06 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 0x7f28eb6b2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25edca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25eaf60>, '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 0x25ebd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ec1d0>, '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': 'd2a8c569965ab8367867b893000001a1-1475233861.210016-279485443', '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 04:18:06 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 04:18:06 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 04:18:06 INFO - Using env: {'DISPLAY': ':0', 04:18:06 INFO - 'HOME': '/home/cltbld', 04:18:06 INFO - 'LANG': 'en_US.UTF-8', 04:18:06 INFO - 'LANGUAGE': 'en_US:en', 04:18:06 INFO - 'LOGNAME': 'cltbld', 04:18:06 INFO - 'MAIL': '/var/mail/cltbld', 04:18:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:06 INFO - 'MOZ_NO_REMOTE': '1', 04:18:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:18:06 INFO - 'NO_EM_RESTART': '1', 04:18:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:18:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:06 INFO - 'PWD': '/builds/slave/test', 04:18:06 INFO - 'SHELL': '/bin/bash', 04:18:06 INFO - 'SHLVL': '1', 04:18:06 INFO - 'TERM': 'linux', 04:18:06 INFO - 'TMOUT': '86400', 04:18:06 INFO - 'USER': 'cltbld', 04:18:06 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1475233861.210016-279485443', 04:18:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:06 INFO - '_': '/tools/buildbot/bin/python'} 04:18:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:18:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:18:06 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 04:18:06 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 04:18:06 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 04:18:06 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 04:18:06 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 04:18:06 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 04:18:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:18:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:18:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:18:08 INFO - Installing collected packages: mozsystemmonitor 04:18:08 INFO - Running setup.py install for mozsystemmonitor 04:18:08 INFO - Successfully installed mozsystemmonitor 04:18:08 INFO - Cleaning up... 04:18:08 INFO - Return code: 0 04:18:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:18:08 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')]} 04:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:18:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:08 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')]} 04:18:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:18:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:08 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 0x7f28eb6b2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25edca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25eaf60>, '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 0x25ebd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ec1d0>, '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': 'd2a8c569965ab8367867b893000001a1-1475233861.210016-279485443', '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 04:18:08 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 04:18:08 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 04:18:08 INFO - Using env: {'DISPLAY': ':0', 04:18:08 INFO - 'HOME': '/home/cltbld', 04:18:08 INFO - 'LANG': 'en_US.UTF-8', 04:18:08 INFO - 'LANGUAGE': 'en_US:en', 04:18:08 INFO - 'LOGNAME': 'cltbld', 04:18:08 INFO - 'MAIL': '/var/mail/cltbld', 04:18:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:08 INFO - 'MOZ_NO_REMOTE': '1', 04:18:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:18:08 INFO - 'NO_EM_RESTART': '1', 04:18:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:18:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:08 INFO - 'PWD': '/builds/slave/test', 04:18:08 INFO - 'SHELL': '/bin/bash', 04:18:08 INFO - 'SHLVL': '1', 04:18:08 INFO - 'TERM': 'linux', 04:18:08 INFO - 'TMOUT': '86400', 04:18:08 INFO - 'USER': 'cltbld', 04:18:08 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1475233861.210016-279485443', 04:18:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:08 INFO - '_': '/tools/buildbot/bin/python'} 04:18:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:18:09 INFO - Downloading/unpacking jsonschema==2.5.1 04:18:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:18:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:18:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:18:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:18:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:18:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:18:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:18:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:18:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:18:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:18:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:18:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:18:11 INFO - Downloading functools32-3.2.3-2.tar.gz 04:18:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:18:11 INFO - warning: no files found matching '*.txt' 04:18:11 INFO - no previously-included directories found matching 'build' 04:18:11 INFO - no previously-included directories found matching 'dist' 04:18:11 INFO - no previously-included directories found matching '.git*' 04:18:11 INFO - Installing collected packages: jsonschema, functools32 04:18:11 INFO - Running setup.py install for functools32 04:18:11 INFO - warning: no files found matching '*.txt' 04:18:11 INFO - no previously-included directories found matching 'build' 04:18:11 INFO - no previously-included directories found matching 'dist' 04:18:11 INFO - no previously-included directories found matching '.git*' 04:18:11 INFO - Successfully installed jsonschema functools32 04:18:11 INFO - Cleaning up... 04:18:11 INFO - Return code: 0 04:18:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04: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')]} 04:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:18:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04: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')]} 04:18:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:18:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f28eb6b2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25edca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25eaf60>, '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 0x25ebd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ec1d0>, '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': 'd2a8c569965ab8367867b893000001a1-1475233861.210016-279485443', '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 04:18:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:18:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:18:11 INFO - Using env: {'DISPLAY': ':0', 04:18:11 INFO - 'HOME': '/home/cltbld', 04:18:11 INFO - 'LANG': 'en_US.UTF-8', 04:18:11 INFO - 'LANGUAGE': 'en_US:en', 04:18:11 INFO - 'LOGNAME': 'cltbld', 04:18:11 INFO - 'MAIL': '/var/mail/cltbld', 04:18:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:11 INFO - 'MOZ_NO_REMOTE': '1', 04:18:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:18:11 INFO - 'NO_EM_RESTART': '1', 04:18:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:18:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:11 INFO - 'PWD': '/builds/slave/test', 04:18:11 INFO - 'SHELL': '/bin/bash', 04:18:11 INFO - 'SHLVL': '1', 04:18:11 INFO - 'TERM': 'linux', 04:18:11 INFO - 'TMOUT': '86400', 04:18:11 INFO - 'USER': 'cltbld', 04:18:11 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1475233861.210016-279485443', 04:18:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:11 INFO - '_': '/tools/buildbot/bin/python'} 04:18:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:18:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:18:11 INFO - Cleaning up... 04:18:11 INFO - Return code: 0 04:18:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04: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')]} 04:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:18:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04: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')]} 04:18:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:18:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f28eb6b2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25edca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25eaf60>, '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 0x25ebd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ec1d0>, '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': 'd2a8c569965ab8367867b893000001a1-1475233861.210016-279485443', '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 04:18:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:18:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:18:11 INFO - Using env: {'DISPLAY': ':0', 04:18:11 INFO - 'HOME': '/home/cltbld', 04:18:11 INFO - 'LANG': 'en_US.UTF-8', 04:18:11 INFO - 'LANGUAGE': 'en_US:en', 04:18:11 INFO - 'LOGNAME': 'cltbld', 04:18:11 INFO - 'MAIL': '/var/mail/cltbld', 04:18:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:11 INFO - 'MOZ_NO_REMOTE': '1', 04:18:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:18:11 INFO - 'NO_EM_RESTART': '1', 04:18:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:18:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:11 INFO - 'PWD': '/builds/slave/test', 04:18:11 INFO - 'SHELL': '/bin/bash', 04:18:11 INFO - 'SHLVL': '1', 04:18:11 INFO - 'TERM': 'linux', 04:18:11 INFO - 'TMOUT': '86400', 04:18:11 INFO - 'USER': 'cltbld', 04:18:11 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1475233861.210016-279485443', 04:18:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:11 INFO - '_': '/tools/buildbot/bin/python'} 04:18:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:18:11 INFO - Downloading/unpacking blobuploader==1.2.4 04:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:18:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:18:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:18:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:18:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:18:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:18:13 INFO - Downloading blobuploader-1.2.4.tar.gz 04:18:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:18:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:18:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:18:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:18:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:18:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:18:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:18:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:18:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:18:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:18:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:18:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:18:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:18:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:18:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:18:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:18:14 INFO - Downloading docopt-0.6.1.tar.gz 04:18:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:18:14 INFO - Installing collected packages: blobuploader, requests, docopt 04:18:14 INFO - Running setup.py install for blobuploader 04:18:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:18:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:18:14 INFO - Running setup.py install for requests 04:18:15 INFO - Running setup.py install for docopt 04:18:15 INFO - Successfully installed blobuploader requests docopt 04:18:15 INFO - Cleaning up... 04:18:15 INFO - Return code: 0 04:18:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04: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')]} 04:18:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:18:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04: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')]} 04:18:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:18:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:15 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 0x7f28eb6b2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25edca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25eaf60>, '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 0x25ebd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ec1d0>, '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': 'd2a8c569965ab8367867b893000001a1-1475233861.210016-279485443', '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 04:18:15 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 04:18:15 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 04:18:15 INFO - Using env: {'DISPLAY': ':0', 04:18:15 INFO - 'HOME': '/home/cltbld', 04:18:15 INFO - 'LANG': 'en_US.UTF-8', 04:18:15 INFO - 'LANGUAGE': 'en_US:en', 04:18:15 INFO - 'LOGNAME': 'cltbld', 04:18:15 INFO - 'MAIL': '/var/mail/cltbld', 04:18:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:15 INFO - 'MOZ_NO_REMOTE': '1', 04:18:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:18:15 INFO - 'NO_EM_RESTART': '1', 04:18:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:18:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:15 INFO - 'PWD': '/builds/slave/test', 04:18:15 INFO - 'SHELL': '/bin/bash', 04:18:15 INFO - 'SHLVL': '1', 04:18:15 INFO - 'TERM': 'linux', 04:18:15 INFO - 'TMOUT': '86400', 04:18:15 INFO - 'USER': 'cltbld', 04:18:15 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1475233861.210016-279485443', 04:18:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:15 INFO - '_': '/tools/buildbot/bin/python'} 04:18:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:18:15 INFO - Running setup.py (path:/tmp/pip-mivLsn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:18:15 INFO - Running setup.py (path:/tmp/pip-MdriE_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:18:15 INFO - Running setup.py (path:/tmp/pip-uj5o90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:18:15 INFO - Running setup.py (path:/tmp/pip-WIlzg0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:18:15 INFO - Running setup.py (path:/tmp/pip-Vm8mcD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:18:15 INFO - Running setup.py (path:/tmp/pip-SKdTYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:18:15 INFO - Running setup.py (path:/tmp/pip-E3v143-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:18:15 INFO - Running setup.py (path:/tmp/pip-WbcRy3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:18:16 INFO - Running setup.py (path:/tmp/pip-vvHlYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:18:16 INFO - Running setup.py (path:/tmp/pip-Bglsgq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:18:16 INFO - Running setup.py (path:/tmp/pip-V9Ug3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:18:16 INFO - Running setup.py (path:/tmp/pip-1MbAhY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:18:16 INFO - Running setup.py (path:/tmp/pip-DDLDQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:18:16 INFO - Running setup.py (path:/tmp/pip-Q_jkZ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:18:16 INFO - Running setup.py (path:/tmp/pip-pzi5gK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:18:16 INFO - Running setup.py (path:/tmp/pip-kDEkmo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:18:16 INFO - Running setup.py (path:/tmp/pip-4ULHT2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:18:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:18:16 INFO - Running setup.py install for manifestparser 04:18:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:18:16 INFO - Running setup.py install for mozcrash 04:18:16 INFO - Running setup.py install for mozdebug 04:18:16 INFO - Running setup.py install for mozdevice 04:18:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:18:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:18:17 INFO - Running setup.py install for mozfile 04:18:17 INFO - Running setup.py install for mozhttpd 04:18:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:18:17 INFO - Running setup.py install for mozinfo 04:18:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:18:17 INFO - Running setup.py install for mozInstall 04:18:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:18:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:18:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:18:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:18:17 INFO - Running setup.py install for mozleak 04:18:17 INFO - Running setup.py install for mozlog 04:18:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:18:17 INFO - Running setup.py install for moznetwork 04:18:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:18:17 INFO - Running setup.py install for mozprocess 04:18:17 INFO - Running setup.py install for mozprofile 04:18:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:18:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:18:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:18:17 INFO - Running setup.py install for mozrunner 04:18:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:18:18 INFO - Running setup.py install for mozscreenshot 04:18:18 INFO - Running setup.py install for moztest 04:18:18 INFO - Running setup.py install for mozversion 04:18:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:18:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:18:18 INFO - Cleaning up... 04:18:18 INFO - Return code: 0 04:18:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04: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')]} 04:18:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:18:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04: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')]} 04:18:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:18:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:18 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 0x7f28eb6b2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25edca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25eaf60>, '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 0x25ebd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ec1d0>, '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': 'd2a8c569965ab8367867b893000001a1-1475233861.210016-279485443', '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 04:18:18 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 04:18:18 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 04:18:18 INFO - Using env: {'DISPLAY': ':0', 04:18:18 INFO - 'HOME': '/home/cltbld', 04:18:18 INFO - 'LANG': 'en_US.UTF-8', 04:18:18 INFO - 'LANGUAGE': 'en_US:en', 04:18:18 INFO - 'LOGNAME': 'cltbld', 04:18:18 INFO - 'MAIL': '/var/mail/cltbld', 04:18:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:18 INFO - 'MOZ_NO_REMOTE': '1', 04:18:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:18:18 INFO - 'NO_EM_RESTART': '1', 04:18:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:18:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:18 INFO - 'PWD': '/builds/slave/test', 04:18:18 INFO - 'SHELL': '/bin/bash', 04:18:18 INFO - 'SHLVL': '1', 04:18:18 INFO - 'TERM': 'linux', 04:18:18 INFO - 'TMOUT': '86400', 04:18:18 INFO - 'USER': 'cltbld', 04:18:18 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1475233861.210016-279485443', 04:18:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:18 INFO - '_': '/tools/buildbot/bin/python'} 04:18:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:18:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:18:18 INFO - Running setup.py (path:/tmp/pip-wEY3iB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:18:18 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)) 04:18:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:18:18 INFO - Running setup.py (path:/tmp/pip-yMk3Kc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:18:18 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)) 04:18:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:18:18 INFO - Running setup.py (path:/tmp/pip-L7SuJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:18:18 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)) 04:18:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:18:18 INFO - Running setup.py (path:/tmp/pip-YKFfgB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:18:18 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)) 04:18:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:18:18 INFO - Running setup.py (path:/tmp/pip-HZzfWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:18:19 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)) 04:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:18:19 INFO - Running setup.py (path:/tmp/pip-vCZIyL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:18:19 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)) 04:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:18:19 INFO - Running setup.py (path:/tmp/pip-sPFMBn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:18:19 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)) 04:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:18:19 INFO - Running setup.py (path:/tmp/pip-zopfV5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:18:19 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)) 04:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:18:19 INFO - Running setup.py (path:/tmp/pip-nmP8Dr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:18:19 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)) 04:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:18:19 INFO - Running setup.py (path:/tmp/pip-EJUzZw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:18:19 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)) 04:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:18:19 INFO - Running setup.py (path:/tmp/pip-8hWG_I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:18:19 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)) 04:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:18:19 INFO - Running setup.py (path:/tmp/pip-jXeZXJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:18:19 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)) 04:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:18:19 INFO - Running setup.py (path:/tmp/pip-Uday6x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:18:19 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)) 04:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:18:19 INFO - Running setup.py (path:/tmp/pip-BCylYi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:18:19 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)) 04:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:18:19 INFO - Running setup.py (path:/tmp/pip-4wF9hd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:18:19 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)) 04:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:18:19 INFO - Running setup.py (path:/tmp/pip-kNnC5h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:18:19 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)) 04:18:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:18:19 INFO - Running setup.py (path:/tmp/pip-Z4O91Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:18:19 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)) 04:18:19 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)) 04:18:19 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)) 04:18:19 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)) 04:18:19 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)) 04:18:19 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)) 04:18:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04: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 04: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 04: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 04: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 04: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 04: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 04:18:21 INFO - Downloading blessings-1.6.tar.gz 04:18:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:18:21 INFO - Installing collected packages: blessings 04:18:21 INFO - Running setup.py install for blessings 04:18:21 INFO - Successfully installed blessings 04:18:21 INFO - Cleaning up... 04:18:22 INFO - Return code: 0 04:18:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:18:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:18:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:18:22 INFO - Reading from file tmpfile_stdout 04:18:22 INFO - Current package versions: 04:18:22 INFO - argparse == 1.2.1 04:18:22 INFO - blessings == 1.6 04:18:22 INFO - blobuploader == 1.2.4 04:18:22 INFO - docopt == 0.6.1 04:18:22 INFO - functools32 == 3.2.3-2 04:18:22 INFO - jsonschema == 2.5.1 04:18:22 INFO - manifestparser == 1.1 04:18:22 INFO - mozInstall == 1.12 04:18:22 INFO - mozcrash == 0.17 04:18:22 INFO - mozdebug == 0.1 04:18:22 INFO - mozdevice == 0.48 04:18:22 INFO - mozfile == 1.2 04:18:22 INFO - mozhttpd == 0.7 04:18:22 INFO - mozinfo == 0.9 04:18:22 INFO - mozleak == 0.1 04:18:22 INFO - mozlog == 3.4 04:18:22 INFO - moznetwork == 0.27 04:18:22 INFO - mozprocess == 0.23 04:18:22 INFO - mozprofile == 0.28 04:18:22 INFO - mozrunner == 6.12 04:18:22 INFO - mozscreenshot == 0.1 04:18:22 INFO - mozsystemmonitor == 0.3 04:18:22 INFO - moztest == 0.7 04:18:22 INFO - mozversion == 1.4 04:18:22 INFO - psutil == 3.1.1 04:18:22 INFO - requests == 1.2.3 04:18:22 INFO - wsgiref == 0.1.2 04:18:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:18:22 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')]} 04:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:18:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:22 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')]} 04:18:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:18:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:22 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 0x7f28eb6b2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25edca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25eaf60>, '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 0x25ebd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ec1d0>, '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': 'd2a8c569965ab8367867b893000001a1-1475233861.210016-279485443', '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 04:18:22 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 04:18:22 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 04:18:22 INFO - Using env: {'DISPLAY': ':0', 04:18:22 INFO - 'HOME': '/home/cltbld', 04:18:22 INFO - 'LANG': 'en_US.UTF-8', 04:18:22 INFO - 'LANGUAGE': 'en_US:en', 04:18:22 INFO - 'LOGNAME': 'cltbld', 04:18:22 INFO - 'MAIL': '/var/mail/cltbld', 04:18:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:22 INFO - 'MOZ_NO_REMOTE': '1', 04:18:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:18:22 INFO - 'NO_EM_RESTART': '1', 04:18:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:18:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:22 INFO - 'PWD': '/builds/slave/test', 04:18:22 INFO - 'SHELL': '/bin/bash', 04:18:22 INFO - 'SHLVL': '1', 04:18:22 INFO - 'TERM': 'linux', 04:18:22 INFO - 'TMOUT': '86400', 04:18:22 INFO - 'USER': 'cltbld', 04:18:22 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1475233861.210016-279485443', 04:18:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:22 INFO - '_': '/tools/buildbot/bin/python'} 04:18:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:18:22 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)) 04:18:22 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)) 04:18:22 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)) 04:18:22 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)) 04:18:22 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)) 04:18:22 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)) 04:18:22 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)) 04:18:22 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)) 04:18:22 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)) 04:18:22 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)) 04:18:22 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)) 04:18:22 INFO - Cleaning up... 04:18:22 INFO - Return code: 0 04:18:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:18:22 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')]} 04:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:18:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:22 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')]} 04:18:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:18:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:18:22 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 0x7f28eb6b2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25edca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25eaf60>, '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 0x25ebd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ec1d0>, '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': 'd2a8c569965ab8367867b893000001a1-1475233861.210016-279485443', '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 04:18:22 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 04:18:22 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 04:18:22 INFO - Using env: {'DISPLAY': ':0', 04:18:22 INFO - 'HOME': '/home/cltbld', 04:18:22 INFO - 'LANG': 'en_US.UTF-8', 04:18:22 INFO - 'LANGUAGE': 'en_US:en', 04:18:22 INFO - 'LOGNAME': 'cltbld', 04:18:22 INFO - 'MAIL': '/var/mail/cltbld', 04:18:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:22 INFO - 'MOZ_NO_REMOTE': '1', 04:18:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:18:22 INFO - 'NO_EM_RESTART': '1', 04:18:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:18:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:22 INFO - 'PWD': '/builds/slave/test', 04:18:22 INFO - 'SHELL': '/bin/bash', 04:18:22 INFO - 'SHLVL': '1', 04:18:22 INFO - 'TERM': 'linux', 04:18:22 INFO - 'TMOUT': '86400', 04:18:22 INFO - 'USER': 'cltbld', 04:18:22 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1475233861.210016-279485443', 04:18:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:22 INFO - '_': '/tools/buildbot/bin/python'} 04:18:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:18:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:18:22 INFO - Cleaning up... 04:18:22 INFO - Return code: 0 04:18:22 INFO - Running post-action listener: _resource_record_post_action 04:18:22 INFO - Running post-action listener: _start_resource_monitoring 04:18:22 INFO - Starting resource monitoring. 04:18:22 INFO - [mozharness: 2016-09-30 11:18:22.751419Z] Finished create-virtualenv step (success) 04:18:22 INFO - [mozharness: 2016-09-30 11:18:22.751877Z] Running install step. 04:18:22 INFO - Running pre-action listener: _resource_record_pre_action 04:18:22 INFO - Running main action method: install 04:18:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:18:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:18:22 INFO - Reading from file tmpfile_stdout 04:18:22 INFO - Detecting whether we're running mozinstall >=1.0... 04:18:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:18:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:18:23 INFO - Reading from file tmpfile_stdout 04:18:23 INFO - Output received: 04:18:23 INFO - Usage: mozinstall [options] installer 04:18:23 INFO - Options: 04:18:23 INFO - -h, --help show this help message and exit 04:18:23 INFO - -d DEST, --destination=DEST 04:18:23 INFO - Directory to install application into. [default: 04:18:23 INFO - "/builds/slave/test"] 04:18:23 INFO - --app=APP Application being installed. [default: firefox] 04:18:23 INFO - mkdir: /builds/slave/test/build/application 04:18:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:18:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:18:38 INFO - Reading from file tmpfile_stdout 04:18:38 INFO - Output received: 04:18:38 INFO - /builds/slave/test/build/application/firefox/firefox 04:18:38 INFO - Running post-action listener: _resource_record_post_action 04:18:39 INFO - [mozharness: 2016-09-30 11:18:39.000194Z] Finished install step (success) 04:18:39 INFO - [mozharness: 2016-09-30 11:18:39.000440Z] Running run-tests step. 04:18:39 INFO - Running pre-action listener: _resource_record_pre_action 04:18:39 INFO - Running main action method: run_tests 04:18:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:18:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:18:39 INFO - Python 2.7.3 04:18:39 INFO - Return code: 0 04:18:39 INFO - grabbing minidump binary from tooltool 04:18:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:18:39 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 0x25eaf60>, '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 0x25ebd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ec1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:18:39 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 04:18:39 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 04:18:39 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 04:18:39 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:18:39 INFO - Return code: 0 04:18:39 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:18:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:18:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:18:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:18:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:18:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BIWz9CtSSRKSlfmg2m3New/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--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 04:18:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BIWz9CtSSRKSlfmg2m3New/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-043 --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 04:18:39 INFO - Using env: {'DISPLAY': ':0', 04:18:39 INFO - 'HOME': '/home/cltbld', 04:18:39 INFO - 'LANG': 'en_US.UTF-8', 04:18:39 INFO - 'LANGUAGE': 'en_US:en', 04:18:39 INFO - 'LOGNAME': 'cltbld', 04:18:39 INFO - 'MAIL': '/var/mail/cltbld', 04:18:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:18:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:18:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:18:39 INFO - 'MOZ_NO_REMOTE': '1', 04:18:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:18:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:18:39 INFO - 'NO_EM_RESTART': '1', 04:18:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:18:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:18:39 INFO - 'PWD': '/builds/slave/test', 04:18:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:18:39 INFO - 'SHELL': '/bin/bash', 04:18:39 INFO - 'SHLVL': '1', 04:18:39 INFO - 'TERM': 'linux', 04:18:39 INFO - 'TMOUT': '86400', 04:18:39 INFO - 'USER': 'cltbld', 04:18:39 INFO - 'XDG_SESSION_COOKIE': 'd2a8c569965ab8367867b893000001a1-1475233861.210016-279485443', 04:18:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:18:39 INFO - '_': '/tools/buildbot/bin/python'} 04:18:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BIWz9CtSSRKSlfmg2m3New/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--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 04:18:39 INFO - mozversion application_buildid: 20160930013320 04:18:39 INFO - mozversion application_changeset: 805d5fc3d66c4fa585a3de407b1e0b055f78eb58 04:18:39 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:18:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:18:39 INFO - mozversion application_name: Firefox 04:18:39 INFO - mozversion application_remotingname: firefox-dev 04:18:39 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:18:39 INFO - mozversion application_vendor: Mozilla 04:18:39 INFO - mozversion application_version: 51.0a2 04:18:39 INFO - mozversion platform_buildid: 20160930013320 04:18:39 INFO - mozversion platform_changeset: 805d5fc3d66c4fa585a3de407b1e0b055f78eb58 04:18:39 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:18:39 INFO - mozversion platform_version: 51.0a2 04:18:39 INFO - using testdate: 1475234319 04:18:39 INFO - actual date: 1475234319 04:18:39 INFO - starting webserver on 'localhost:40526' 04:18:39 INFO - SUITE-START | Running 2 tests 04:18:39 INFO - TEST-START | dromaeo_css 04:18:39 INFO - operating with platform_type : linux_ 04:18:39 INFO - Initialising browser for dromaeo_css test... 04:18:39 INFO - TEST-INFO | started process 31495 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpn8FWIY/profile http://localhost:40526/getInfo.html) 04:18:43 INFO - PROCESS | 31495 | __metrics Screen width/height:1600/1200 04:18:43 INFO - PROCESS | 31495 | colorDepth:24 04:18:43 INFO - PROCESS | 31495 | Browser inner width/height: 1024/697 04:18:43 INFO - PROCESS | 31495 | __metrics 04:18:44 INFO - PROCESS | 31495 | 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" 04:18:44 INFO - PROCESS | 31495 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:18:44 INFO - PROCESS | 31495 | console.error: PushService: 04:18:44 INFO - PROCESS | 31495 | stateChangeProcessEnqueue: Error transitioning state 04:18:44 INFO - PROCESS | 31495 | UnknownError 04:18:45 INFO - TEST-INFO | 31495: exit 0 04:18:45 INFO - Browser initialized. 04:18:45 INFO - Running cycle 1/1 for dromaeo_css test... 04:18:45 INFO - TEST-INFO | started process 31587 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpn8FWIY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:18:57 INFO - PROCESS | 31587 | RSS: Main: 144359424 04:18:57 INFO - PROCESS | 31587 | 04:19:48 INFO - PROCESS | 31587 | 1475234388085 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] 04:21:57 INFO - PROCESS | 31587 | Cycle 1(1): loaded http://localhost:40526/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40526/tests/dromaeo/cssquery-ext.html) 04:21:58 INFO - PROCESS | 31587 | RSS: Main: 242360320 04:21:58 INFO - PROCESS | 31587 | 04:25:00 INFO - PROCESS | 31587 | Cycle 1(1): loaded http://localhost:40526/tests/dromaeo/cssquery-ext.html (next: http://localhost:40526/tests/dromaeo/cssquery-jquery.html) 04:25:01 INFO - PROCESS | 31587 | RSS: Main: 232157184 04:25:01 INFO - PROCESS | 31587 | 04:28:03 INFO - PROCESS | 31587 | Cycle 1(1): loaded http://localhost:40526/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40526/tests/dromaeo/cssquery-mootools.html) 04:28:03 INFO - PROCESS | 31587 | RSS: Main: 175685632 04:28:03 INFO - PROCESS | 31587 | 04:31:05 INFO - PROCESS | 31587 | Cycle 1(1): loaded http://localhost:40526/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40526/tests/dromaeo/cssquery-prototype.html) 04:31:06 INFO - PROCESS | 31587 | RSS: Main: 175521792 04:31:06 INFO - PROCESS | 31587 | 04:34:08 INFO - PROCESS | 31587 | Cycle 1(1): loaded http://localhost:40526/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40526/tests/dromaeo/cssquery-yui.html) 04:34:09 INFO - PROCESS | 31587 | RSS: Main: 177516544 04:34:09 INFO - PROCESS | 31587 | 04:37:12 INFO - PROCESS | 31587 | Cycle 1(1): loaded http://localhost:40526/tests/dromaeo/cssquery-yui.html (next: http://localhost:40526/tests/dromaeo/cssquery-dojo.html) 04:37:12 INFO - PROCESS | 31587 | RSS: Main: 254631936 04:37:12 INFO - PROCESS | 31587 | 04:37:12 INFO - PROCESS | 31587 | __start_tp_report 04:37:12 INFO - PROCESS | 31587 | _x_x_mozilla_page_load 04:37:12 INFO - PROCESS | 31587 | _x_x_mozilla_page_load_details 04:37:12 INFO - PROCESS | 31587 | |i|pagename|runs| 04:37:12 INFO - PROCESS | 31587 | |0;dojo.html;3051.3287076843094;3060.8163510189383;3098.767592430568;3105.8136511809294;3139.529070639404;5455.072637651599;5536.280283563136;5558.555315574755;5565.471280228372;5580.386157522669;5552.222688823568;5562.3047119110115;5563.054280772269;5564.081926482136;5567.025770490169;4949.50504949504;4968.577670897978;4992.6505144639705;5083;5088.796448142089;4884.731339776311;5042.445331013586;5101.132807422729;5107.565856902149;5110.259012443212;4845.248986407112;4889.657723959324;4902.852914412573;4959.2313191455205;4999.275105109757;4929.4824043475455;4947.257911313314;5009.348784658007;5010.298558201855;5032.572231360339;4855.393075865517;4949.455559888409;4960.751962401901;4972.229304457815;4992.176290911995;5303.7082960437165;5304.708241046741;5309.575233981311;5454.727263636842;5537.141743029837;5679.148127780824;5683.4600712932615;5789.218455508494;5807.2160258365;5816.389279125716;5741.942580574183;5852.736626851802;5875.412458754133;5885.264341957255;5885.970570147165;5457.154141108134;5509.559235261169;5537.695426751527;5548.750306236229;5608.411116832755;6104.664243466652;6131.96934015327;6134.846628834315;6135.723892424851;6136.325004249529;5610.046292130348;5869.325027621878;5871;5878.500327472213;5879.941200588025;5739.85650358736;5837.795677151279;5856.502197313276;5860.589758716849;5970.044792833126;5895.764169433174;5903.911441328383;5904.970475147683;5911.9704401478575;5912.083627037816;5860.531157507389;5912.231409916683;5917.112433135064;5919.378465261172;5935.792247271324;4444.044530425974;4478.910421791611;4504.346869703874;4530.116627257719;4547.7498737569085;4056.371262454324;4058.6955978301744;4062.6749860011128;4070.4911886014247;4102.220578090153;5542.085555883315;5548.195511650788;5552.416996215339;5554.972225138848;5585.078462053797;5741.942580574141;5794.652320860761;5882.647041177543;5901.940980590139;5965.224520812267;5655.886882262414;5667.631603945731;5701.287339082614;5702.54379649627;5709.65742055478;5187.3800598581765;5189.273501709764;5190.97404512975;5191.065608190562;5197.428282888879;5880.852978675567;5932.644041357532;6138.355472675394;6184.381561843779;6199.504039676815;5843.1235314701435;5883.94116058834;5888.911666324922;5955.017422125301;5999.340072591924;6100.054491553817;6132;6145.477634401126;6148.292946311231;6158.3841615838055;5579.609427340048;5868.23712917318;5870.882582348244;5872.823815285548;5875.14810352488;5746.281714785652;5903.881922361615;5950.821475355746;5953.910691339547;6048.667323297261;5876.853078673067;5890.322612899571;5891.381404952418;5891.882162356815;5895.823125306248;5881.353051164283;5889.94110058894;5891.086881533369;5896.439838215363;5896.557758168069;5481.5340696041285;5485.61600687948;5658.236138121301;5659.660420374791;5689.061304884648;5784.045632470764;5817.825465236049;5823.068309070529;5828.154917537018;5857.1799948008165;5463.808766693147;5678.77284908599;5678.943210568007;5683;5685.118806584986 04:37:12 INFO - PROCESS | 31587 | |1;ext.html;593.6675461741426;604.8094850122211;633.284388640836;643.1381948189426;644.6422235659209;37559.43660845086;38171.61828381715;46255.07489850201;48509;48624.270635940404;48743.268850967266;49283;49286.274980375696;49298.26052609214;49299.50700492994;9334.346595738283;9469.10043545862;9537.046295370443;9543.47510886901;9544.284178686592;8642.524661143634;8846.336524760636;10962.890371096266;11221.495032723506;11222.943885280562;11332;11422.42887855608;11427.714307142305;11431;11435.313881167114;11216.66350009501;11274.267172633834;11298.152638552141;11306.434678266054;11310.095192384671;8405.201505856934;8600.053994060649;8609.612567434418;8780;10741.946290268499;42684.359734604004;45327.77336113331;45338.773306133255;45356.546434535565;45359.773201134114;4070.61329173728;4128.710990230685;4453.710508816916;4477.126960242754;4576.0619073090265;34966.47550286748;34998.650013500046;35373;35584;35649.46525802115;4520.593146616788;4599.264117741179;4640.211164102111;4673.976630116862;4710.8351207707565;362390;362465;363199;363785;365127.0461886174;54994;58238.04976576954;62261.377386225555;65004.34995649983;65225;68415.55298905555;68721.31278687248;68847.65576172087;68864.3113568858;68885;17482.475525734248;17553.912230438767;17595.12024398775;17598.032108233914;17602.11989400525;12997.870021299856;13335.333233338295;15527.068375897483;15699.293531791096;15704.764428533581;18047.187876545326;18627.254909803727;18697;19247.13387897548;19318.613627727365;6803.523753337219;6920.612388725882;7080.079589653312;7103.360697537143;7245.9275407246305;1040.245821779219;1049.0925349572717;1092.317301686446;1129.1531351486385;1131.7396998690347;33696;34708.47937280943;34889.302213955576;35071;35230.82384588061;1134.6709454258173;1156.1791128299003;1177.2995067934564;1196.4077781498213;1228.8525376954644;18293.268269269345;18315.175817088264;18325.7251141233;18356.541086472946;18547.536311592317;513181;516469;516698;518094;518911;62511.6874415625;62732.686336568026;62951.37048629547;62976.68511657504;63185.68407157935;1327.5419980106847;1367.0294091195165;1406.5217825939026;1444.2562080528537;1452.1577485058851;33500.83249583736;35679;35710.4643430354;36326.455103174005;36586.63413365939;55532;56002;56193;56249;56282.71858640681;414251;416508;416673;417057;473261;28850.855745721135;30511.389772204875;30518.694813052472;30741.53887691731;30799.53800693036;51312;51486;52037.739811302214;52081.21878171754;52892.73553632207;43686.34470483009;44188;44596;44627.77686111549;45600;43914;44728.77635611801;45335.77240648664;45347.31979020251;45472.31791523063;43261.35107973446;44043.779781100886;44156.779216103714;44234.11531769282;44275.11449771051;36867.52529898774;39419.11610696024;39953.80023099866;40209.39685904655;40605.59394406018;29000.054606560105;29007.709922901344;29536.852315739143;29728.256793580334;29913.700862991092 04:37:12 INFO - PROCESS | 31587 | |2;jquery.html;391.3620798099098;395.23127516979486;416.47524119609295;422.21046642778;437.2479335542867;14697.265136743174;16466.91766541167;20470.488237794045;20616.072276747545;20878.791212087875;19681.933396993325;19962.60074798503;20034.1986320547;20163.89918050408;20525.4868628284;5647.689377084259;5716.514096301819;5771.971140144315;5814.069748840187;5933.080372542263;4231.090315582149;4436.844710435135;5798.246227990356;5875;5881.76472941084;6328.65192414417;6600.6699665016795;6615.603063816162;6617.338266173392;6624.27133015368;6323.272823625296;6520.347965203489;6554.049662798915;6559.573627714183;6580.473562115019;5629.408912064216;5831.504322132624;5831.79588714393;5836.795712150097;5838.9124163137585;23693.881530592236;23935.162269320663;24017.87991060051;24093;24215.515689686283;2501.7498250175017;2601.9609705854427;2635.9604605930926;2753.408017276295;3052.6489453712884;14920.253987300592;15105.924470377688;15121.16833574153;15270.38918443261;15335.463258786;1929.9131539080636;1942.9708554371696;1952.6485232658115;1979.633767752956;1992.7110568967564;63109.36890631081;69205;84445.73331400033;84656.57671711664;85152.99999999937;11096.778064438666;11115.388653623966;11251.83122253167;11269.943650281695;11336.206465547333;15918.204089795463;15925.761113583305;15928.840711592968;15931;15947.362105515882;8680.826383472295;9055.366124371363;9074.86387704185;9171.89937207526;9282.953585232166;9005.414648047856;9038.367314287936;9118.726438206864;9231.215346695471;9363.25093992479;6867.622280774605;6960.477964152708;6984.720611175598;6994.755183568653;7022;1985.4043786864172;2036.5926814637132;2043.8262747666317;2066.73132492775;2070.234013415046;964.5852283518014;1012.4077414712476;1059.8516207730925;1076.6016573867562;1089.624079692505;18701.625967480573;20266.89866550658;20440.59118817645;20483.26247080659;20672.17311307561;1003.1673710820002;1065.1106326217541;1069.8181309177457;1078.757279612081;1090.0734375780683;10501.684949451528;10903.890961090447;10978.066864316463;11008.643809257961;11089.500972456259;515048;515118;517022;518384;530387;81894.18105818865;98939;100488.49755751174;102208;102773;1080.5353697909975;1103.939283339408;1129.1362107987395;1169.9590514332128;1171.6777886081225;17106.401275955282;17177.29683958887;17450.389236376664;17610.559736006704;17688.73466897972;40907.18185636211;42013.57986420219;42792.57207427886;44245.336319954586;45045.0990980172;60213;62306.37693623187;63278.683606583516;63501.364986349545;65092.67453662701;5696.344920334214;7065.293470652894;7465.962670186615;8321.084680684999;8423.157684231528;12730.745385092432;13424.127431716906;13725.107867988549;13893.444262229397;14063.648408789863;11650.41747912618;11663.358515281741;11919.82120268214;11999;12551.184173028723;11729.589464369048;12111.878881211074;12201.938990305347;12406.131570789958;12597.93701031489;12174.269543827399;12200.572979946015;12219.45012474423;12267.325297108744;12495.25028498293;8058.879116813136;8248.46384984974;8291.673332266808;8400.327973762207;8478.957605212181;9496.335256531976;12068;12113.15207935436;12117.15179937396;12130.939345303217 04:37:12 INFO - PROCESS | 31587 | |3;mootools.html;1346.8753590751721;1483.1175450606886;1501.06183635228;1666.8583170430513;1774.7781527309087;7154.391876690481;7798.844023119541;8103.716369927054;8108.472949258301;8293.792655183623;8092.878606820902;8136.105028446881;8153.429259951806;8156.510609363442;8159.55122468264;2317.6639387288874;2334.5214231082587;2334.9066037358484;2337.2170322941834;2342.61346877765;2269.693591365161;2269.9773002270013;2323.1288266899915;2328.045501344449;2329.359426157812;2276.8064714499287;2289.610766169755;2298.3449716830705;2299.9310020699404;2301.6432452969816;2239.921602743896;2241.3388050525054;2246.696695946051;2246.7416247131628;2273.9090436382526;2258.435391152212;2278.065992942902;2279.430142464384;2279.430142464384;2280.5552917181158;7964.442489025771;7988.201179882023;8053.1946805319585;8054.194580541957;8066.11272759996;4901.950980490221;5052.191649336125;5059.3169922060415;5064.03783281179;5068.898622027576;3205.2147223930288;3311.437055700537;3345.364380767671;3347;3368.410528157587;1134.7106487845629;1136.4658610453073;1138.4649214869082;1154.463174623804;1158.1776938373764;10870;10884.6190383337;10961.12311015117;10967.396793176373;10973.615923442641;4120.690948178899;4197.706160568731;4322.481302243751;4352.499462561783;4360.4331436913;8823.735287941372;8825;8825.852639156998;8827.823443531093;8837.116288371111;4959.330490383752;4974.427940786783;4980.477049909779;4986.077575648516;4989.950100499022;4056.6146216110014;4176.0395109125075;4576.061907308994;4664.95335046652;4691.155591993405;3694.2980833641523;3763.8870833875026;3779.055236190952;3808.8476460941806;3875.186210895687;1111.6053800900725;1114.5151858941224;1121.5681962444517;1130.4630300607146;1134.9886501134883;990.4799980010408;1013.8783345998528;1029.330934892311;1030.4332617060586;1038.657243109772;5748.827535173951;5828.708564571754;5863.530917526673;5889.116632505158;5938.435848594377;440.7289516947055;447.53456405338085;449.17352072187333;1028.1671845804772;1034.8809886863107;4064.7967601620394;4168.562300958356;4188.099558594856;4193.958060419357;4200.180964711852;9720.805583888283;10011.599536018623;10079.244056695778;10097.596096156218;10109.848352274721;9969.601215951427;9995.100440960348;9996.350237234556;9998.950005250073;10048.09567138961;7565.167831538526;7717.112532058773;7725.5364678119495;7726.1501234863;7731.7680469586;5659.349174844946;5662.094064949588;5760.8271751847515;5831.35855055952;5844.707764611838;9176.6788162414;9228.815423691623;9244.168024877794;9361.953190234006;9370.578323975302;9130.95434522823;9404.38871473352;9422.340436169403;9478.715638530855;9567.808643827224;6901.516893817385;6938.306169383021;6966;7010.053642758162;7028.121484814398;7826.882596761167;7890.447668663139;7890.921090789018;7893.329067029137;7898.802529936797;7071.71713131469;7203.063601731741;7216.5670059796585;7222.674979626035;7356.301151390609;6917.308269173243;6996.300369962963;7100.538464999759;7122.287771222836;7167.3549380556;6699.29657385987;6795.25252222265;6855.245922948571;6855.862882742417;6858.485613578901;4630.398048253705;4661.34741136248;4671.602913752369;4711.458182309078;4786.832460863853;5214.452482489284;5316.8936621266585;5336.066188416934;5363.704996225245;5380.650257733235 04:37:12 INFO - PROCESS | 31587 | |4;prototype.html;323.6989599672305;325.9837008149592;327.10699789574466;328.17136729757374;334.04463235147483;5476.835694929148;5514.6415482993625;5524.917126243109;5527.14840528423;5556.138798486231;5438.872648227275;5457.781688732456;5463.153211252253;5468.72656367182;5499.780008799653;4080.795960201993;4178.8328466861285;4180.205760905434;4242.918055895742;4251;4235.555266696999;4252.87241382759;4292.420523229355;4307.203167414023;4312.568743125678;4208.253574766468;4220.683448741342;4221.2190744712325;4225.873223803291;4228.894277643053;4023.859164929228;4183.644390226835;4183.937240941388;4207.747535147901;4227.217964676544;3687.557493100818;3688.8708895188806;3690.409534474471;3694.298083364179;3720.9441858372147;5229.294045303911;5240.371155461331;5246;5253.894922101575;5286.709230992294;4650.720956742606;4793.424789025305;4804.183288840905;4853.39332583427;4960.776765045582;5695.77216911323;5704.458076482728;5721.5696075981;5722.942770572325;5730.106296320373;4215.346621273708;4215.747055176699;4244.639205667522;4245.299525578276;4248.936265956013;5844.736986835603;5917.378675239081;5948.643081415129;5978.880422391571;6026.9095963560585;4269.87190384289;4271.124419493996;4271.231178387858;4285.914281714348;4301.526832048472;4351.8694439166875;4361.629261512743;4361.825526978885;4362.149380870763;4373.365861950063;4683.695559788603;4870.634702397264;4924.827631032968;4961.231009193581;4966.255061740768;5705.914411283834;5706.771729130871;5714.799892022722;5735.2257445245195;5744.971275143597;1502.4440956846038;1519.7720341948575;1521.9422501361669;1521.9498546003222;1529.2277399912973;913.4656226107801;964.252704154286;976.7985377985118;981.6318880419842;1003.4481035430484;5499.51513091463;5547.861303467364;5551.417101204315;5551.861203469945;5551.9735296616445;5847.327557330961;5861.677607731615;5863;5870.354261031283;5905.143754155623;5289.3917199521775;5437.646552974044;5452.454754524516;5506.972465137729;5524.779008839681;3039.6960303969427;3076.8153910765427;3083.8612262448246;3087.1664650544226;3101.6278046634097;5855.033919403336;5896.292444906553;5952.7023648817385;5959.629285264414;5984.354302566829;5881.176635271066;5903.114532820111;5917.319508256605;5925.933332000195;5944.711286154861;5965.910511342333;6003.939960600425;6019.2175017248355;6025.9096113558335;6030.909536356959;5574.9163762542785;5758.568107391879;5761.711914404179;5779.8555036124435;5817.883642327045;5717.571182161271;5743.6789538405565;5752;5753.280839895013;5762.279715035621;5795.7391917362975;5998.400159983967;6000.399960003964;6001.839337838462;6011.519078473799;4119.835206591702;4142.5857414259535;4161.4382058421725;4178.540360560275;4181.393697913724;5662.971685141712;5803.8549036274435;5828.213191219301;5840.561957853093;5900.793472228452;5590.776368945197;5704.657720536781;5713.342965559014;5782.306123265235;5794.420557944172;5759.337676167294;5759.8560035999435;5762.7694892203845;5773.595848290579;5798.246227990503;5538.627531294638;5625.409332020079;5661.433856614306;5689.7198130421975;5754.4533269339345;5742.942570574408;5743.253377060955;5746.362286748256;5747.224124743107;5752.68360240174;5392.379876314118;5641.802536911188;5643.125315576092;5649.152627105967;5650.802221922378 04:37:12 INFO - PROCESS | 31587 | |5;yui.html;816.2286639126029;817.8159914019344;830.6677329068372;908.5502676175286;919.4850883505236;16571.58571035723;17980.190891409886;17989.37037203698;18028.18873150708;18089.4573162805;16456.753148702777;16477.588060298498;16481.83518164818;16606.25271862766;16655.583610409747;804.8873157757904;843.6372359885247;849.8512760266959;850.3962186847315;850.6257246811414;846.8306338732248;848.5248260973843;853.9530325832077;855.0466230153396;855.1576696953496;897.2552781191596;899.8695189197563;901.400568621869;901.4816480523692;901.9278457723399;898.5013317608724;900.6037343568842;900.7928176519438;901.1394118616738;901.1574178143425;651.3486513486514;659.4691273524811;660.0165753027953;660.5970358081566;662.78459500662;15744.60638484047;15756.921215393964;15758.606034849106;15802.525924222291;15818.920905395515;15517.844821551753;15585;15616.297266622916;15675;15951.169484891545;13490.123141995738;13526.391312390966;13540.59378218655;13677.931610341884;13813.171209727449;4091.3862920561855;4147.854825081107;4150.460440142792;4237.512686041114;4253.51084625269;90757.54621226918;91695;92570.53714731384;94437.5278123605;94737;12109.152359334763;12272.754544909052;12286.815697764541;12330.50677972871;12610.243385396905;23213.006447504387;23432;23519;23619.763802361755;23652.408689782547;8671.913280867237;8697;8874.156955089256;8917.509536975529;9103.817923641622;4264.722793018506;4308.418363520885;4448.621867141264;4453.487848897418;4580.106879158532;3562.305350456688;3690.55713314404;3695.3902606069696;3696.482492451059;3704.388775852008;584.5031723035386;587.49475451112;589.7434615942079;594.349187639534;609.9390060993954;725.151572659989;738.6269933683456;789.1989630525022;816.7549735079452;821.3103180837367;10745.623903163352;10861.565537378416;11130.666080017612;11137.276077055127;11276.774464510829;717.9569225846466;781.8670825959487;786.9842603147905;819.1521774962885;835.774340927946;5067.797288108434;5466.863328416822;5480.877376392944;5502.5597952163735;5562.666240025612;129629;133777;134257;135503.32248338696;137416;29950;83127.5843620778;92763;110485;110490;60752.69623651942;63585.68207158935;90927;94702;95294;10788.244822862323;11265.436728163722;11390.886091138982;11507.367094809866;11536.480858361227;54155.729221355214;54691.45308546864;55435;57159.714201428724;57589.136162956755;74889;81969.59015204886;82944.58527707565;83047;84902;13746.243956582084;13851.30743462843;13969.790453143009;14356.138631682132;14428.927855360656;15928.283227255033;16024.759628605814;16366.345346186019;16767.1616419181;16917.069561174252;14524.49164279245;15113.773293400827;15655.138967356905;16526.09106499106;16662.166891655612;13197.406116724962;13682.31588420595;15007.774883376977;15305;16267.26797294101;13337;13492.5277615283;13511.527096551574;13665.31673416345;14535.709285814435;12956.416961236579;12964.416601252778;13002.154859934075;13267.93856491459;13913.791293130813;12778.808317875053;12903.354832258537;13057.28184949837;13101.148425352321;13672 04:37:12 INFO - PROCESS | 31587 | __end_tp_report 04:37:12 INFO - PROCESS | 31587 | __start_cc_report 04:37:12 INFO - PROCESS | 31587 | _x_x_mozilla_cycle_collect,864 04:37:12 INFO - PROCESS | 31587 | __end_cc_report 04:37:12 INFO - PROCESS | 31587 | __startTimestamp1475235432533__endTimestamp 04:37:12 INFO - PROCESS | 31587 | 04:37:12 INFO - PROCESS | 31587 | ------- Summary: start ------- 04:37:12 INFO - PROCESS | 31587 | Number of tests: 6 04:37:12 INFO - PROCESS | 31587 | 04:37:12 INFO - PROCESS | 31587 | [#0] dojo.html Cycles:165 Average:5478.65 Median:5683.00 stddev:637.62 (11.2%) stddev-sans-first:610.48 04:37:12 INFO - PROCESS | 31587 | Values: 3051.3 3060.8 3098.8 3105.8 3139.5 5455.1 5536.3 5558.6 5565.5 5580.4 5552.2 5562.3 5563.1 5564.1 5567.0 4949.5 4968.6 4992.7 5083.0 5088.8 4884.7 5042.4 5101.1 5107.6 5110.3 4845.2 4889.7 4902.9 4959.2 4999.3 4929.5 4947.3 5009.3 5010.3 5032.6 4855.4 4949.5 4960.8 4972.2 4992.2 5303.7 5304.7 5309.6 5454.7 5537.1 5679.1 5683.5 5789.2 5807.2 5816.4 5741.9 5852.7 5875.4 5885.3 5886.0 5457.2 5509.6 5537.7 5548.8 5608.4 6104.7 6132.0 6134.8 6135.7 6136.3 5610.0 5869.3 5871.0 5878.5 5879.9 5739.9 5837.8 5856.5 5860.6 5970.0 5895.8 5903.9 5905.0 5912.0 5912.1 5860.5 5912.2 5917.1 5919.4 5935.8 4444.0 4478.9 4504.3 4530.1 4547.7 4056.4 4058.7 4062.7 4070.5 4102.2 5542.1 5548.2 5552.4 5555.0 5585.1 5741.9 5794.7 5882.6 5901.9 5965.2 5655.9 5667.6 5701.3 5702.5 5709.7 5187.4 5189.3 5191.0 5191.1 5197.4 5880.9 5932.6 6138.4 6184.4 6199.5 5843.1 5883.9 5888.9 5955.0 5999.3 6100.1 6132.0 6145.5 6148.3 6158.4 5579.6 5868.2 5870.9 5872.8 5875.1 5746.3 5903.9 5950.8 5953.9 6048.7 5876.9 5890.3 5891.4 5891.9 5895.8 5881.4 5889.9 5891.1 5896.4 5896.6 5481.5 5485.6 5658.2 5659.7 5689.1 5784.0 5817.8 5823.1 5828.2 5857.2 5463.8 5678.8 5678.9 5683.0 5685.1 04:37:12 INFO - PROCESS | 31587 | 04:37:12 INFO - PROCESS | 31587 | [#1] ext.html Cycles:180 Average:61818.86 Median:416865.00 stddev:116303.28 (27.9%) stddev-sans-first:116538.69 04:37:12 INFO - PROCESS | 31587 | Values: 593.7 604.8 633.3 643.1 644.6 37559.4 38171.6 46255.1 48509.0 48624.3 48743.3 49283.0 49286.3 49298.3 49299.5 9334.3 9469.1 9537.0 9543.5 9544.3 8642.5 8846.3 10962.9 11221.5 11222.9 11332.0 11422.4 11427.7 11431.0 11435.3 11216.7 11274.3 11298.2 11306.4 11310.1 8405.2 8600.1 8609.6 8780.0 10741.9 42684.4 45327.8 45338.8 45356.5 45359.8 4070.6 4128.7 4453.7 4477.1 4576.1 34966.5 34998.7 35373.0 35584.0 35649.5 4520.6 4599.3 4640.2 4674.0 4710.8 362390.0 362465.0 363199.0 363785.0 365127.0 54994.0 58238.0 62261.4 65004.3 65225.0 68415.6 68721.3 68847.7 68864.3 68885.0 17482.5 17553.9 17595.1 17598.0 17602.1 12997.9 13335.3 15527.1 15699.3 15704.8 18047.2 18627.3 18697.0 19247.1 19318.6 6803.5 6920.6 7080.1 7103.4 7245.9 1040.2 1049.1 1092.3 1129.2 1131.7 33696.0 34708.5 34889.3 35071.0 35230.8 1134.7 1156.2 1177.3 1196.4 1228.9 18293.3 18315.2 18325.7 18356.5 18547.5 513181.0 516469.0 516698.0 518094.0 518911.0 62511.7 62732.7 62951.4 62976.7 63185.7 1327.5 1367.0 1406.5 1444.3 1452.2 33500.8 35679.0 35710.5 36326.5 36586.6 55532.0 56002.0 56193.0 56249.0 56282.7 414251.0 416508.0 416673.0 417057.0 473261.0 28850.9 30511.4 30518.7 30741.5 30799.5 51312.0 51486.0 52037.7 52081.2 52892.7 43686.3 44188.0 44596.0 44627.8 45600.0 43914.0 44728.8 45335.8 45347.3 45472.3 43261.4 44043.8 44156.8 44234.1 44275.1 36867.5 39419.1 39953.8 40209.4 40605.6 29000.1 29007.7 29536.9 29728.3 29913.7 04:37:12 INFO - PROCESS | 31587 | 04:37:12 INFO - PROCESS | 31587 | [#2] jquery.html Cycles:180 Average:30437.01 Median:22286.34 stddev:85499.37 (383.6%) stddev-sans-first:85709.45 04:37:12 INFO - PROCESS | 31587 | Values: 391.4 395.2 416.5 422.2 437.2 14697.3 16466.9 20470.5 20616.1 20878.8 19681.9 19962.6 20034.2 20163.9 20525.5 5647.7 5716.5 5772.0 5814.1 5933.1 4231.1 4436.8 5798.2 5875.0 5881.8 6328.7 6600.7 6615.6 6617.3 6624.3 6323.3 6520.3 6554.0 6559.6 6580.5 5629.4 5831.5 5831.8 5836.8 5838.9 23693.9 23935.2 24017.9 24093.0 24215.5 2501.7 2602.0 2636.0 2753.4 3052.6 14920.3 15105.9 15121.2 15270.4 15335.5 1929.9 1943.0 1952.6 1979.6 1992.7 63109.4 69205.0 84445.7 84656.6 85153.0 11096.8 11115.4 11251.8 11269.9 11336.2 15918.2 15925.8 15928.8 15931.0 15947.4 8680.8 9055.4 9074.9 9171.9 9283.0 9005.4 9038.4 9118.7 9231.2 9363.3 6867.6 6960.5 6984.7 6994.8 7022.0 1985.4 2036.6 2043.8 2066.7 2070.2 964.6 1012.4 1059.9 1076.6 1089.6 18701.6 20266.9 20440.6 20483.3 20672.2 1003.2 1065.1 1069.8 1078.8 1090.1 10501.7 10903.9 10978.1 11008.6 11089.5 515048.0 515118.0 517022.0 518384.0 530387.0 81894.2 98939.0 100488.5 102208.0 102773.0 1080.5 1103.9 1129.1 1170.0 1171.7 17106.4 17177.3 17450.4 17610.6 17688.7 40907.2 42013.6 42792.6 44245.3 45045.1 60213.0 62306.4 63278.7 63501.4 65092.7 5696.3 7065.3 7466.0 8321.1 8423.2 12730.7 13424.1 13725.1 13893.4 14063.6 11650.4 11663.4 11919.8 11999.0 12551.2 11729.6 12111.9 12201.9 12406.1 12597.9 12174.3 12200.6 12219.5 12267.3 12495.3 8058.9 8248.5 8291.7 8400.3 8479.0 9496.3 12068.0 12113.2 12117.2 12130.9 04:37:12 INFO - PROCESS | 31587 | 04:37:12 INFO - PROCESS | 31587 | [#3] mootools.html Cycles:180 Average:5408.34 Median:4966.88 stddev:2950.03 (59.4%) stddev-sans-first:2942.51 04:37:12 INFO - PROCESS | 31587 | Values: 1346.9 1483.1 1501.1 1666.9 1774.8 7154.4 7798.8 8103.7 8108.5 8293.8 8092.9 8136.1 8153.4 8156.5 8159.6 2317.7 2334.5 2334.9 2337.2 2342.6 2269.7 2270.0 2323.1 2328.0 2329.4 2276.8 2289.6 2298.3 2299.9 2301.6 2239.9 2241.3 2246.7 2246.7 2273.9 2258.4 2278.1 2279.4 2279.4 2280.6 7964.4 7988.2 8053.2 8054.2 8066.1 4902.0 5052.2 5059.3 5064.0 5068.9 3205.2 3311.4 3345.4 3347.0 3368.4 1134.7 1136.5 1138.5 1154.5 1158.2 10870.0 10884.6 10961.1 10967.4 10973.6 4120.7 4197.7 4322.5 4352.5 4360.4 8823.7 8825.0 8825.9 8827.8 8837.1 4959.3 4974.4 4980.5 4986.1 4990.0 4056.6 4176.0 4576.1 4665.0 4691.2 3694.3 3763.9 3779.1 3808.8 3875.2 1111.6 1114.5 1121.6 1130.5 1135.0 990.5 1013.9 1029.3 1030.4 1038.7 5748.8 5828.7 5863.5 5889.1 5938.4 440.7 447.5 449.2 1028.2 1034.9 4064.8 4168.6 4188.1 4194.0 4200.2 9720.8 10011.6 10079.2 10097.6 10109.8 9969.6 9995.1 9996.4 9999.0 10048.1 7565.2 7717.1 7725.5 7726.2 7731.8 5659.3 5662.1 5760.8 5831.4 5844.7 9176.7 9228.8 9244.2 9362.0 9370.6 9131.0 9404.4 9422.3 9478.7 9567.8 6901.5 6938.3 6966.0 7010.1 7028.1 7826.9 7890.4 7890.9 7893.3 7898.8 7071.7 7203.1 7216.6 7222.7 7356.3 6917.3 6996.3 7100.5 7122.3 7167.4 6699.3 6795.3 6855.2 6855.9 6858.5 4630.4 4661.3 4671.6 4711.5 4786.8 5214.5 5316.9 5336.1 5363.7 5380.7 04:37:12 INFO - PROCESS | 31587 | 04:37:12 INFO - PROCESS | 31587 | [#4] prototype.html Cycles:180 Average:4811.89 Median:5526.03 stddev:1405.61 (25.4%) stddev-sans-first:1368.59 04:37:12 INFO - PROCESS | 31587 | Values: 323.7 326.0 327.1 328.2 334.0 5476.8 5514.6 5524.9 5527.1 5556.1 5438.9 5457.8 5463.2 5468.7 5499.8 4080.8 4178.8 4180.2 4242.9 4251.0 4235.6 4252.9 4292.4 4307.2 4312.6 4208.3 4220.7 4221.2 4225.9 4228.9 4023.9 4183.6 4183.9 4207.7 4227.2 3687.6 3688.9 3690.4 3694.3 3720.9 5229.3 5240.4 5246.0 5253.9 5286.7 4650.7 4793.4 4804.2 4853.4 4960.8 5695.8 5704.5 5721.6 5722.9 5730.1 4215.3 4215.7 4244.6 4245.3 4248.9 5844.7 5917.4 5948.6 5978.9 6026.9 4269.9 4271.1 4271.2 4285.9 4301.5 4351.9 4361.6 4361.8 4362.1 4373.4 4683.7 4870.6 4924.8 4961.2 4966.3 5705.9 5706.8 5714.8 5735.2 5745.0 1502.4 1519.8 1521.9 1521.9 1529.2 913.5 964.3 976.8 981.6 1003.4 5499.5 5547.9 5551.4 5551.9 5552.0 5847.3 5861.7 5863.0 5870.4 5905.1 5289.4 5437.6 5452.5 5507.0 5524.8 3039.7 3076.8 3083.9 3087.2 3101.6 5855.0 5896.3 5952.7 5959.6 5984.4 5881.2 5903.1 5917.3 5925.9 5944.7 5965.9 6003.9 6019.2 6025.9 6030.9 5574.9 5758.6 5761.7 5779.9 5817.9 5717.6 5743.7 5752.0 5753.3 5762.3 5795.7 5998.4 6000.4 6001.8 6011.5 4119.8 4142.6 4161.4 4178.5 4181.4 5663.0 5803.9 5828.2 5840.6 5900.8 5590.8 5704.7 5713.3 5782.3 5794.4 5759.3 5759.9 5762.8 5773.6 5798.2 5538.6 5625.4 5661.4 5689.7 5754.5 5742.9 5743.3 5746.4 5747.2 5752.7 5392.4 5641.8 5643.1 5649.2 5650.8 04:37:12 INFO - PROCESS | 31587 | 04:37:12 INFO - PROCESS | 31587 | [#5] yui.html Cycles:180 Average:22299.30 Median:3695.94 stddev:32286.80 (873.6%) stddev-sans-first:32337.07 04:37:12 INFO - PROCESS | 31587 | Values: 816.2 817.8 830.7 908.6 919.5 16571.6 17980.2 17989.4 18028.2 18089.5 16456.8 16477.6 16481.8 16606.3 16655.6 804.9 843.6 849.9 850.4 850.6 846.8 848.5 854.0 855.0 855.2 897.3 899.9 901.4 901.5 901.9 898.5 900.6 900.8 901.1 901.2 651.3 659.5 660.0 660.6 662.8 15744.6 15756.9 15758.6 15802.5 15818.9 15517.8 15585.0 15616.3 15675.0 15951.2 13490.1 13526.4 13540.6 13677.9 13813.2 4091.4 4147.9 4150.5 4237.5 4253.5 90757.5 91695.0 92570.5 94437.5 94737.0 12109.2 12272.8 12286.8 12330.5 12610.2 23213.0 23432.0 23519.0 23619.8 23652.4 8671.9 8697.0 8874.2 8917.5 9103.8 4264.7 4308.4 4448.6 4453.5 4580.1 3562.3 3690.6 3695.4 3696.5 3704.4 584.5 587.5 589.7 594.3 609.9 725.2 738.6 789.2 816.8 821.3 10745.6 10861.6 11130.7 11137.3 11276.8 718.0 781.9 787.0 819.2 835.8 5067.8 5466.9 5480.9 5502.6 5562.7 129629.0 133777.0 134257.0 135503.3 137416.0 29950.0 83127.6 92763.0 110485.0 110490.0 60752.7 63585.7 90927.0 94702.0 95294.0 10788.2 11265.4 11390.9 11507.4 11536.5 54155.7 54691.5 55435.0 57159.7 57589.1 74889.0 81969.6 82944.6 83047.0 84902.0 13746.2 13851.3 13969.8 14356.1 14428.9 15928.3 16024.8 16366.3 16767.2 16917.1 14524.5 15113.8 15655.1 16526.1 16662.2 13197.4 13682.3 15007.8 15305.0 16267.3 13337.0 13492.5 13511.5 13665.3 14535.7 12956.4 12964.4 13002.2 13267.9 13913.8 12778.8 12903.4 13057.3 13101.1 13672.0 04:37:12 INFO - PROCESS | 31587 | -------- Summary: end -------- 04:37:12 INFO - PROCESS | 31587 | 04:37:13 INFO - TEST-INFO | 31587: exit 0 04:37:13 INFO - TEST-OK | dromaeo_css | took 1113958ms 04:37:13 INFO - TEST-START | kraken 04:37:13 INFO - operating with platform_type : linux_ 04:37:13 INFO - Initialising browser for kraken test... 04:37:13 INFO - TEST-INFO | started process 31904 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbInE7U/profile http://localhost:40526/getInfo.html) 04:37:17 INFO - PROCESS | 31904 | __metrics Screen width/height:1600/1200 04:37:17 INFO - PROCESS | 31904 | colorDepth:24 04:37:17 INFO - PROCESS | 31904 | Browser inner width/height: 1024/697 04:37:17 INFO - PROCESS | 31904 | __metrics 04:37:17 INFO - PROCESS | 31904 | 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" 04:37:17 INFO - PROCESS | 31904 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:37:18 INFO - PROCESS | 31904 | console.error: PushService: 04:37:18 INFO - PROCESS | 31904 | stateChangeProcessEnqueue: Error transitioning state 04:37:18 INFO - PROCESS | 31904 | UnknownError 04:37:18 INFO - TEST-INFO | 31904: exit 0 04:37:18 INFO - Browser initialized. 04:37:18 INFO - Running cycle 1/1 for kraken test... 04:37:18 INFO - TEST-INFO | started process 31998 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbInE7U/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:37:30 INFO - PROCESS | 31998 | RSS: Main: 148131840 04:37:30 INFO - PROCESS | 31998 | 04:37:33 INFO - PROCESS | 31998 | Cycle 1(1): loaded http://localhost:40526/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40526/tests/kraken/driver.html?testName=audio-beat-detection) 04:37:34 INFO - PROCESS | 31998 | RSS: Main: 190296064 04:37:34 INFO - PROCESS | 31998 | 04:37:36 INFO - PROCESS | 31998 | Cycle 1(1): loaded http://localhost:40526/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40526/tests/kraken/driver.html?testName=audio-dft) 04:37:37 INFO - PROCESS | 31998 | RSS: Main: 178778112 04:37:37 INFO - PROCESS | 31998 | 04:37:40 INFO - PROCESS | 31998 | Cycle 1(1): loaded http://localhost:40526/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40526/tests/kraken/driver.html?testName=audio-fft) 04:37:40 INFO - PROCESS | 31998 | RSS: Main: 270151680 04:37:40 INFO - PROCESS | 31998 | 04:37:42 INFO - PROCESS | 31998 | Cycle 1(1): loaded http://localhost:40526/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40526/tests/kraken/driver.html?testName=audio-oscillator) 04:37:43 INFO - PROCESS | 31998 | RSS: Main: 176259072 04:37:43 INFO - PROCESS | 31998 | 04:37:45 INFO - PROCESS | 31998 | Cycle 1(1): loaded http://localhost:40526/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40526/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:37:46 INFO - PROCESS | 31998 | RSS: Main: 177188864 04:37:46 INFO - PROCESS | 31998 | 04:37:48 INFO - PROCESS | 31998 | Cycle 1(1): loaded http://localhost:40526/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40526/tests/kraken/driver.html?testName=imaging-darkroom) 04:37:49 INFO - PROCESS | 31998 | RSS: Main: 213590016 04:37:49 INFO - PROCESS | 31998 | 04:37:52 INFO - PROCESS | 31998 | Cycle 1(1): loaded http://localhost:40526/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40526/tests/kraken/driver.html?testName=imaging-desaturate) 04:37:53 INFO - PROCESS | 31998 | RSS: Main: 212959232 04:37:53 INFO - PROCESS | 31998 | 04:37:56 INFO - PROCESS | 31998 | Cycle 1(1): loaded http://localhost:40526/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40526/tests/kraken/driver.html?testName=json-parse-financial) 04:37:56 INFO - PROCESS | 31998 | RSS: Main: 212815872 04:37:56 INFO - PROCESS | 31998 | 04:37:58 INFO - PROCESS | 31998 | Cycle 1(1): loaded http://localhost:40526/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40526/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:37:58 INFO - PROCESS | 31998 | RSS: Main: 174764032 04:37:58 INFO - PROCESS | 31998 | 04:38:00 INFO - PROCESS | 31998 | Cycle 1(1): loaded http://localhost:40526/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40526/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:38:01 INFO - PROCESS | 31998 | RSS: Main: 182296576 04:38:01 INFO - PROCESS | 31998 | 04:38:03 INFO - PROCESS | 31998 | Cycle 1(1): loaded http://localhost:40526/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40526/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:38:03 INFO - PROCESS | 31998 | RSS: Main: 184623104 04:38:03 INFO - PROCESS | 31998 | 04:38:06 INFO - PROCESS | 31998 | Cycle 1(1): loaded http://localhost:40526/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40526/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:38:07 INFO - PROCESS | 31998 | RSS: Main: 185294848 04:38:07 INFO - PROCESS | 31998 | 04:38:09 INFO - PROCESS | 31998 | Cycle 1(1): loaded http://localhost:40526/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40526/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:38:10 INFO - PROCESS | 31998 | RSS: Main: 182550528 04:38:10 INFO - PROCESS | 31998 | 04:38:11 INFO - PROCESS | 31998 | Cycle 1(1): loaded http://localhost:40526/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40526/tests/kraken/driver.html?testName=ai-astar) 04:38:12 INFO - PROCESS | 31998 | RSS: Main: 234598400 04:38:12 INFO - PROCESS | 31998 | 04:38:12 INFO - PROCESS | 31998 | __start_tp_report 04:38:12 INFO - PROCESS | 31998 | _x_x_mozilla_page_load 04:38:12 INFO - PROCESS | 31998 | _x_x_mozilla_page_load_details 04:38:12 INFO - PROCESS | 31998 | |i|pagename|runs| 04:38:12 INFO - PROCESS | 31998 | |0;ai-astar;109;112;110;110;111;110;113;111;109;110 04:38:12 INFO - PROCESS | 31998 | |1;audio-beat-detection;141;116;116;160;122;117;119;119;154;122 04:38:12 INFO - PROCESS | 31998 | |2;audio-dft;162;159;161;163;160;161;161;161;161;162 04:38:12 INFO - PROCESS | 31998 | |3;audio-fft;74;75;75;108;77;76;76;77;99;76 04:38:12 INFO - PROCESS | 31998 | |4;audio-oscillator;81;82;109;81;82;82;117;82;82;113 04:38:12 INFO - PROCESS | 31998 | |5;imaging-gaussian-blur;117;116;117;117;117;117;117;116;118;116 04:38:12 INFO - PROCESS | 31998 | |6;imaging-darkroom;146;145;145;146;146;145;145;146;145;146 04:38:12 INFO - PROCESS | 31998 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;95 04:38:12 INFO - PROCESS | 31998 | |8;json-parse-financial;50;49;49;49;50;50;52;48;49;50 04:38:12 INFO - PROCESS | 31998 | |9;json-stringify-tinderbox;59;60;59;59;59;58;59;59;58;60 04:38:12 INFO - PROCESS | 31998 | |10;stanford-crypto-aes;74;73;72;78;74;74;75;74;73;73 04:38:12 INFO - PROCESS | 31998 | |11;stanford-crypto-ccm;120;119;116;116;126;126;123;116;167;128 04:38:12 INFO - PROCESS | 31998 | |12;stanford-crypto-pbkdf2;151;154;154;154;153;152;154;152;153;156 04:38:12 INFO - PROCESS | 31998 | |13;stanford-crypto-sha256-iterative;64;63;61;62;62;61;62;60;60;60 04:38:12 INFO - PROCESS | 31998 | __end_tp_report 04:38:12 INFO - PROCESS | 31998 | __start_cc_report 04:38:12 INFO - PROCESS | 31998 | _x_x_mozilla_cycle_collect,1584 04:38:12 INFO - PROCESS | 31998 | __end_cc_report 04:38:12 INFO - PROCESS | 31998 | __startTimestamp1475235492104__endTimestamp 04:38:12 INFO - PROCESS | 31998 | 04:38:12 INFO - PROCESS | 31998 | ------- Summary: start ------- 04:38:12 INFO - PROCESS | 31998 | Number of tests: 14 04:38:12 INFO - PROCESS | 31998 | 04:38:12 INFO - PROCESS | 31998 | [#0] ai-astar Cycles:10 Average:110.50 Median:110.50 stddev:1.27 (1.1%) stddev-sans-first:1.22 04:38:12 INFO - PROCESS | 31998 | Values: 109.0 112.0 110.0 110.0 111.0 110.0 113.0 111.0 109.0 110.0 04:38:12 INFO - PROCESS | 31998 | 04:38:12 INFO - PROCESS | 31998 | [#1] audio-beat-detection Cycles:10 Average:128.60 Median:122.00 stddev:16.69 (13.7%) stddev-sans-first:17.09 04:38:12 INFO - PROCESS | 31998 | Values: 141.0 116.0 116.0 160.0 122.0 117.0 119.0 119.0 154.0 122.0 04:38:12 INFO - PROCESS | 31998 | 04:38:12 INFO - PROCESS | 31998 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:1.10 (0.7%) stddev-sans-first:1.12 04:38:12 INFO - PROCESS | 31998 | Values: 162.0 159.0 161.0 163.0 160.0 161.0 161.0 161.0 161.0 162.0 04:38:12 INFO - PROCESS | 31998 | 04:38:12 INFO - PROCESS | 31998 | [#3] audio-fft Cycles:10 Average:81.30 Median:76.00 stddev:11.93 (15.7%) stddev-sans-first:12.35 04:38:12 INFO - PROCESS | 31998 | Values: 74.0 75.0 75.0 108.0 77.0 76.0 76.0 77.0 99.0 76.0 04:38:12 INFO - PROCESS | 31998 | 04:38:12 INFO - PROCESS | 31998 | [#4] audio-oscillator Cycles:10 Average:91.10 Median:82.00 stddev:15.23 (18.6%) stddev-sans-first:15.71 04:38:12 INFO - PROCESS | 31998 | Values: 81.0 82.0 109.0 81.0 82.0 82.0 117.0 82.0 82.0 113.0 04:38:12 INFO - PROCESS | 31998 | 04:38:12 INFO - PROCESS | 31998 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 04:38:12 INFO - PROCESS | 31998 | Values: 117.0 116.0 117.0 117.0 117.0 117.0 117.0 116.0 118.0 116.0 04:38:12 INFO - PROCESS | 31998 | 04:38:12 INFO - PROCESS | 31998 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 04:38:12 INFO - PROCESS | 31998 | Values: 146.0 145.0 145.0 146.0 146.0 145.0 145.0 146.0 145.0 146.0 04:38:12 INFO - PROCESS | 31998 | 04:38:12 INFO - PROCESS | 31998 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 04:38:12 INFO - PROCESS | 31998 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 04:38:12 INFO - PROCESS | 31998 | 04:38:12 INFO - PROCESS | 31998 | [#8] json-parse-financial Cycles:10 Average:49.60 Median:50.00 stddev:1.07 (2.1%) stddev-sans-first:1.13 04:38:12 INFO - PROCESS | 31998 | Values: 50.0 49.0 49.0 49.0 50.0 50.0 52.0 48.0 49.0 50.0 04:38:12 INFO - PROCESS | 31998 | 04:38:12 INFO - PROCESS | 31998 | [#9] json-stringify-tinderbox Cycles:10 Average:59.00 Median:59.00 stddev:0.67 (1.1%) stddev-sans-first:0.71 04:38:12 INFO - PROCESS | 31998 | Values: 59.0 60.0 59.0 59.0 59.0 58.0 59.0 59.0 58.0 60.0 04:38:12 INFO - PROCESS | 31998 | 04:38:12 INFO - PROCESS | 31998 | [#10] stanford-crypto-aes Cycles:10 Average:74.00 Median:74.00 stddev:1.63 (2.2%) stddev-sans-first:1.73 04:38:12 INFO - PROCESS | 31998 | Values: 74.0 73.0 72.0 78.0 74.0 74.0 75.0 74.0 73.0 73.0 04:38:12 INFO - PROCESS | 31998 | 04:38:12 INFO - PROCESS | 31998 | [#11] stanford-crypto-ccm Cycles:10 Average:125.70 Median:124.50 stddev:15.20 (12.2%) stddev-sans-first:15.98 04:38:12 INFO - PROCESS | 31998 | Values: 120.0 119.0 116.0 116.0 126.0 126.0 123.0 116.0 167.0 128.0 04:38:12 INFO - PROCESS | 31998 | 04:38:12 INFO - PROCESS | 31998 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.30 Median:154.00 stddev:1.42 (0.9%) stddev-sans-first:1.24 04:38:12 INFO - PROCESS | 31998 | Values: 151.0 154.0 154.0 154.0 153.0 152.0 154.0 152.0 153.0 156.0 04:38:12 INFO - PROCESS | 31998 | 04:38:12 INFO - PROCESS | 31998 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.50 Median:62.00 stddev:1.35 (2.2%) stddev-sans-first:1.09 04:38:12 INFO - PROCESS | 31998 | Values: 64.0 63.0 61.0 62.0 62.0 61.0 62.0 60.0 60.0 60.0 04:38:12 INFO - PROCESS | 31998 | -------- Summary: end -------- 04:38:12 INFO - PROCESS | 31998 | 04:38:13 INFO - TEST-INFO | 31998: exit 0 04:38:13 INFO - TEST-OK | kraken | took 59961ms 04:38:13 INFO - SUITE-END | took 1173s 04:38:13 INFO - Completed test suite (00:19:33) 04:38:13 INFO - javascript benchmark 04:38:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3051.3287076843094, 3060.8163510189383, 3098.767592430568, 3105.8136511809294, 3139.529070639404, 5455.072637651599, 5536.280283563136, 5558.555315574755, 5565.471280228372, 5580.386157522669, 5552.222688823568, 5562.3047119110115, 5563.054280772269, 5564.081926482136, 5567.025770490169, 4949.50504949504, 4968.577670897978, 4992.6505144639705, 5083.0, 5088.796448142089, 4884.731339776311, 5042.445331013586, 5101.132807422729, 5107.565856902149, 5110.259012443212, 4845.248986407112, 4889.657723959324, 4902.852914412573, 4959.2313191455205, 4999.275105109757, 4929.4824043475455, 4947.257911313314, 5009.348784658007, 5010.298558201855, 5032.572231360339, 4855.393075865517, 4949.455559888409, 4960.751962401901, 4972.229304457815, 4992.176290911995, 5303.7082960437165, 5304.708241046741, 5309.575233981311, 5454.727263636842, 5537.141743029837, 5679.148127780824, 5683.4600712932615, 5789.218455508494, 5807.2160258365, 5816.389279125716, 5741.942580574183, 5852.736626851802, 5875.412458754133, 5885.264341957255, 5885.970570147165, 5457.154141108134, 5509.559235261169, 5537.695426751527, 5548.750306236229, 5608.411116832755, 6104.664243466652, 6131.96934015327, 6134.846628834315, 6135.723892424851, 6136.325004249529, 5610.046292130348, 5869.325027621878, 5871.0, 5878.500327472213, 5879.941200588025, 5739.85650358736, 5837.795677151279, 5856.502197313276, 5860.589758716849, 5970.044792833126, 5895.764169433174, 5903.911441328383, 5904.970475147683, 5911.9704401478575, 5912.083627037816, 5860.531157507389, 5912.231409916683, 5917.112433135064, 5919.378465261172, 5935.792247271324, 4444.044530425974, 4478.910421791611, 4504.346869703874, 4530.116627257719, 4547.7498737569085, 4056.371262454324, 4058.6955978301744, 4062.6749860011128, 4070.4911886014247, 4102.220578090153, 5542.085555883315, 5548.195511650788, 5552.416996215339, 5554.972225138848, 5585.078462053797, 5741.942580574141, 5794.652320860761, 5882.647041177543, 5901.940980590139, 5965.224520812267, 5655.886882262414, 5667.631603945731, 5701.287339082614, 5702.54379649627, 5709.65742055478, 5187.3800598581765, 5189.273501709764, 5190.97404512975, 5191.065608190562, 5197.428282888879, 5880.852978675567, 5932.644041357532, 6138.355472675394, 6184.381561843779, 6199.504039676815, 5843.1235314701435, 5883.94116058834, 5888.911666324922, 5955.017422125301, 5999.340072591924, 6100.054491553817, 6132.0, 6145.477634401126, 6148.292946311231, 6158.3841615838055, 5579.609427340048, 5868.23712917318, 5870.882582348244, 5872.823815285548, 5875.14810352488, 5746.281714785652, 5903.881922361615, 5950.821475355746, 5953.910691339547, 6048.667323297261, 5876.853078673067, 5890.322612899571, 5891.381404952418, 5891.882162356815, 5895.823125306248, 5881.353051164283, 5889.94110058894, 5891.086881533369, 5896.439838215363, 5896.557758168069, 5481.5340696041285, 5485.61600687948, 5658.236138121301, 5659.660420374791, 5689.061304884648, 5784.045632470764, 5817.825465236049, 5823.068309070529, 5828.154917537018, 5857.1799948008165, 5463.808766693147, 5678.77284908599, 5678.943210568007, 5683.0, 5685.118806584986], "value": 5434.132554327312, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.6675461741426, 604.8094850122211, 633.284388640836, 643.1381948189426, 644.6422235659209, 37559.43660845086, 38171.61828381715, 46255.07489850201, 48509.0, 48624.270635940404, 48743.268850967266, 49283.0, 49286.274980375696, 49298.26052609214, 49299.50700492994, 9334.346595738283, 9469.10043545862, 9537.046295370443, 9543.47510886901, 9544.284178686592, 8642.524661143634, 8846.336524760636, 10962.890371096266, 11221.495032723506, 11222.943885280562, 11332.0, 11422.42887855608, 11427.714307142305, 11431.0, 11435.313881167114, 11216.66350009501, 11274.267172633834, 11298.152638552141, 11306.434678266054, 11310.095192384671, 8405.201505856934, 8600.053994060649, 8609.612567434418, 8780.0, 10741.946290268499, 42684.359734604004, 45327.77336113331, 45338.773306133255, 45356.546434535565, 45359.773201134114, 4070.61329173728, 4128.710990230685, 4453.710508816916, 4477.126960242754, 4576.0619073090265, 34966.47550286748, 34998.650013500046, 35373.0, 35584.0, 35649.46525802115, 4520.593146616788, 4599.264117741179, 4640.211164102111, 4673.976630116862, 4710.8351207707565, 362390.0, 362465.0, 363199.0, 363785.0, 365127.0461886174, 54994.0, 58238.04976576954, 62261.377386225555, 65004.34995649983, 65225.0, 68415.55298905555, 68721.31278687248, 68847.65576172087, 68864.3113568858, 68885.0, 17482.475525734248, 17553.912230438767, 17595.12024398775, 17598.032108233914, 17602.11989400525, 12997.870021299856, 13335.333233338295, 15527.068375897483, 15699.293531791096, 15704.764428533581, 18047.187876545326, 18627.254909803727, 18697.0, 19247.13387897548, 19318.613627727365, 6803.523753337219, 6920.612388725882, 7080.079589653312, 7103.360697537143, 7245.9275407246305, 1040.245821779219, 1049.0925349572717, 1092.317301686446, 1129.1531351486385, 1131.7396998690347, 33696.0, 34708.47937280943, 34889.302213955576, 35071.0, 35230.82384588061, 1134.6709454258173, 1156.1791128299003, 1177.2995067934564, 1196.4077781498213, 1228.8525376954644, 18293.268269269345, 18315.175817088264, 18325.7251141233, 18356.541086472946, 18547.536311592317, 513181.0, 516469.0, 516698.0, 518094.0, 518911.0, 62511.6874415625, 62732.686336568026, 62951.37048629547, 62976.68511657504, 63185.68407157935, 1327.5419980106847, 1367.0294091195165, 1406.5217825939026, 1444.2562080528537, 1452.1577485058851, 33500.83249583736, 35679.0, 35710.4643430354, 36326.455103174005, 36586.63413365939, 55532.0, 56002.0, 56193.0, 56249.0, 56282.71858640681, 414251.0, 416508.0, 416673.0, 417057.0, 473261.0, 28850.855745721135, 30511.389772204875, 30518.694813052472, 30741.53887691731, 30799.53800693036, 51312.0, 51486.0, 52037.739811302214, 52081.21878171754, 52892.73553632207, 43686.34470483009, 44188.0, 44596.0, 44627.77686111549, 45600.0, 43914.0, 44728.77635611801, 45335.77240648664, 45347.31979020251, 45472.31791523063, 43261.35107973446, 44043.779781100886, 44156.779216103714, 44234.11531769282, 44275.11449771051, 36867.52529898774, 39419.11610696024, 39953.80023099866, 40209.39685904655, 40605.59394406018, 29000.054606560105, 29007.709922901344, 29536.852315739143, 29728.256793580334, 29913.700862991092], "value": 21690.555483153596, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.3620798099098, 395.23127516979486, 416.47524119609295, 422.21046642778, 437.2479335542867, 14697.265136743174, 16466.91766541167, 20470.488237794045, 20616.072276747545, 20878.791212087875, 19681.933396993325, 19962.60074798503, 20034.1986320547, 20163.89918050408, 20525.4868628284, 5647.689377084259, 5716.514096301819, 5771.971140144315, 5814.069748840187, 5933.080372542263, 4231.090315582149, 4436.844710435135, 5798.246227990356, 5875.0, 5881.76472941084, 6328.65192414417, 6600.6699665016795, 6615.603063816162, 6617.338266173392, 6624.27133015368, 6323.272823625296, 6520.347965203489, 6554.049662798915, 6559.573627714183, 6580.473562115019, 5629.408912064216, 5831.504322132624, 5831.79588714393, 5836.795712150097, 5838.9124163137585, 23693.881530592236, 23935.162269320663, 24017.87991060051, 24093.0, 24215.515689686283, 2501.7498250175017, 2601.9609705854427, 2635.9604605930926, 2753.408017276295, 3052.6489453712884, 14920.253987300592, 15105.924470377688, 15121.16833574153, 15270.38918443261, 15335.463258786, 1929.9131539080636, 1942.9708554371696, 1952.6485232658115, 1979.633767752956, 1992.7110568967564, 63109.36890631081, 69205.0, 84445.73331400033, 84656.57671711664, 85152.99999999937, 11096.778064438666, 11115.388653623966, 11251.83122253167, 11269.943650281695, 11336.206465547333, 15918.204089795463, 15925.761113583305, 15928.840711592968, 15931.0, 15947.362105515882, 8680.826383472295, 9055.366124371363, 9074.86387704185, 9171.89937207526, 9282.953585232166, 9005.414648047856, 9038.367314287936, 9118.726438206864, 9231.215346695471, 9363.25093992479, 6867.622280774605, 6960.477964152708, 6984.720611175598, 6994.755183568653, 7022.0, 1985.4043786864172, 2036.5926814637132, 2043.8262747666317, 2066.73132492775, 2070.234013415046, 964.5852283518014, 1012.4077414712476, 1059.8516207730925, 1076.6016573867562, 1089.624079692505, 18701.625967480573, 20266.89866550658, 20440.59118817645, 20483.26247080659, 20672.17311307561, 1003.1673710820002, 1065.1106326217541, 1069.8181309177457, 1078.757279612081, 1090.0734375780683, 10501.684949451528, 10903.890961090447, 10978.066864316463, 11008.643809257961, 11089.500972456259, 515048.0, 515118.0, 517022.0, 518384.0, 530387.0, 81894.18105818865, 98939.0, 100488.49755751174, 102208.0, 102773.0, 1080.5353697909975, 1103.939283339408, 1129.1362107987395, 1169.9590514332128, 1171.6777886081225, 17106.401275955282, 17177.29683958887, 17450.389236376664, 17610.559736006704, 17688.73466897972, 40907.18185636211, 42013.57986420219, 42792.57207427886, 44245.336319954586, 45045.0990980172, 60213.0, 62306.37693623187, 63278.683606583516, 63501.364986349545, 65092.67453662701, 5696.344920334214, 7065.293470652894, 7465.962670186615, 8321.084680684999, 8423.157684231528, 12730.745385092432, 13424.127431716906, 13725.107867988549, 13893.444262229397, 14063.648408789863, 11650.41747912618, 11663.358515281741, 11919.82120268214, 11999.0, 12551.184173028723, 11729.589464369048, 12111.878881211074, 12201.938990305347, 12406.131570789958, 12597.93701031489, 12174.269543827399, 12200.572979946015, 12219.45012474423, 12267.325297108744, 12495.25028498293, 8058.879116813136, 8248.46384984974, 8291.673332266808, 8400.327973762207, 8478.957605212181, 9496.335256531976, 12068.0, 12113.15207935436, 12117.15179937396, 12130.939345303217], "value": 9755.161617939537, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1346.8753590751721, 1483.1175450606886, 1501.06183635228, 1666.8583170430513, 1774.7781527309087, 7154.391876690481, 7798.844023119541, 8103.716369927054, 8108.472949258301, 8293.792655183623, 8092.878606820902, 8136.105028446881, 8153.429259951806, 8156.510609363442, 8159.55122468264, 2317.6639387288874, 2334.5214231082587, 2334.9066037358484, 2337.2170322941834, 2342.61346877765, 2269.693591365161, 2269.9773002270013, 2323.1288266899915, 2328.045501344449, 2329.359426157812, 2276.8064714499287, 2289.610766169755, 2298.3449716830705, 2299.9310020699404, 2301.6432452969816, 2239.921602743896, 2241.3388050525054, 2246.696695946051, 2246.7416247131628, 2273.9090436382526, 2258.435391152212, 2278.065992942902, 2279.430142464384, 2279.430142464384, 2280.5552917181158, 7964.442489025771, 7988.201179882023, 8053.1946805319585, 8054.194580541957, 8066.11272759996, 4901.950980490221, 5052.191649336125, 5059.3169922060415, 5064.03783281179, 5068.898622027576, 3205.2147223930288, 3311.437055700537, 3345.364380767671, 3347.0, 3368.410528157587, 1134.7106487845629, 1136.4658610453073, 1138.4649214869082, 1154.463174623804, 1158.1776938373764, 10870.0, 10884.6190383337, 10961.12311015117, 10967.396793176373, 10973.615923442641, 4120.690948178899, 4197.706160568731, 4322.481302243751, 4352.499462561783, 4360.4331436913, 8823.735287941372, 8825.0, 8825.852639156998, 8827.823443531093, 8837.116288371111, 4959.330490383752, 4974.427940786783, 4980.477049909779, 4986.077575648516, 4989.950100499022, 4056.6146216110014, 4176.0395109125075, 4576.061907308994, 4664.95335046652, 4691.155591993405, 3694.2980833641523, 3763.8870833875026, 3779.055236190952, 3808.8476460941806, 3875.186210895687, 1111.6053800900725, 1114.5151858941224, 1121.5681962444517, 1130.4630300607146, 1134.9886501134883, 990.4799980010408, 1013.8783345998528, 1029.330934892311, 1030.4332617060586, 1038.657243109772, 5748.827535173951, 5828.708564571754, 5863.530917526673, 5889.116632505158, 5938.435848594377, 440.7289516947055, 447.53456405338085, 449.17352072187333, 1028.1671845804772, 1034.8809886863107, 4064.7967601620394, 4168.562300958356, 4188.099558594856, 4193.958060419357, 4200.180964711852, 9720.805583888283, 10011.599536018623, 10079.244056695778, 10097.596096156218, 10109.848352274721, 9969.601215951427, 9995.100440960348, 9996.350237234556, 9998.950005250073, 10048.09567138961, 7565.167831538526, 7717.112532058773, 7725.5364678119495, 7726.1501234863, 7731.7680469586, 5659.349174844946, 5662.094064949588, 5760.8271751847515, 5831.35855055952, 5844.707764611838, 9176.6788162414, 9228.815423691623, 9244.168024877794, 9361.953190234006, 9370.578323975302, 9130.95434522823, 9404.38871473352, 9422.340436169403, 9478.715638530855, 9567.808643827224, 6901.516893817385, 6938.306169383021, 6966.0, 7010.053642758162, 7028.121484814398, 7826.882596761167, 7890.447668663139, 7890.921090789018, 7893.329067029137, 7898.802529936797, 7071.71713131469, 7203.063601731741, 7216.5670059796585, 7222.674979626035, 7356.301151390609, 6917.308269173243, 6996.300369962963, 7100.538464999759, 7122.287771222836, 7167.3549380556, 6699.29657385987, 6795.25252222265, 6855.245922948571, 6855.862882742417, 6858.485613578901, 4630.398048253705, 4661.34741136248, 4671.602913752369, 4711.458182309078, 4786.832460863853, 5214.452482489284, 5316.8936621266585, 5336.066188416934, 5363.704996225245, 5380.650257733235], "value": 4372.25002185245, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.6989599672305, 325.9837008149592, 327.10699789574466, 328.17136729757374, 334.04463235147483, 5476.835694929148, 5514.6415482993625, 5524.917126243109, 5527.14840528423, 5556.138798486231, 5438.872648227275, 5457.781688732456, 5463.153211252253, 5468.72656367182, 5499.780008799653, 4080.795960201993, 4178.8328466861285, 4180.205760905434, 4242.918055895742, 4251.0, 4235.555266696999, 4252.87241382759, 4292.420523229355, 4307.203167414023, 4312.568743125678, 4208.253574766468, 4220.683448741342, 4221.2190744712325, 4225.873223803291, 4228.894277643053, 4023.859164929228, 4183.644390226835, 4183.937240941388, 4207.747535147901, 4227.217964676544, 3687.557493100818, 3688.8708895188806, 3690.409534474471, 3694.298083364179, 3720.9441858372147, 5229.294045303911, 5240.371155461331, 5246.0, 5253.894922101575, 5286.709230992294, 4650.720956742606, 4793.424789025305, 4804.183288840905, 4853.39332583427, 4960.776765045582, 5695.77216911323, 5704.458076482728, 5721.5696075981, 5722.942770572325, 5730.106296320373, 4215.346621273708, 4215.747055176699, 4244.639205667522, 4245.299525578276, 4248.936265956013, 5844.736986835603, 5917.378675239081, 5948.643081415129, 5978.880422391571, 6026.9095963560585, 4269.87190384289, 4271.124419493996, 4271.231178387858, 4285.914281714348, 4301.526832048472, 4351.8694439166875, 4361.629261512743, 4361.825526978885, 4362.149380870763, 4373.365861950063, 4683.695559788603, 4870.634702397264, 4924.827631032968, 4961.231009193581, 4966.255061740768, 5705.914411283834, 5706.771729130871, 5714.799892022722, 5735.2257445245195, 5744.971275143597, 1502.4440956846038, 1519.7720341948575, 1521.9422501361669, 1521.9498546003222, 1529.2277399912973, 913.4656226107801, 964.252704154286, 976.7985377985118, 981.6318880419842, 1003.4481035430484, 5499.51513091463, 5547.861303467364, 5551.417101204315, 5551.861203469945, 5551.9735296616445, 5847.327557330961, 5861.677607731615, 5863.0, 5870.354261031283, 5905.143754155623, 5289.3917199521775, 5437.646552974044, 5452.454754524516, 5506.972465137729, 5524.779008839681, 3039.6960303969427, 3076.8153910765427, 3083.8612262448246, 3087.1664650544226, 3101.6278046634097, 5855.033919403336, 5896.292444906553, 5952.7023648817385, 5959.629285264414, 5984.354302566829, 5881.176635271066, 5903.114532820111, 5917.319508256605, 5925.933332000195, 5944.711286154861, 5965.910511342333, 6003.939960600425, 6019.2175017248355, 6025.9096113558335, 6030.909536356959, 5574.9163762542785, 5758.568107391879, 5761.711914404179, 5779.8555036124435, 5817.883642327045, 5717.571182161271, 5743.6789538405565, 5752.0, 5753.280839895013, 5762.279715035621, 5795.7391917362975, 5998.400159983967, 6000.399960003964, 6001.839337838462, 6011.519078473799, 4119.835206591702, 4142.5857414259535, 4161.4382058421725, 4178.540360560275, 4181.393697913724, 5662.971685141712, 5803.8549036274435, 5828.213191219301, 5840.561957853093, 5900.793472228452, 5590.776368945197, 5704.657720536781, 5713.342965559014, 5782.306123265235, 5794.420557944172, 5759.337676167294, 5759.8560035999435, 5762.7694892203845, 5773.595848290579, 5798.246227990503, 5538.627531294638, 5625.409332020079, 5661.433856614306, 5689.7198130421975, 5754.4533269339345, 5742.942570574408, 5743.253377060955, 5746.362286748256, 5747.224124743107, 5752.68360240174, 5392.379876314118, 5641.802536911188, 5643.125315576092, 5649.152627105967, 5650.802221922378], "value": 4360.1045617385835, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [816.2286639126029, 817.8159914019344, 830.6677329068372, 908.5502676175286, 919.4850883505236, 16571.58571035723, 17980.190891409886, 17989.37037203698, 18028.18873150708, 18089.4573162805, 16456.753148702777, 16477.588060298498, 16481.83518164818, 16606.25271862766, 16655.583610409747, 804.8873157757904, 843.6372359885247, 849.8512760266959, 850.3962186847315, 850.6257246811414, 846.8306338732248, 848.5248260973843, 853.9530325832077, 855.0466230153396, 855.1576696953496, 897.2552781191596, 899.8695189197563, 901.400568621869, 901.4816480523692, 901.9278457723399, 898.5013317608724, 900.6037343568842, 900.7928176519438, 901.1394118616738, 901.1574178143425, 651.3486513486514, 659.4691273524811, 660.0165753027953, 660.5970358081566, 662.78459500662, 15744.60638484047, 15756.921215393964, 15758.606034849106, 15802.525924222291, 15818.920905395515, 15517.844821551753, 15585.0, 15616.297266622916, 15675.0, 15951.169484891545, 13490.123141995738, 13526.391312390966, 13540.59378218655, 13677.931610341884, 13813.171209727449, 4091.3862920561855, 4147.854825081107, 4150.460440142792, 4237.512686041114, 4253.51084625269, 90757.54621226918, 91695.0, 92570.53714731384, 94437.5278123605, 94737.0, 12109.152359334763, 12272.754544909052, 12286.815697764541, 12330.50677972871, 12610.243385396905, 23213.006447504387, 23432.0, 23519.0, 23619.763802361755, 23652.408689782547, 8671.913280867237, 8697.0, 8874.156955089256, 8917.509536975529, 9103.817923641622, 4264.722793018506, 4308.418363520885, 4448.621867141264, 4453.487848897418, 4580.106879158532, 3562.305350456688, 3690.55713314404, 3695.3902606069696, 3696.482492451059, 3704.388775852008, 584.5031723035386, 587.49475451112, 589.7434615942079, 594.349187639534, 609.9390060993954, 725.151572659989, 738.6269933683456, 789.1989630525022, 816.7549735079452, 821.3103180837367, 10745.623903163352, 10861.565537378416, 11130.666080017612, 11137.276077055127, 11276.774464510829, 717.9569225846466, 781.8670825959487, 786.9842603147905, 819.1521774962885, 835.774340927946, 5067.797288108434, 5466.863328416822, 5480.877376392944, 5502.5597952163735, 5562.666240025612, 129629.0, 133777.0, 134257.0, 135503.32248338696, 137416.0, 29950.0, 83127.5843620778, 92763.0, 110485.0, 110490.0, 60752.69623651942, 63585.68207158935, 90927.0, 94702.0, 95294.0, 10788.244822862323, 11265.436728163722, 11390.886091138982, 11507.367094809866, 11536.480858361227, 54155.729221355214, 54691.45308546864, 55435.0, 57159.714201428724, 57589.136162956755, 74889.0, 81969.59015204886, 82944.58527707565, 83047.0, 84902.0, 13746.243956582084, 13851.30743462843, 13969.790453143009, 14356.138631682132, 14428.927855360656, 15928.283227255033, 16024.759628605814, 16366.345346186019, 16767.1616419181, 16917.069561174252, 14524.49164279245, 15113.773293400827, 15655.138967356905, 16526.09106499106, 16662.166891655612, 13197.406116724962, 13682.31588420595, 15007.774883376977, 15305.0, 16267.26797294101, 13337.0, 13492.5277615283, 13511.527096551574, 13665.31673416345, 14535.709285814435, 12956.416961236579, 12964.416601252778, 13002.154859934075, 13267.93856491459, 13913.791293130813, 12778.808317875053, 12903.354832258537, 13057.28184949837, 13101.148425352321, 13672.0], "value": 8168.963736429334, "unit": "score"}], "name": "dromaeo_css", "value": 7507.7352692692175, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 112.0, 110.0, 110.0, 111.0, 110.0, 113.0, 111.0, 109.0, 110.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 116.0, 116.0, 160.0, 122.0, 117.0, 119.0, 119.0, 154.0, 122.0], "value": 128.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 159.0, 161.0, 163.0, 160.0, 161.0, 161.0, 161.0, 161.0, 162.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 75.0, 108.0, 77.0, 76.0, 76.0, 77.0, 99.0, 76.0], "value": 81.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 109.0, 81.0, 82.0, 82.0, 117.0, 82.0, 82.0, 113.0], "value": 91.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 118.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 145.0, 146.0, 146.0, 145.0, 145.0, 146.0, 145.0, 146.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 49.0, 49.0, 50.0, 50.0, 52.0, 48.0, 49.0, 50.0], "value": 49.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 59.0, 59.0, 59.0, 58.0, 59.0, 59.0, 58.0, 60.0], "value": 59.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 72.0, 78.0, 74.0, 74.0, 75.0, 74.0, 73.0, 73.0], "value": 74.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 119.0, 116.0, 116.0, 126.0, 126.0, 123.0, 116.0, 167.0, 128.0], "value": 125.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 154.0, 154.0, 154.0, 153.0, 152.0, 154.0, 152.0, 153.0, 156.0], "value": 153.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 61.0, 62.0, 62.0, 61.0, 62.0, 60.0, 60.0, 60.0], "value": 61.5, "unit": "score"}], "name": "kraken", "value": 1453.0, "alertThreshold": 2.0}]} 04:38:13 INFO - Return code: 0 04:38:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:38:13 INFO - # TBPL SUCCESS # 04:38:13 INFO - Running post-action listener: _resource_record_post_action 04:38:13 INFO - [mozharness: 2016-09-30 11:38:13.451902Z] Finished run-tests step (success) 04:38:13 INFO - Running post-run listener: _resource_record_post_run 04:38:13 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 274432; Write bytes: 367779840; Read time: 164; Write time: 201700 04:38:13 INFO - TinderboxPrint: CPU usage
12.9% 04:38:13 INFO - TinderboxPrint: I/O read bytes / time
274,432 / 164 04:38:13 INFO - TinderboxPrint: I/O write bytes / time
367,779,840 / 201,700 04:38:13 INFO - TinderboxPrint: CPU idle
8,268.9 (87.1%) 04:38:13 INFO - TinderboxPrint: CPU user
1,185.0 (12.5%) 04:38:13 INFO - TinderboxPrint: Swap in / out
0 / 0 04:38:13 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 118669312; Read time: 0; Write time: 68280 04:38:13 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 270336; Write bytes: 249110528; Read time: 156; Write time: 133420 04:38:14 INFO - Running post-run listener: _upload_blobber_files 04:38:14 INFO - Blob upload gear active. 04:38:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:38:14 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 04:38:14 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:38:14 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:38:14 INFO - (blobuploader) - INFO - Open directory for files ... 04:38:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:38:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:38:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:38:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:38:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:38:18 INFO - (blobuploader) - INFO - Done attempting. 04:38:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:38:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:38:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:38:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:38:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:38:19 INFO - (blobuploader) - INFO - Done attempting. 04:38:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:38:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:38:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:38:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:38:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:38:19 INFO - (blobuploader) - INFO - Done attempting. 04:38:19 INFO - (blobuploader) - INFO - Iteration through files over. 04:38:19 INFO - Return code: 0 04:38:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:38:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:38:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/08355844cd1d21dd06aa1cfe1588d94e8072eb072b3b5fc31bdc9f879babe42f54134e200374e64273d2575c02e7289bf45b7f19abecafeef7c61a736029a710", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5a3d7f078700d78c028548ff35002702f40f473dae3bc89c8206154ebeefbbbf223361b4f69eb5aa5df5061c037558f0c634e0eb201e3c925521707152388621", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e45a4c2bc669e91674320050c109efc89d4cc936f33d4a1dda7000f8918e06960a9540d309077124640d2e3d44a92524cebc076a71012152e967f61f2ffc92c6"} 04:38:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:38:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:38:19 INFO - Contents: 04:38:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/08355844cd1d21dd06aa1cfe1588d94e8072eb072b3b5fc31bdc9f879babe42f54134e200374e64273d2575c02e7289bf45b7f19abecafeef7c61a736029a710", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5a3d7f078700d78c028548ff35002702f40f473dae3bc89c8206154ebeefbbbf223361b4f69eb5aa5df5061c037558f0c634e0eb201e3c925521707152388621", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e45a4c2bc669e91674320050c109efc89d4cc936f33d4a1dda7000f8918e06960a9540d309077124640d2e3d44a92524cebc076a71012152e967f61f2ffc92c6"} 04:38:19 INFO - Running post-run listener: copy_logs_to_upload_dir 04:38:19 INFO - Copying logs to upload dir... 04:38:19 INFO - mkdir: /builds/slave/test/build/upload/logs 04:38:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.627967 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-09-30 04:38:19.078836) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-30 04:38:19.082043) ========= 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=d2a8c569965ab8367867b893000001a1-1475233861.210016-279485443 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/08355844cd1d21dd06aa1cfe1588d94e8072eb072b3b5fc31bdc9f879babe42f54134e200374e64273d2575c02e7289bf45b7f19abecafeef7c61a736029a710", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5a3d7f078700d78c028548ff35002702f40f473dae3bc89c8206154ebeefbbbf223361b4f69eb5aa5df5061c037558f0c634e0eb201e3c925521707152388621", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e45a4c2bc669e91674320050c109efc89d4cc936f33d4a1dda7000f8918e06960a9540d309077124640d2e3d44a92524cebc076a71012152e967f61f2ffc92c6"} build_url:https://queue.taskcluster.net/v1/task/BIWz9CtSSRKSlfmg2m3New/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011291 build_url: 'https://queue.taskcluster.net/v1/task/BIWz9CtSSRKSlfmg2m3New/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/08355844cd1d21dd06aa1cfe1588d94e8072eb072b3b5fc31bdc9f879babe42f54134e200374e64273d2575c02e7289bf45b7f19abecafeef7c61a736029a710", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5a3d7f078700d78c028548ff35002702f40f473dae3bc89c8206154ebeefbbbf223361b4f69eb5aa5df5061c037558f0c634e0eb201e3c925521707152388621", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e45a4c2bc669e91674320050c109efc89d4cc936f33d4a1dda7000f8918e06960a9540d309077124640d2e3d44a92524cebc076a71012152e967f61f2ffc92c6"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-30 04:38:19.108238) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 04:38:19.108575) ========= 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=d2a8c569965ab8367867b893000001a1-1475233861.210016-279485443 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004503 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 04:38:19.191710) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-30 04:38:19.192069) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-30 04:38:19.192377) ========= ========= Total master_lag: 0.19 =========