builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-023 starttime: 1475246168.12 results: success (0) buildid: 20160930013320 builduid: aa5c9bca12eb4c9481f5747ff57287d7 revision: 805d5fc3d66c4fa585a3de407b1e0b055f78eb58 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-30 07:36:08.115983) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-30 07:36:08.116363) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-30 07:36:08.116626) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-30 07:36:08.218381) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 07:36:08.218626) ========= 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=6d15e502d8e29d5d2676d664000001c3-1475243472.858512-887638643 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-30 07:36:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.1M=0s 2016-09-30 07:36:08 (43.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.082537 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 07:36:08.317994) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 07:36:08.318303) ========= 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=6d15e502d8e29d5d2676d664000001c3-1475243472.858512-887638643 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018371 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 07:36:08.368126) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-30 07:36:08.368440) ========= 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=6d15e502d8e29d5d2676d664000001c3-1475243472.858512-887638643 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-30 07:36:08,986 truncating revision to first 12 chars 2016-09-30 07:36:08,986 Setting DEBUG logging. 2016-09-30 07:36:08,987 attempt 1/10 2016-09-30 07:36:08,987 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 07:36:10,166 unpacking tar archive at: mozilla-aurora-805d5fc3d66c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.520675 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-30 07:36:09.904484) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-30 07:36:09.904822) ========= script_repo_revision: 805d5fc3d66c4fa585a3de407b1e0b055f78eb58 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-30 07:36:09.905225) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-30 07:36:09.905487) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-30 07:36:09.916199) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-09-30 07:36:09.916579) ========= /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=6d15e502d8e29d5d2676d664000001c3-1475243472.858512-887638643 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:36:10 INFO - MultiFileLogger online at 20160930 07:36:10 in /builds/slave/test 07:36:10 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 07:36:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:36:10 INFO - {'append_to_log': False, 07:36:10 INFO - 'base_work_dir': '/builds/slave/test', 07:36:10 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:36:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:36:10 INFO - 'branch': 'Mozilla-Aurora', 07:36:10 INFO - 'buildbot_json_path': 'buildprops.json', 07:36:10 INFO - 'config_files': ('talos/linux_config.py',), 07:36:10 INFO - 'default_actions': ('clobber', 07:36:10 INFO - 'read-buildbot-config', 07:36:10 INFO - 'download-and-extract', 07:36:10 INFO - 'populate-webroot', 07:36:10 INFO - 'create-virtualenv', 07:36:10 INFO - 'install', 07:36:10 INFO - 'run-tests'), 07:36:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:36:10 INFO - 'download_minidump_stackwalk': True, 07:36:10 INFO - 'download_symbols': 'ondemand', 07:36:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:36:10 INFO - 'tooltool.py': '/tools/tooltool.py', 07:36:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:36:10 INFO - '/tools/misc-python/virtualenv.py')}, 07:36:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:36:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:36:10 INFO - 'installer_path': 'installer.exe', 07:36:10 INFO - 'log_level': 'info', 07:36:10 INFO - 'log_name': 'talos', 07:36:10 INFO - 'log_to_console': True, 07:36:10 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:36:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:36:10 INFO - 'opt_config_files': (), 07:36:10 INFO - 'pip_index': False, 07:36:10 INFO - 'sps_profile': False, 07:36:10 INFO - 'sps_profile_interval': 0, 07:36:10 INFO - 'suite': 'dromaeojs', 07:36:10 INFO - 'system_bits': '32', 07:36:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:36:10 INFO - 'title': 'talos-linux64-ix-023', 07:36:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:36:10 INFO - 'use_talos_json': True, 07:36:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:36:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:36:10 INFO - 'work_dir': 'build'} 07:36:10 INFO - [mozharness: 2016-09-30 14:36:10.642196Z] Running clobber step. 07:36:10 INFO - Running pre-action listener: _resource_record_pre_action 07:36:10 INFO - Running main action method: clobber 07:36:10 INFO - rmtree: /builds/slave/test/build 07:36:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:36:11 INFO - Running post-action listener: _resource_record_post_action 07:36:11 INFO - [mozharness: 2016-09-30 14:36:11.098376Z] Finished clobber step (success) 07:36:11 INFO - [mozharness: 2016-09-30 14:36:11.098477Z] Running read-buildbot-config step. 07:36:11 INFO - Running pre-action listener: _resource_record_pre_action 07:36:11 INFO - Running main action method: read_buildbot_config 07:36:11 INFO - Using buildbot properties: 07:36:11 INFO - { 07:36:11 INFO - "project": "", 07:36:11 INFO - "product": "firefox", 07:36:11 INFO - "script_repo_revision": "production", 07:36:11 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 07:36:11 INFO - "repository": "", 07:36:11 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 07:36:11 INFO - "buildid": "20160930013320", 07:36:11 INFO - "pgo_build": "True", 07:36:11 INFO - "basedir": "/builds/slave/test", 07:36:11 INFO - "buildnumber": 545, 07:36:11 INFO - "slavename": "talos-linux64-ix-023", 07:36:11 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 07:36:11 INFO - "platform": "ubuntu64_hw", 07:36:11 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 07:36:11 INFO - "branch": "mozilla-aurora", 07:36:11 INFO - "repo_path": "releases/mozilla-aurora", 07:36:11 INFO - "revision": "805d5fc3d66c4fa585a3de407b1e0b055f78eb58", 07:36:11 INFO - "stage_platform": "linux64-pgo", 07:36:11 INFO - "builduid": "aa5c9bca12eb4c9481f5747ff57287d7", 07:36:11 INFO - "slavebuilddir": "test" 07:36:11 INFO - } 07:36:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/BIWz9CtSSRKSlfmg2m3New/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 07:36:11 INFO - Running post-action listener: _resource_record_post_action 07:36:11 INFO - [mozharness: 2016-09-30 14:36:11.100973Z] Finished read-buildbot-config step (success) 07:36:11 INFO - [mozharness: 2016-09-30 14:36:11.101063Z] Running download-and-extract step. 07:36:11 INFO - Running pre-action listener: _resource_record_pre_action 07:36:11 INFO - Running main action method: download_and_extract 07:36:11 INFO - mkdir: /builds/slave/test/build/tests 07:36: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')]} 07:36:11 INFO - trying https://queue.taskcluster.net/v1/task/BIWz9CtSSRKSlfmg2m3New/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 07:36:11 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 07:36:11 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 07:36:11 INFO - Downloaded 1361 bytes. 07:36:11 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 07:36:11 INFO - Using the following test package requirements: 07:36:11 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 07:36:11 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:36:11 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 07:36:11 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:36:11 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 07:36:11 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:36:11 INFO - u'jsshell-linux-x86_64.zip'], 07:36:11 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:36:11 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 07:36:11 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 07:36:11 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:36:11 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 07:36:11 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:36:11 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 07:36:11 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:36:11 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 07:36:11 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 07:36:11 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 07:36:11 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:36:11 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 07:36:11 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 07:36:11 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 07:36:12 INFO - Expected file size: 21901358 07:36:16 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 07:36:16 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 07:36:16 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 07:36:16 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 07:36:18 INFO - Expected file size: 21901358 07:36:22 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 07:36:22 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 07:36:22 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 07:36:23 INFO - Expected file size: 14414059 07:36:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:23 INFO - trying https://queue.taskcluster.net/v1/task/BIWz9CtSSRKSlfmg2m3New/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 07:36:23 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 07:36:23 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 07:36:25 INFO - Downloaded 63921663 bytes. 07:36:25 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 07:36:25 INFO - mkdir: /builds/slave/test/properties 07:36:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:36:25 INFO - Writing to file /builds/slave/test/properties/build_url 07:36:25 INFO - Contents: 07:36:25 INFO - build_url:https://queue.taskcluster.net/v1/task/BIWz9CtSSRKSlfmg2m3New/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 07:36:25 INFO - Running post-action listener: _resource_record_post_action 07:36:25 INFO - Running post-action listener: set_extra_try_arguments 07:36:25 INFO - [mozharness: 2016-09-30 14:36:25.616393Z] Finished download-and-extract step (success) 07:36:25 INFO - [mozharness: 2016-09-30 14:36:25.616620Z] Running populate-webroot step. 07:36:25 INFO - Running pre-action listener: _resource_record_pre_action 07:36:25 INFO - Running main action method: populate_webroot 07:36:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:36:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:36:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:36:25 INFO - '--tppagecycles', 07:36:25 INFO - '7'], 07:36:25 INFO - 'tests': ['tsvgm']}}, 07:36:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:36:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:36:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:36:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:36:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:36:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:36:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:25 INFO - 'talos_options': ['--disable-e10s'], 07:36:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:36:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:36:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:25 INFO - 'talos_options': ['--disable-e10s'], 07:36:25 INFO - 'tests': ['damp', 'tps']}, 07:36:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:25 INFO - 'tests': ['damp', 'tps']}, 07:36:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:36:25 INFO - 'tests': ['dromaeo_dom']}, 07:36:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:36:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:36:25 INFO - 'tests': ['basic_compositor_video']}, 07:36:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:36:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:36:25 INFO - 'tests': ['a11yr', 07:36:25 INFO - 'ts_paint', 07:36:25 INFO - 'tpaint', 07:36:25 INFO - 'sessionrestore', 07:36:25 INFO - 'sessionrestore_no_auto_restore', 07:36:25 INFO - 'tabpaint']}, 07:36:25 INFO - 'other-e10s': {'tests': ['a11yr', 07:36:25 INFO - 'ts_paint', 07:36:25 INFO - 'tpaint', 07:36:25 INFO - 'sessionrestore', 07:36:25 INFO - 'sessionrestore_no_auto_restore', 07:36:25 INFO - 'tabpaint']}, 07:36:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:36:25 INFO - 'ts_paint', 07:36:25 INFO - 'tpaint', 07:36:25 INFO - 'sessionrestore', 07:36:25 INFO - 'sessionrestore_no_auto_restore', 07:36:25 INFO - 'tabpaint']}, 07:36:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:36:25 INFO - 'ts_paint', 07:36:25 INFO - 'tpaint', 07:36:25 INFO - 'sessionrestore', 07:36:25 INFO - 'sessionrestore_no_auto_restore', 07:36:25 INFO - 'tabpaint']}, 07:36:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:36:25 INFO - 'tests': ['a11yr', 07:36:25 INFO - 'ts_paint', 07:36:25 INFO - 'tpaint', 07:36:25 INFO - 'sessionrestore', 07:36:25 INFO - 'sessionrestore_no_auto_restore', 07:36:25 INFO - 'tabpaint']}, 07:36:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:36:25 INFO - 'tests': ['a11yr', 07:36:25 INFO - 'ts_paint', 07:36:25 INFO - 'tpaint', 07:36:25 INFO - 'sessionrestore', 07:36:25 INFO - 'sessionrestore_no_auto_restore', 07:36:25 INFO - 'tabpaint']}, 07:36:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:36:25 INFO - 'tests': ['tsvgx', 07:36:25 INFO - 'tsvgr_opacity', 07:36:25 INFO - 'tart', 07:36:25 INFO - 'tscrollx', 07:36:25 INFO - 'cart']}, 07:36:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:36:25 INFO - 'tsvgr_opacity', 07:36:25 INFO - 'tart', 07:36:25 INFO - 'tscrollx', 07:36:25 INFO - 'cart']}, 07:36:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:25 INFO - 'talos_options': ['--disable-e10s'], 07:36:25 INFO - 'tests': ['tp5o']}, 07:36:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:25 INFO - 'tests': ['tp5o']}, 07:36:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:25 INFO - 'talos_options': ['--disable-e10s', 07:36:25 INFO - '--xperf_path', 07:36:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:36:25 INFO - 'tests': ['tp5n']}, 07:36:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:36:25 INFO - 'talos_options': ['--xperf_path', 07:36:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:36:25 INFO - 'tests': ['tp5n']}}, 07:36:25 INFO - 'talos.zip': {'path': '', 07:36:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:36:25 INFO - Running post-action listener: _resource_record_post_action 07:36:25 INFO - [mozharness: 2016-09-30 14:36:25.638350Z] Finished populate-webroot step (success) 07:36:25 INFO - [mozharness: 2016-09-30 14:36:25.638439Z] Running create-virtualenv step. 07:36:25 INFO - Running pre-action listener: _resource_record_pre_action 07:36:25 INFO - Running main action method: create_virtualenv 07:36:25 INFO - Creating virtualenv /builds/slave/test/build/venv 07:36:25 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 07:36:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:36:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:36:25 INFO - Using real prefix '/usr' 07:36:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:36:26 INFO - Installing distribute.............................................................................................................................................................................................done. 07:36:28 INFO - Installing pip.................done. 07:36:28 INFO - Return code: 0 07:36:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:36:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f077af70f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1583ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1580f60>, '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 0x1581d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15821d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1475243472.858512-887638643', '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 07:36:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:36:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:36:28 INFO - Using env: {'DISPLAY': ':0', 07:36:28 INFO - 'HOME': '/home/cltbld', 07:36:28 INFO - 'LANG': 'en_US.UTF-8', 07:36:28 INFO - 'LANGUAGE': 'en_US:en', 07:36:28 INFO - 'LOGNAME': 'cltbld', 07:36:28 INFO - 'MAIL': '/var/mail/cltbld', 07:36:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:28 INFO - 'MOZ_NO_REMOTE': '1', 07:36:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:36:28 INFO - 'NO_EM_RESTART': '1', 07:36:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:36:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:28 INFO - 'PWD': '/builds/slave/test', 07:36:28 INFO - 'SHELL': '/bin/bash', 07:36:28 INFO - 'SHLVL': '1', 07:36:28 INFO - 'TERM': 'linux', 07:36:28 INFO - 'TMOUT': '86400', 07:36:28 INFO - 'USER': 'cltbld', 07:36:28 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1475243472.858512-887638643', 07:36:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:28 INFO - '_': '/tools/buildbot/bin/python'} 07:36:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:28 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 07:36:28 INFO - Cleaning up... 07:36:28 INFO - Return code: 0 07:36:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:36:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f077af70f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1583ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1580f60>, '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 0x1581d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15821d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1475243472.858512-887638643', '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 07:36:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:36:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:36:28 INFO - Using env: {'DISPLAY': ':0', 07:36:28 INFO - 'HOME': '/home/cltbld', 07:36:28 INFO - 'LANG': 'en_US.UTF-8', 07:36:28 INFO - 'LANGUAGE': 'en_US:en', 07:36:28 INFO - 'LOGNAME': 'cltbld', 07:36:28 INFO - 'MAIL': '/var/mail/cltbld', 07:36:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:28 INFO - 'MOZ_NO_REMOTE': '1', 07:36:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:36:28 INFO - 'NO_EM_RESTART': '1', 07:36:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:36:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:28 INFO - 'PWD': '/builds/slave/test', 07:36:28 INFO - 'SHELL': '/bin/bash', 07:36:28 INFO - 'SHLVL': '1', 07:36:28 INFO - 'TERM': 'linux', 07:36:28 INFO - 'TMOUT': '86400', 07:36:28 INFO - 'USER': 'cltbld', 07:36:28 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1475243472.858512-887638643', 07:36:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:28 INFO - '_': '/tools/buildbot/bin/python'} 07:36:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:28 INFO - Downloading/unpacking psutil>=3.1.1 07:36:28 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 07:36:28 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 07:36:28 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 07:36:28 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 07:36:28 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 07:36:28 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 07:36:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:36:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:36:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:36:30 INFO - Installing collected packages: psutil 07:36:30 INFO - Running setup.py install for psutil 07:36:30 INFO - building 'psutil._psutil_linux' extension 07:36:30 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 07:36:30 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 07:36:30 INFO - building 'psutil._psutil_posix' extension 07:36:30 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 07:36:31 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 07:36:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:36:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:36:31 INFO - Successfully installed psutil 07:36:31 INFO - Cleaning up... 07:36:31 INFO - Return code: 0 07:36:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:36:31 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')]} 07:36:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:31 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')]} 07:36:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:31 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 0x7f077af70f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1583ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1580f60>, '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 0x1581d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15821d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1475243472.858512-887638643', '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 07:36:31 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 07:36:31 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 07:36:31 INFO - Using env: {'DISPLAY': ':0', 07:36:31 INFO - 'HOME': '/home/cltbld', 07:36:31 INFO - 'LANG': 'en_US.UTF-8', 07:36:31 INFO - 'LANGUAGE': 'en_US:en', 07:36:31 INFO - 'LOGNAME': 'cltbld', 07:36:31 INFO - 'MAIL': '/var/mail/cltbld', 07:36:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:31 INFO - 'MOZ_NO_REMOTE': '1', 07:36:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:36:31 INFO - 'NO_EM_RESTART': '1', 07:36:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:36:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:31 INFO - 'PWD': '/builds/slave/test', 07:36:31 INFO - 'SHELL': '/bin/bash', 07:36:31 INFO - 'SHLVL': '1', 07:36:31 INFO - 'TERM': 'linux', 07:36:31 INFO - 'TMOUT': '86400', 07:36:31 INFO - 'USER': 'cltbld', 07:36:31 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1475243472.858512-887638643', 07:36:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:31 INFO - '_': '/tools/buildbot/bin/python'} 07:36:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:36:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:36:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:36:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:36:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:36:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:36:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:36:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:36:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:36:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:36:33 INFO - Installing collected packages: mozsystemmonitor 07:36:33 INFO - Running setup.py install for mozsystemmonitor 07:36:33 INFO - Successfully installed mozsystemmonitor 07:36:33 INFO - Cleaning up... 07:36:33 INFO - Return code: 0 07:36:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:36:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f077af70f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1583ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1580f60>, '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 0x1581d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15821d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1475243472.858512-887638643', '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 07:36:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:36:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:36:33 INFO - Using env: {'DISPLAY': ':0', 07:36:33 INFO - 'HOME': '/home/cltbld', 07:36:33 INFO - 'LANG': 'en_US.UTF-8', 07:36:33 INFO - 'LANGUAGE': 'en_US:en', 07:36:33 INFO - 'LOGNAME': 'cltbld', 07:36:33 INFO - 'MAIL': '/var/mail/cltbld', 07:36:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:33 INFO - 'MOZ_NO_REMOTE': '1', 07:36:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:36:33 INFO - 'NO_EM_RESTART': '1', 07:36:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:36:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:33 INFO - 'PWD': '/builds/slave/test', 07:36:33 INFO - 'SHELL': '/bin/bash', 07:36:33 INFO - 'SHLVL': '1', 07:36:33 INFO - 'TERM': 'linux', 07:36:33 INFO - 'TMOUT': '86400', 07:36:33 INFO - 'USER': 'cltbld', 07:36:33 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1475243472.858512-887638643', 07:36:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:33 INFO - '_': '/tools/buildbot/bin/python'} 07:36:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:33 INFO - Downloading/unpacking jsonschema==2.5.1 07:36:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:36:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:36:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:36:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:36:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:36:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:36:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:36:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:36:35 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 07:36:35 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 07:36:35 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 07:36:35 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 07:36:35 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 07:36:35 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 07:36:35 INFO - Downloading functools32-3.2.3-2.tar.gz 07:36:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:36:35 INFO - warning: no files found matching '*.txt' 07:36:35 INFO - no previously-included directories found matching 'build' 07:36:35 INFO - no previously-included directories found matching 'dist' 07:36:35 INFO - no previously-included directories found matching '.git*' 07:36:35 INFO - Installing collected packages: jsonschema, functools32 07:36:35 INFO - Running setup.py install for functools32 07:36:36 INFO - warning: no files found matching '*.txt' 07:36:36 INFO - no previously-included directories found matching 'build' 07:36:36 INFO - no previously-included directories found matching 'dist' 07:36:36 INFO - no previously-included directories found matching '.git*' 07:36:36 INFO - Successfully installed jsonschema functools32 07:36:36 INFO - Cleaning up... 07:36:36 INFO - Return code: 0 07:36:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:36:36 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')]} 07:36:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:36 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')]} 07:36:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:36 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 0x7f077af70f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1583ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1580f60>, '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 0x1581d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15821d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1475243472.858512-887638643', '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 07:36:36 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 07:36:36 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 07:36:36 INFO - Using env: {'DISPLAY': ':0', 07:36:36 INFO - 'HOME': '/home/cltbld', 07:36:36 INFO - 'LANG': 'en_US.UTF-8', 07:36:36 INFO - 'LANGUAGE': 'en_US:en', 07:36:36 INFO - 'LOGNAME': 'cltbld', 07:36:36 INFO - 'MAIL': '/var/mail/cltbld', 07:36:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:36 INFO - 'MOZ_NO_REMOTE': '1', 07:36:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:36:36 INFO - 'NO_EM_RESTART': '1', 07:36:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:36:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:36 INFO - 'PWD': '/builds/slave/test', 07:36:36 INFO - 'SHELL': '/bin/bash', 07:36:36 INFO - 'SHLVL': '1', 07:36:36 INFO - 'TERM': 'linux', 07:36:36 INFO - 'TMOUT': '86400', 07:36:36 INFO - 'USER': 'cltbld', 07:36:36 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1475243472.858512-887638643', 07:36:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:36 INFO - '_': '/tools/buildbot/bin/python'} 07:36:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:36:36 INFO - Cleaning up... 07:36:36 INFO - Return code: 0 07:36:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:36:36 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')]} 07:36:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:36 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')]} 07:36:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:36 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 0x7f077af70f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1583ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1580f60>, '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 0x1581d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15821d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1475243472.858512-887638643', '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 07:36:36 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 07:36:36 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 07:36:36 INFO - Using env: {'DISPLAY': ':0', 07:36:36 INFO - 'HOME': '/home/cltbld', 07:36:36 INFO - 'LANG': 'en_US.UTF-8', 07:36:36 INFO - 'LANGUAGE': 'en_US:en', 07:36:36 INFO - 'LOGNAME': 'cltbld', 07:36:36 INFO - 'MAIL': '/var/mail/cltbld', 07:36:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:36 INFO - 'MOZ_NO_REMOTE': '1', 07:36:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:36:36 INFO - 'NO_EM_RESTART': '1', 07:36:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:36:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:36 INFO - 'PWD': '/builds/slave/test', 07:36:36 INFO - 'SHELL': '/bin/bash', 07:36:36 INFO - 'SHLVL': '1', 07:36:36 INFO - 'TERM': 'linux', 07:36:36 INFO - 'TMOUT': '86400', 07:36:36 INFO - 'USER': 'cltbld', 07:36:36 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1475243472.858512-887638643', 07:36:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:36 INFO - '_': '/tools/buildbot/bin/python'} 07:36:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:36 INFO - Downloading/unpacking blobuploader==1.2.4 07:36:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:36:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:36:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:36:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:36:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:36:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:36:38 INFO - Downloading blobuploader-1.2.4.tar.gz 07:36:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:36:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:36:38 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 07:36:38 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 07:36:38 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 07:36:38 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 07:36:38 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 07:36:38 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 07:36:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:36:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:36:38 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 07:36:38 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 07:36:38 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 07:36:38 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 07:36:38 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 07:36:38 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 07:36:39 INFO - Downloading docopt-0.6.1.tar.gz 07:36:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:36:39 INFO - Installing collected packages: blobuploader, requests, docopt 07:36:39 INFO - Running setup.py install for blobuploader 07:36:39 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:36:39 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:36:39 INFO - Running setup.py install for requests 07:36:39 INFO - Running setup.py install for docopt 07:36:39 INFO - Successfully installed blobuploader requests docopt 07:36:39 INFO - Cleaning up... 07:36:39 INFO - Return code: 0 07:36:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:36: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')]} 07:36:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36: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')]} 07:36:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:39 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 0x7f077af70f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1583ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1580f60>, '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 0x1581d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15821d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1475243472.858512-887638643', '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 07:36:39 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 07:36:39 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 07:36:39 INFO - Using env: {'DISPLAY': ':0', 07:36:39 INFO - 'HOME': '/home/cltbld', 07:36:39 INFO - 'LANG': 'en_US.UTF-8', 07:36:39 INFO - 'LANGUAGE': 'en_US:en', 07:36:39 INFO - 'LOGNAME': 'cltbld', 07:36:39 INFO - 'MAIL': '/var/mail/cltbld', 07:36:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:39 INFO - 'MOZ_NO_REMOTE': '1', 07:36:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:36:39 INFO - 'NO_EM_RESTART': '1', 07:36:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:36:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:39 INFO - 'PWD': '/builds/slave/test', 07:36:39 INFO - 'SHELL': '/bin/bash', 07:36:39 INFO - 'SHLVL': '1', 07:36:39 INFO - 'TERM': 'linux', 07:36:39 INFO - 'TMOUT': '86400', 07:36:39 INFO - 'USER': 'cltbld', 07:36:39 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1475243472.858512-887638643', 07:36:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:39 INFO - '_': '/tools/buildbot/bin/python'} 07:36:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:36:40 INFO - Running setup.py (path:/tmp/pip-P5Mgdm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:36:40 INFO - Running setup.py (path:/tmp/pip-hBc1N2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:36:40 INFO - Running setup.py (path:/tmp/pip-7dzUfe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:36:40 INFO - Running setup.py (path:/tmp/pip-24K1Su-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:36:40 INFO - Running setup.py (path:/tmp/pip-RawAZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:36:40 INFO - Running setup.py (path:/tmp/pip-jdhPYV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:36:40 INFO - Running setup.py (path:/tmp/pip-8rJspl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:36:40 INFO - Running setup.py (path:/tmp/pip-M5Z598-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:36:40 INFO - Running setup.py (path:/tmp/pip-1YPHby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:36:40 INFO - Running setup.py (path:/tmp/pip-FIbSyl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:36:40 INFO - Running setup.py (path:/tmp/pip-pLNGdX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:36:40 INFO - Running setup.py (path:/tmp/pip-YYd7qM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:36:40 INFO - Running setup.py (path:/tmp/pip-mxQnWG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:36:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:36:40 INFO - Running setup.py (path:/tmp/pip-gSfcav-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:36:41 INFO - Running setup.py (path:/tmp/pip-yauUbg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:36:41 INFO - Running setup.py (path:/tmp/pip-7VGZFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:36:41 INFO - Running setup.py (path:/tmp/pip-xD3MC4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:36:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:36:41 INFO - Running setup.py install for manifestparser 07:36:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:36:41 INFO - Running setup.py install for mozcrash 07:36:41 INFO - Running setup.py install for mozdebug 07:36:41 INFO - Running setup.py install for mozdevice 07:36:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:36:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:36:41 INFO - Running setup.py install for mozfile 07:36:41 INFO - Running setup.py install for mozhttpd 07:36:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:36:41 INFO - Running setup.py install for mozinfo 07:36:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:36:41 INFO - Running setup.py install for mozInstall 07:36:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:36:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:36:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:36:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:36:42 INFO - Running setup.py install for mozleak 07:36:42 INFO - Running setup.py install for mozlog 07:36:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:36:42 INFO - Running setup.py install for moznetwork 07:36:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:36:42 INFO - Running setup.py install for mozprocess 07:36:42 INFO - Running setup.py install for mozprofile 07:36:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:36:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:36:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:36:42 INFO - Running setup.py install for mozrunner 07:36:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:36:42 INFO - Running setup.py install for mozscreenshot 07:36:42 INFO - Running setup.py install for moztest 07:36:42 INFO - Running setup.py install for mozversion 07:36:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:36:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:36:43 INFO - Cleaning up... 07:36:43 INFO - Return code: 0 07:36:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:36:43 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')]} 07:36:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:43 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')]} 07:36:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:43 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 0x7f077af70f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1583ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1580f60>, '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 0x1581d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15821d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1475243472.858512-887638643', '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 07:36:43 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 07:36:43 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 07:36:43 INFO - Using env: {'DISPLAY': ':0', 07:36:43 INFO - 'HOME': '/home/cltbld', 07:36:43 INFO - 'LANG': 'en_US.UTF-8', 07:36:43 INFO - 'LANGUAGE': 'en_US:en', 07:36:43 INFO - 'LOGNAME': 'cltbld', 07:36:43 INFO - 'MAIL': '/var/mail/cltbld', 07:36:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:43 INFO - 'MOZ_NO_REMOTE': '1', 07:36:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:36:43 INFO - 'NO_EM_RESTART': '1', 07:36:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:36:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:43 INFO - 'PWD': '/builds/slave/test', 07:36:43 INFO - 'SHELL': '/bin/bash', 07:36:43 INFO - 'SHLVL': '1', 07:36:43 INFO - 'TERM': 'linux', 07:36:43 INFO - 'TMOUT': '86400', 07:36:43 INFO - 'USER': 'cltbld', 07:36:43 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1475243472.858512-887638643', 07:36:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:43 INFO - '_': '/tools/buildbot/bin/python'} 07:36:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:36:43 INFO - Running setup.py (path:/tmp/pip-Bnpnc4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:36:43 INFO - Running setup.py (path:/tmp/pip-VZEXoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:36:43 INFO - Running setup.py (path:/tmp/pip-CSjXqZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:36:43 INFO - Running setup.py (path:/tmp/pip-QN_UwQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:36:43 INFO - Running setup.py (path:/tmp/pip-YEGnPL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:36:43 INFO - Running setup.py (path:/tmp/pip-6T31VJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:36:43 INFO - Running setup.py (path:/tmp/pip-iLJlZt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:36:43 INFO - Running setup.py (path:/tmp/pip-gCjxaX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:36:43 INFO - Running setup.py (path:/tmp/pip-ez9AK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:36:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:36:43 INFO - Running setup.py (path:/tmp/pip-oPXyKC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:36:43 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)) 07:36:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:36:44 INFO - Running setup.py (path:/tmp/pip-AeVT2Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:36:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:36:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:36:44 INFO - Running setup.py (path:/tmp/pip-GmUHPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:36:44 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)) 07:36:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:36:44 INFO - Running setup.py (path:/tmp/pip-iL_XuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:36:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:36:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:36:44 INFO - Running setup.py (path:/tmp/pip-mivHri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:36:44 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)) 07:36:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:36:44 INFO - Running setup.py (path:/tmp/pip-a9m_D3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:36:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:36:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:36:44 INFO - Running setup.py (path:/tmp/pip-J8zAkq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:36:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:36:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:36:44 INFO - Running setup.py (path:/tmp/pip-3Aagcw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:36:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:36:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:36:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:36:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:36:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:36:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:36:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:36:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:36:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:36:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:36:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:36:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:36:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:36:46 INFO - Downloading blessings-1.6.tar.gz 07:36:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:36:46 INFO - Installing collected packages: blessings 07:36:46 INFO - Running setup.py install for blessings 07:36:46 INFO - Successfully installed blessings 07:36:46 INFO - Cleaning up... 07:36:46 INFO - Return code: 0 07:36:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:36:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:36:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:36:46 INFO - Reading from file tmpfile_stdout 07:36:46 INFO - Current package versions: 07:36:46 INFO - argparse == 1.2.1 07:36:46 INFO - blessings == 1.6 07:36:46 INFO - blobuploader == 1.2.4 07:36:46 INFO - docopt == 0.6.1 07:36:46 INFO - functools32 == 3.2.3-2 07:36:46 INFO - jsonschema == 2.5.1 07:36:46 INFO - manifestparser == 1.1 07:36:46 INFO - mozInstall == 1.12 07:36:46 INFO - mozcrash == 0.17 07:36:46 INFO - mozdebug == 0.1 07:36:46 INFO - mozdevice == 0.48 07:36:46 INFO - mozfile == 1.2 07:36:46 INFO - mozhttpd == 0.7 07:36:46 INFO - mozinfo == 0.9 07:36:46 INFO - mozleak == 0.1 07:36:46 INFO - mozlog == 3.4 07:36:46 INFO - moznetwork == 0.27 07:36:46 INFO - mozprocess == 0.23 07:36:46 INFO - mozprofile == 0.28 07:36:46 INFO - mozrunner == 6.12 07:36:46 INFO - mozscreenshot == 0.1 07:36:46 INFO - mozsystemmonitor == 0.3 07:36:46 INFO - moztest == 0.7 07:36:46 INFO - mozversion == 1.4 07:36:46 INFO - psutil == 3.1.1 07:36:46 INFO - requests == 1.2.3 07:36:46 INFO - wsgiref == 0.1.2 07:36:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:36: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')]} 07:36:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36: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')]} 07:36:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:46 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 0x7f077af70f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1583ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1580f60>, '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 0x1581d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15821d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1475243472.858512-887638643', '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 07:36:46 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 07:36:46 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 07:36:46 INFO - Using env: {'DISPLAY': ':0', 07:36:46 INFO - 'HOME': '/home/cltbld', 07:36:46 INFO - 'LANG': 'en_US.UTF-8', 07:36:46 INFO - 'LANGUAGE': 'en_US:en', 07:36:46 INFO - 'LOGNAME': 'cltbld', 07:36:46 INFO - 'MAIL': '/var/mail/cltbld', 07:36:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:46 INFO - 'MOZ_NO_REMOTE': '1', 07:36:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:36:46 INFO - 'NO_EM_RESTART': '1', 07:36:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:36:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:46 INFO - 'PWD': '/builds/slave/test', 07:36:46 INFO - 'SHELL': '/bin/bash', 07:36:46 INFO - 'SHLVL': '1', 07:36:46 INFO - 'TERM': 'linux', 07:36:46 INFO - 'TMOUT': '86400', 07:36:46 INFO - 'USER': 'cltbld', 07:36:46 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1475243472.858512-887638643', 07:36:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:46 INFO - '_': '/tools/buildbot/bin/python'} 07:36:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:47 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)) 07:36:47 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)) 07:36:47 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)) 07:36:47 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)) 07:36:47 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)) 07:36:47 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)) 07:36:47 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)) 07:36:47 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)) 07:36:47 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)) 07:36:47 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)) 07:36:47 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)) 07:36:47 INFO - Cleaning up... 07:36:47 INFO - Return code: 0 07:36:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:36:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:47 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 0x7f077af70f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14b68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1583ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1580f60>, '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 0x1581d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15821d0>, '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': '6d15e502d8e29d5d2676d664000001c3-1475243472.858512-887638643', '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 07:36:47 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 07:36:47 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 07:36:47 INFO - Using env: {'DISPLAY': ':0', 07:36:47 INFO - 'HOME': '/home/cltbld', 07:36:47 INFO - 'LANG': 'en_US.UTF-8', 07:36:47 INFO - 'LANGUAGE': 'en_US:en', 07:36:47 INFO - 'LOGNAME': 'cltbld', 07:36:47 INFO - 'MAIL': '/var/mail/cltbld', 07:36:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:47 INFO - 'MOZ_NO_REMOTE': '1', 07:36:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:36:47 INFO - 'NO_EM_RESTART': '1', 07:36:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:36:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:47 INFO - 'PWD': '/builds/slave/test', 07:36:47 INFO - 'SHELL': '/bin/bash', 07:36:47 INFO - 'SHLVL': '1', 07:36:47 INFO - 'TERM': 'linux', 07:36:47 INFO - 'TMOUT': '86400', 07:36:47 INFO - 'USER': 'cltbld', 07:36:47 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1475243472.858512-887638643', 07:36:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:47 INFO - '_': '/tools/buildbot/bin/python'} 07:36:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:36:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:36:47 INFO - Cleaning up... 07:36:47 INFO - Return code: 0 07:36:47 INFO - Running post-action listener: _resource_record_post_action 07:36:47 INFO - Running post-action listener: _start_resource_monitoring 07:36:47 INFO - Starting resource monitoring. 07:36:47 INFO - [mozharness: 2016-09-30 14:36:47.352837Z] Finished create-virtualenv step (success) 07:36:47 INFO - [mozharness: 2016-09-30 14:36:47.353333Z] Running install step. 07:36:47 INFO - Running pre-action listener: _resource_record_pre_action 07:36:47 INFO - Running main action method: install 07:36:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:36:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:36:47 INFO - Reading from file tmpfile_stdout 07:36:47 INFO - Detecting whether we're running mozinstall >=1.0... 07:36:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:36:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:36:47 INFO - Reading from file tmpfile_stdout 07:36:47 INFO - Output received: 07:36:47 INFO - Usage: mozinstall [options] installer 07:36:47 INFO - Options: 07:36:47 INFO - -h, --help show this help message and exit 07:36:47 INFO - -d DEST, --destination=DEST 07:36:47 INFO - Directory to install application into. [default: 07:36:47 INFO - "/builds/slave/test"] 07:36:47 INFO - --app=APP Application being installed. [default: firefox] 07:36:47 INFO - mkdir: /builds/slave/test/build/application 07:36:47 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'] 07:36:47 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 07:37:03 INFO - Reading from file tmpfile_stdout 07:37:03 INFO - Output received: 07:37:03 INFO - /builds/slave/test/build/application/firefox/firefox 07:37:03 INFO - Running post-action listener: _resource_record_post_action 07:37:03 INFO - [mozharness: 2016-09-30 14:37:03.600944Z] Finished install step (success) 07:37:03 INFO - [mozharness: 2016-09-30 14:37:03.601276Z] Running run-tests step. 07:37:03 INFO - Running pre-action listener: _resource_record_pre_action 07:37:03 INFO - Running main action method: run_tests 07:37:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:37:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:37:03 INFO - Python 2.7.3 07:37:03 INFO - Return code: 0 07:37:03 INFO - grabbing minidump binary from tooltool 07:37: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')]} 07:37:03 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 0x1580f60>, '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 0x1581d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15821d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:37:03 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 07:37:03 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 07:37:03 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 07:37:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:37:03 INFO - Return code: 0 07:37:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:37:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:37:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:37:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:37:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:37:03 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-023', '--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 07:37:03 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-023 --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 07:37:03 INFO - Using env: {'DISPLAY': ':0', 07:37:03 INFO - 'HOME': '/home/cltbld', 07:37:03 INFO - 'LANG': 'en_US.UTF-8', 07:37:03 INFO - 'LANGUAGE': 'en_US:en', 07:37:03 INFO - 'LOGNAME': 'cltbld', 07:37:03 INFO - 'MAIL': '/var/mail/cltbld', 07:37:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:37:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:37:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:03 INFO - 'MOZ_NO_REMOTE': '1', 07:37:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:37:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:37:03 INFO - 'NO_EM_RESTART': '1', 07:37:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:37:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:03 INFO - 'PWD': '/builds/slave/test', 07:37:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:37:03 INFO - 'SHELL': '/bin/bash', 07:37:03 INFO - 'SHLVL': '1', 07:37:03 INFO - 'TERM': 'linux', 07:37:03 INFO - 'TMOUT': '86400', 07:37:03 INFO - 'USER': 'cltbld', 07:37:03 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1475243472.858512-887638643', 07:37:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:03 INFO - '_': '/tools/buildbot/bin/python'} 07:37:03 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-023', '--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 07:37:03 INFO - mozversion application_buildid: 20160930013320 07:37:03 INFO - mozversion application_changeset: 805d5fc3d66c4fa585a3de407b1e0b055f78eb58 07:37:03 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:37:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:37:03 INFO - mozversion application_name: Firefox 07:37:03 INFO - mozversion application_remotingname: firefox-dev 07:37:03 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:37:03 INFO - mozversion application_vendor: Mozilla 07:37:03 INFO - mozversion application_version: 51.0a2 07:37:03 INFO - mozversion platform_buildid: 20160930013320 07:37:03 INFO - mozversion platform_changeset: 805d5fc3d66c4fa585a3de407b1e0b055f78eb58 07:37:03 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:37:03 INFO - mozversion platform_version: 51.0a2 07:37:03 INFO - using testdate: 1475246223 07:37:03 INFO - actual date: 1475246223 07:37:03 INFO - starting webserver on 'localhost:34948' 07:37:03 INFO - SUITE-START | Running 2 tests 07:37:03 INFO - TEST-START | dromaeo_css 07:37:03 INFO - operating with platform_type : linux_ 07:37:03 INFO - Initialising browser for dromaeo_css test... 07:37:03 INFO - TEST-INFO | started process 5403 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpW8Ei7S/profile http://localhost:34948/getInfo.html) 07:37:07 INFO - PROCESS | 5403 | __metrics Screen width/height:1600/1200 07:37:07 INFO - PROCESS | 5403 | colorDepth:24 07:37:07 INFO - PROCESS | 5403 | Browser inner width/height: 1024/697 07:37:07 INFO - PROCESS | 5403 | __metrics 07:37:08 INFO - PROCESS | 5403 | 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" 07:37:08 INFO - PROCESS | 5403 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:37:08 INFO - PROCESS | 5403 | console.error: PushService: 07:37:08 INFO - PROCESS | 5403 | stateChangeProcessEnqueue: Error transitioning state 07:37:08 INFO - PROCESS | 5403 | UnknownError 07:37:09 INFO - TEST-INFO | 5403: exit 0 07:37:09 INFO - Browser initialized. 07:37:09 INFO - Running cycle 1/1 for dromaeo_css test... 07:37:09 INFO - TEST-INFO | started process 5493 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpW8Ei7S/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:37:21 INFO - PROCESS | 5493 | RSS: Main: 144310272 07:37:21 INFO - PROCESS | 5493 | 07:38:12 INFO - PROCESS | 5493 | 1475246292033 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] 07:40:21 INFO - PROCESS | 5493 | Cycle 1(1): loaded http://localhost:34948/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34948/tests/dromaeo/cssquery-ext.html) 07:40:22 INFO - PROCESS | 5493 | RSS: Main: 241410048 07:40:22 INFO - PROCESS | 5493 | 07:43:24 INFO - PROCESS | 5493 | Cycle 1(1): loaded http://localhost:34948/tests/dromaeo/cssquery-ext.html (next: http://localhost:34948/tests/dromaeo/cssquery-jquery.html) 07:43:25 INFO - PROCESS | 5493 | RSS: Main: 235114496 07:43:25 INFO - PROCESS | 5493 | 07:46:27 INFO - PROCESS | 5493 | Cycle 1(1): loaded http://localhost:34948/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34948/tests/dromaeo/cssquery-mootools.html) 07:46:27 INFO - PROCESS | 5493 | RSS: Main: 173617152 07:46:27 INFO - PROCESS | 5493 | 07:49:29 INFO - PROCESS | 5493 | Cycle 1(1): loaded http://localhost:34948/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34948/tests/dromaeo/cssquery-prototype.html) 07:49:30 INFO - PROCESS | 5493 | RSS: Main: 175673344 07:49:30 INFO - PROCESS | 5493 | 07:52:32 INFO - PROCESS | 5493 | Cycle 1(1): loaded http://localhost:34948/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34948/tests/dromaeo/cssquery-yui.html) 07:52:33 INFO - PROCESS | 5493 | RSS: Main: 177401856 07:52:33 INFO - PROCESS | 5493 | 07:55:36 INFO - PROCESS | 5493 | Cycle 1(1): loaded http://localhost:34948/tests/dromaeo/cssquery-yui.html (next: http://localhost:34948/tests/dromaeo/cssquery-dojo.html) 07:55:36 INFO - PROCESS | 5493 | RSS: Main: 266895360 07:55:36 INFO - PROCESS | 5493 | 07:55:36 INFO - PROCESS | 5493 | __start_tp_report 07:55:36 INFO - PROCESS | 5493 | _x_x_mozilla_page_load 07:55:36 INFO - PROCESS | 5493 | _x_x_mozilla_page_load_details 07:55:36 INFO - PROCESS | 5493 | |i|pagename|runs| 07:55:36 INFO - PROCESS | 5493 | |0;dojo.html;3093.938121237575;3132.8746850125995;3146.496560550311;3172.7144556989865;3181.3160170563324;5559.416261292562;5691.146328050784;5691.8577035574135;5700.68646224458;5708.200851880735;5486.643368181066;5583.972080139593;5601.243832082668;5606.915896261549;5638.605297629168;5019.297298378221;5022.799088036474;5051.343325367716;5108;5118.027574760802;4928.236123400879;5068.290439338495;5119.334486516766;5128.615353848458;5134.7432628368615;4914.582260507861;5031.144705400091;5059.3169922060415;5097.9490205097845;5121.28302037715;5086.109930762121;5117.104506711311;5119.616028797836;5173.144735332695;5186.299849520342;5010.5240002199735;5073.543381095719;5126.333576635014;5143.074246635604;5165.948340516585;5400.91898621521;5401.243825864382;5469.890602187974;5493.368262649767;5518.530924871391;5822.214001109838;5830.854228644275;5841.532677385799;5903.586748927577;5908.084246941731;5945.227120474311;5948.048312270059;5951.9107213391835;5968.492678122365;5971.074483455028;5285.524302812765;5318.069337865897;5489.64317319373;5528.86177845549;5567.415421380778;6091.878162436727;6113.786017489456;6116.26604807426;6119.051547010221;6121.204243448412;5840.474357307863;5841.970790146107;5847.970760146172;5849.064149736022;5855.414458554111;5713.914291285634;5777.740001699934;5847.386024467455;5906.911396329058;5915.881682366328;5946;5965;5966.940330596725;5970.044792833126;5986.910196347058;5923.703814809242;5971.790987315423;5972.283326000907;5989.401059893976;5994.250718660168;4449;4521.163584736769;4550.271956486946;4609.262517997105;4622.2835460503675;4089.345704687236;4104.220198162399;4133.565975572582;4134.979325103355;4155.833766649361;5706.885862282731;5709.543236541067;5717.371089180187;5717.628354156966;5718.228039214736;5859.53123750099;5904.114382842608;5948.553858460633;6053.092036194606;6053.636781793019;5597.216389705444;5670.886582268414;5676.290463692038;5705.657660540381;5718.285214348207;5195.922061169085;5200.141976573823;5200.7919683213;5202.167653175474;5202.4277329493725;5754.510866576305;5858.8242352729485;6203.4416902479;6211;6211.440970312603;5776.451237132465;5818.94181058184;5843.94156058434;5929.851753706192;5974.940250597612;6131.448169664752;6135.662538560422;6137.815865524041;6138.785142519991;6143.262808462834;5606.355269143937;5841.970790146022;5843.474087332161;5843.853903652443;5849.883002339844;5692.203091567101;5804.303483581997;5813.680247586423;5826.359100498857;5923.940760592339;5709.3719690834805;5754.482096611325;5754.9424505754405;5758.193852860519;5765.250517432707;5728.2266893970445;5740;5741.827745167651;5743.913841292468;5744.281964754406;5517.641353312182;5529.889402212014;5783.537317014713;5798.478136967693;5843.29880414353;5930.28836539618;5943.970280148572;5950.256217972754;5959.986802243716;5972.641641501524;5432.619716619799;5826.73779679907;5830.387809279964;5831.387704291158;5836.182934389104 07:55:36 INFO - PROCESS | 5493 | |1;ext.html;584.6755050946723;615.3661728419728;620.5904103291829;632.6235889645662;638.1608185236414;33852.32295354091;36982.630173698286;46477.999999999956;46946.06107877849;47074.29388559166;49301.50698493023;49633.50366496334;49646.50353496464;49801;49938;9326.253899688043;9488.146066853982;9491.95254023729;9493.240540756757;9498.240140788721;8598.753180788783;8799;11074.667759967213;11317.264377815416;11332.150088743378;11476;11507.769844603103;11537.826932596017;11545.769084618345;11575.710607234802;11286.153538484605;11344.886551134465;11358.71603209918;11367.772644547103;11378.431078446045;10997.780044399067;11196.552137914474;11199.104071674328;11201.271917325348;11210;45556.54443455556;45798.31302530465;45998.08003839904;46017;46040.76979615114;3865.4588357629673;3976.602339766029;4044.1305119399176;4070.267351876661;4208.74747515147;34757;34895.65104348975;35074.64925350739;35228.119297017525;35425.64574354249;4351.282038463682;4373;4393.143337049277;4489.506154323022;4494.1910456117885;321400;323470;324749;325010;325479;63792.4914129718;64208.67895660492;64277.357226428074;64329.99999999906;64438;40638;41283.174336513104;41400.58599413967;44117.33823992643;44117.77941110274;18702.625947481247;18857.90571047136;18882.3391181308;18887;18940.43178704641;13707.177569345871;15443.92278038625;15623.3750649975;15638.2180890955;15646.843531564766;18646.53383665392;20475.488112797;21101.894490527757;21163.576728465345;21918.342449726533;7307.451941104438;7392.778216653509;7522.172561018284;7524.510906791151;7616.428767842434;974.7563109222694;1106.5020740666732;1120.8935151160626;1133.0935251798528;1219.1283232488813;33934.49098263528;34296.48555271673;34584.308313833586;34736.65263347334;34870.476942845875;1120.2214460949724;1156.3928937307812;1173.988260117405;1183.8461000069935;1236.1841184817977;18080.095995200187;18341.266349345875;18488.35290764817;18568.44294671162;18579.72130418045;517464;519610;524046;525719;527084;62813.37186628167;63338;63400.682996585645;63608.68195658992;63687.6815615919;1301.271288078679;1361.3805718398105;1396.2599822094305;1433.7132573485344;1447.5874375803057;31363.215919602193;36215.45676814902;36408.635913641585;36622.633773661924;36633.26733465369;53695.19457208067;54279.45720542902;54351.45648543464;54359.184612230056;54381.72809135929;413923;416165;416326;417134;417298;31368.372632546765;31554.842225788725;31775.68224317727;32167.195820104684;33306.83346583252;52171.73914130405;52623;52759.20861187162;52878.73560632172;53848;44768.55231447644;46107;46453;46816.53183468122;47807.760961194974;45971.3104303429;46778;46925.296120557534;47100.29349559691;47789;44093.559064408946;44988;45414.77292613648;46032.30951535796;47794.76102619465;37071.62928370682;37961.62038379581;39801.22270916178;40650.79674601608;41847.58152418437;29225.7077429223;30002.84998574993;30453.695463045973;30686.539701904934;30822 07:55:36 INFO - PROCESS | 5493 | |2;jquery.html;386.96710779583736;407.5842640506683;419.55317586770093;422.2462903716866;436.6943139802139;14926.77609835852;16665.25006374713;20662.896685516553;21253.36239912805;21342.253021144264;20160.798392016077;20450.386488405373;20640.174393024263;21300;21452.678209826867;5742.39704830993;5814.1278808178795;5836.708164591774;5869.706514674271;5875.265591801025;4267.231898258327;5871.530277577805;5948.7620495180145;5954.047352423613;5954.761809527614;4448.243798554237;4476.910461790762;6369.076483909854;6625.337466253384;6680;6371.52213583983;6563.638999855006;6652.06871038055;6656.434203092719;6671.6997735101795;5883.294004719461;5884.970575147139;5886.234789477383;5892.998190307658;5894.027485464893;24823.751762482505;25090.372740681447;25125.49749005028;25188.496230075296;25347.87326063376;2579.071534247669;2669.746374094458;2851.443968426158;3235.822029788361;3330.3672302262494;13663.453461861514;15161.545153645407;15217.923910380488;15494.690106197926;16537.503874883652;1950.3466338776527;2025.4936265933516;2025.6151331247015;2028.3103744726716;2088.043688910308;58432.70783646055;74782.25217747808;78384.21615783827;84768.15231847664;86346.13653863444;11056.778864422828;11210.271332363533;11335.546578136782;11390.772184556263;11672.066234701204;16487.752683709754;16608.501744947673;16609.584760381087;16623.251953662115;16648.001119932844;8460.957695211608;8700.434471759441;8773.605187766438;8856.3800533962;8860.069692682175;8466.872996905051;8580.742577722553;8599.14008599135;8726.607302671267;8788.736337909744;6965.955106734031;7114.822129446702;7118.537295075907;7229.349358557755;7257.201707812136;1768.3644309967865;1949.991508745994;1977.940661780149;2000.3398878370103;2013.3154727392757;914.3371055984358;958.5351104714208;1044.738815296176;1076.278893141597;1084.3656460970421;19836.702449463268;19873.608847380543;20623.896880515502;21265.468363291042;21465.67801482979;1020.2297265564599;1037.2998226197437;1062.150279776176;1068.5672302717412;1069.9037086662238;11459.541618335339;12301.876981230253;12374;12443.808643907045;12489.375531223403;500079;500861;504879;508573;512942;82173.58913205395;83213.58393208116;83976.58011709902;84504;90721;1021.7803172317987;1046.8115739166872;1084.7776205877776;1088.455772113943;1093.2347356850237;18553.628927421647;18612.16245268939;19004.809951900304;19049.333273335367;19479.8052019478;40408.59591404048;41823.37264940968;43992;45982.54017459916;46732.29901551548;54957.72521137369;55554.44445555493;55679.44320556905;62761;63067.369326307984;7873.212678732081;8484.024337201301;8517.95741021291;8529.658813647384;8637.524936128573;10524.210684198562;10537.473126343499;13187.274699891597;14089.365978530786;14136.363863625957;12179.634610961686;12360;12412.56556020535;12460.314682692539;12646.367681616066;11944.522219111139;12561.120721549769;12573.811392828931;12611.558595449114;12913.160644558073;12874.16317939331;12929;12969.935150324189;12970.416331265304;13037.608871733863;8258.628361723617;8299.087100418828;8373.790655233424;8486.490810551386;9001.504917229353;10482.947585262025;11636.592719254786;11983.940080299542;12421.503139874303;13096.51166278404 07:55:36 INFO - PROCESS | 5493 | |3;mootools.html;1464.4288727396313;1502.4591147187011;1617.9271932763024;1741.8954862708247;1772.3796671165094;7954.64204110815;8488.363372747037;8502.702405415812;8506.532140732257;8512.106228845967;7396.926030739691;7646.96176519118;7990.560519171458;8247.91752082479;8334.624941877615;2339.6139636959883;2363.598188307983;2366.1954935321987;2369.7156341239074;2373.2405630198346;2320.8027317678016;2340.0756701103055;2364.160722943356;2367.715874095111;2371.3597328721235;1999.800019998003;2061.7525896892394;2275.16956310947;2347.190219374313;2350.565145448089;2380.6310021946624;2383.5947888858936;2419.2621250518673;2419.9516009679883;2420.673209116782;2336.497653004612;2338.5556744218716;2340.485093279493;2342.437814924423;2344.367020904346;8208.507489550646;8297.25324720772;8307.418480706354;8317.83364332716;8435.873461898076;4923.556879880792;5046.571041461443;5050.090983622947;5051.797928082872;5057.721825299607;3203.871845126192;3214.694604012615;3372.814495202763;3394.439917413624;3398.3373242217776;1063.8138325792954;1066.7493139112303;1074.3446497636435;1080.1304949515636;1085.4572713643179;10704.892951070546;10791.298565593212;10868.184886133493;10874.565017399294;10875.129989600813;4300.225959327288;4362.8691139265875;4451.399061126771;4482.529334419904;4498.617617502484;9404.106609872051;9410.482423466776;9410.576524056434;9413.388129771543;9417.246620270362;4981.701097934136;4984.22744474607;5000;5003.499650034967;5004.974975125101;4544.795484203219;4578.542145785462;4579.793909274091;4592.770361481913;4672.719636821802;3925.293447179478;3945.822437990297;3984.820683069269;3993.7004724645767;4020.115574573589;1112.1603189591806;1220.371508673034;1223.1988047828686;1233.5929143382841;1256.9811452828214;871.877937088808;997.3467378866853;1002.2883752535636;1008.5310330696261;1014.6702321745462;5780.219670344533;5828.6502809831545;5859.912101318484;6005.309389420273;6033.396660333931;988.7626969527405;993.7167907146429;1001.1840350114634;1001.354126588361;1017.9643712470177;3981.761094334291;4116.361963895601;4369.300911854088;4518.118966801508;4633.745144017112;10081.798364032678;10235.078842904175;10257.17942564593;10271.435071071162;10280.691579252634;10269.691909242585;10277.383356998604;10280.07479326864;10287.74280642975;10288.12550933179;7313.926860731431;7606.23937606246;7612.695492180251;7674.501157424861;7729.4202934779;5997.610155339889;6131.724072416841;6162.322144564005;6243.750249990131;6245.0632405139595;9449.102335278138;9583.90416095858;9637.855432168662;9670.903290967;9678.903210968081;9517.191038761783;9638.710838674851;9699.563519641773;9712.562934667816;9782.657606983721;7591.089069311497;7726;7733.381329493514;7737.5744334062165;7747.380209583107;8172.38707096982;8180.918190818254;8205.261526462647;8272;8285.295749861089;6336.873262534816;7364;7458.47790654649;7490.812729681802;7509.136449308399;7352.0442342495135;7406.814829629302;7411.777646670608;7428.73999410018;7446.962765186139;7021.368076873106;7032.8593428130125;7049.7885063448175;7104.502684812013;7116.857662846817;5264.368275806928;5374.7581358839725;5398.271233383443;5445.972770136124;5480.753366098615;5378.946210538001;5433.7554810034435;5483;5486.723910825925;5630.831075067755 07:55:36 INFO - PROCESS | 5493 | |4;prototype.html;320.78026551812013;334.13459140825256;336.9612494563123;337.4853348643317;338.40778637384585;5582.832515024551;5668.461496157864;5675.51758100562;5680.119581464869;5703.3441154267275;5541.501264886159;5550.806576586033;5613.354464236614;5640.294963129609;5653.717314134287;4016.915432833148;4090.141070375217;4177.749335039892;4202.348635961415;4346.695731298811;4340.240457919867;4352.499462561815;4373.1909596724545;4375.584319489643;4381.518033016366;4321.092570560186;4322.891927701817;4327.588879056485;4336.262835318003;4352.97823510882;4226.640735537468;4310.482742070956;4321.978390108061;4371.606555410029;4378.5183629800695;3842.884713458601;3849;3851.1912498375373;3856.132370216707;3865.4588357629955;5406.8918621627345;5448.45515448452;5459.754311056012;5474.37044739856;5480.232767412565;4842.66101372904;4984.725840078794;5008.323876276729;5013.699178049329;5019.511439549515;5781.739821708034;5791.623544469596;5791.681457519835;5802.12968054791;5802.912956305659;4234.3860140279485;4243.787810609457;4250.256205164084;4256.787160641956;4272.572742725733;5736;6034.668093254869;6039;6058.182944930629;6068.605540639888;4228.9577104228565;4235.555266697014;4248.617624413818;4249.68127390447;4252.170826688766;4299.914001719948;4340.674449416307;4341.630961368256;4350.369196466495;4353.564643535685;4806.399200099988;4880.243562247857;4902.068606964665;4937.950620493749;5003.599712023102;5774;5806.680632565165;5825.563082768809;5832.562557808096;5848.736806843703;1471.683588028579;1473.850396690583;1478.7633978563379;1482.495951376537;1489.4935721854622;947.1901524196712;950.1923365139714;955.2310390135868;962.6775030364906;982.3271059324386;5507.972460137674;5673.971630141823;5682.687452190086;5694.772209111589;5695.943040569624;6014.338422773491;6020.849478763066;6027.758889644453;6047.15339852421;6080.513558915276;5516.503514683617;5659.603827732101;5686.800961966312;5707.6290041147195;5724.627899186539;3180.7773455858332;3206.701285979182;3211.518272259133;3217.069758145094;3223.6453990061545;6038.852618002566;6205.486777854976;6240.625562466266;6287.245530536365;6291.1192433058495;6154.692265386713;6158.753649854045;6159.876802463926;6193.7922105189955;6201.875962480815;6235.937640623536;6240.906386404207;6243.96878015607;6245.625962288289;6287.371262873676;5713.114467257582;5837.912431313449;5938.8218353449465;5957.76168953237;5971.552133589911;5837.591368604157;5893.263342082239;5894.793682221273;5896.616719913192;5897.405141737621;5910.822675319747;6109.450149486567;6120.8064427438;6163.476104531165;6217;4119.608637179464;4134.607212314825;4144.544100148921;4149.336106223111;4185.686073544557;6019.458248757633;6065.72704228302;6067.120267561268;6082.239720034996;6086.756529738761;5658.377578466283;5925.881482370414;5929.733162007634;5938.465538101592;5951.791687290924;5845.970770146122;5892.263467066617;5894.322152952465;5900.970495147497;5902.1736956826935;5628.662280263197;5790.420957904176;5863.706814659336;5864.44287792659;5895.793647222326;5893.145493903446;5907.822765317047;5907.9704601476715;5912.881742365214;5914.408559144051;5756.532084318639;5757.193992841089;5778.479936805707;5789.392113828156;5794.82615521535 07:55:36 INFO - PROCESS | 5493 | |5;yui.html;824.9628766705498;837.1377481194372;878.9340799440041;888.8000199955011;900.567727490804;16585.41951031714;17766.11169441526;17767.64464710705;17813.198406071726;17832.375866844664;16265;16429.507114786546;16445.91777041116;16470.34118635256;16471.17644117795;701.3302296307028;729.0849983271014;731.7073170731724;732.9486935914488;852.7612268564811;875.7854325690183;883.9071897450772;884.0805562215288;884.2793123604274;890.4523717913502;921.4379228670506;928.0023974227698;931.7018554062703;933.7385532051003;934.1219253901311;922.4511415707677;922.575615217001;923.3628796130682;923.7321176858703;923.8891333040011;543.7933585237624;562.5499600319728;564.6668465605312;687.3195536418959;688.3976520544524;12888.420021099073;15547;16051.277692503865;16053.518394448069;16773.412930547373;16506.42227522043;17144.399946001955;17201;17252.74120888188;17612.647747044986;13476.595702128952;13591.796123058162;13739.175649461064;13768.724625507433;13834.446622135103;4268.317069268902;4324.805383757738;4351.782410879443;4354.847580334705;4454.821807127711;91263.54368228116;91773;97869;97980;98119.50940245324;12441.129120961446;12459.626211213677;12465.875341246652;12598.118131730875;12706.745865082647;25774;25982;26012.479750405266;26053.73946260551;26055.869720651277;9071.138241867144;9079.319051071196;9500.572474238676;9565.521723913776;9990.600375984879;4695.5539223773685;4940.547975242434;4976.626752993468;5134.486551344836;5198.142306519458;3632.182758879231;3744.251149770057;3751.7561358512157;3770.736048476635;3787.6401741834484;606.9666168360783;608.9360617135137;612.788587937161;618.6535540097469;619.6715740657459;710.2897102897103;827.5034979012544;841.3984001439001;843.582426698788;855.2644725536034;10633.73415664599;11056.834147487794;11255.324680519032;11277.276288279125;11563.132765042656;612.4518555892452;630.2688880898136;633.6894921488438;758.4766511107318;776.7786180938406;5006.3992320921;5219.525037129703;5534.944650553523;5551.250581171571;5555.111182210827;130145.34927325303;134377.32811336077;138130.3093484526;138435;141793;104253.47873260586;117205.41397293129;120541;121272.39363803125;124496;104456;105326;105395.47302263592;105589;107993.46003270091;9903.306768526421;9993.10062094415;10052.346597471142;10260.846087308846;10298.794024119625;50208;59731.402685974324;60697.39302606917;61565.690401671454;62734.68632656807;50841.745791270805;51127.48872511227;74178.62910685412;76172;79115;13858.86141138616;14237.145771253758;14268.714625707635;14748.483803066842;14790;15766;16162.272697728866;16190.190490475665;16663.50009499717;16671;15004.849951500346;15579.221038948233;16081.678366432372;16143.354265829235;17046.48860534186;14787;15220.856841589697;15458.5362439127;15790.526284211492;16376.181190940644;15120.470783522524;15741.685166296838;15991.600209994844;16710.498685039467;16960.830391695927;12036.879631203927;12486.750264994831;12885.260300223781;12924.741505169655;13656.180629162282;12273.263604183778;12732.363381830686;13074.477020919438;13259.933700331436;13264.403101860247 07:55:36 INFO - PROCESS | 5493 | __end_tp_report 07:55:36 INFO - PROCESS | 5493 | __start_cc_report 07:55:36 INFO - PROCESS | 5493 | _x_x_mozilla_cycle_collect,908 07:55:36 INFO - PROCESS | 5493 | __end_cc_report 07:55:36 INFO - PROCESS | 5493 | __startTimestamp1475247336353__endTimestamp 07:55:36 INFO - PROCESS | 5493 | 07:55:36 INFO - PROCESS | 5493 | ------- Summary: start ------- 07:55:36 INFO - PROCESS | 5493 | Number of tests: 6 07:55:36 INFO - PROCESS | 5493 | 07:55:36 INFO - PROCESS | 5493 | [#0] dojo.html Cycles:165 Average:5516.38 Median:5728.23 stddev:620.36 (10.8%) stddev-sans-first:592.44 07:55:36 INFO - PROCESS | 5493 | Values: 3093.9 3132.9 3146.5 3172.7 3181.3 5559.4 5691.1 5691.9 5700.7 5708.2 5486.6 5584.0 5601.2 5606.9 5638.6 5019.3 5022.8 5051.3 5108.0 5118.0 4928.2 5068.3 5119.3 5128.6 5134.7 4914.6 5031.1 5059.3 5097.9 5121.3 5086.1 5117.1 5119.6 5173.1 5186.3 5010.5 5073.5 5126.3 5143.1 5165.9 5400.9 5401.2 5469.9 5493.4 5518.5 5822.2 5830.9 5841.5 5903.6 5908.1 5945.2 5948.0 5951.9 5968.5 5971.1 5285.5 5318.1 5489.6 5528.9 5567.4 6091.9 6113.8 6116.3 6119.1 6121.2 5840.5 5842.0 5848.0 5849.1 5855.4 5713.9 5777.7 5847.4 5906.9 5915.9 5946.0 5965.0 5966.9 5970.0 5986.9 5923.7 5971.8 5972.3 5989.4 5994.3 4449.0 4521.2 4550.3 4609.3 4622.3 4089.3 4104.2 4133.6 4135.0 4155.8 5706.9 5709.5 5717.4 5717.6 5718.2 5859.5 5904.1 5948.6 6053.1 6053.6 5597.2 5670.9 5676.3 5705.7 5718.3 5195.9 5200.1 5200.8 5202.2 5202.4 5754.5 5858.8 6203.4 6211.0 6211.4 5776.5 5818.9 5843.9 5929.9 5974.9 6131.4 6135.7 6137.8 6138.8 6143.3 5606.4 5842.0 5843.5 5843.9 5849.9 5692.2 5804.3 5813.7 5826.4 5923.9 5709.4 5754.5 5754.9 5758.2 5765.3 5728.2 5740.0 5741.8 5743.9 5744.3 5517.6 5529.9 5783.5 5798.5 5843.3 5930.3 5944.0 5950.3 5960.0 5972.6 5432.6 5826.7 5830.4 5831.4 5836.2 07:55:36 INFO - PROCESS | 5493 | 07:55:36 INFO - PROCESS | 5493 | [#1] ext.html Cycles:180 Average:60292.82 Median:40644.40 stddev:113150.79 (278.4%) stddev-sans-first:113379.40 07:55:36 INFO - PROCESS | 5493 | Values: 584.7 615.4 620.6 632.6 638.2 33852.3 36982.6 46478.0 46946.1 47074.3 49301.5 49633.5 49646.5 49801.0 49938.0 9326.3 9488.1 9492.0 9493.2 9498.2 8598.8 8799.0 11074.7 11317.3 11332.2 11476.0 11507.8 11537.8 11545.8 11575.7 11286.2 11344.9 11358.7 11367.8 11378.4 10997.8 11196.6 11199.1 11201.3 11210.0 45556.5 45798.3 45998.1 46017.0 46040.8 3865.5 3976.6 4044.1 4070.3 4208.7 34757.0 34895.7 35074.6 35228.1 35425.6 4351.3 4373.0 4393.1 4489.5 4494.2 321400.0 323470.0 324749.0 325010.0 325479.0 63792.5 64208.7 64277.4 64330.0 64438.0 40638.0 41283.2 41400.6 44117.3 44117.8 18702.6 18857.9 18882.3 18887.0 18940.4 13707.2 15443.9 15623.4 15638.2 15646.8 18646.5 20475.5 21101.9 21163.6 21918.3 7307.5 7392.8 7522.2 7524.5 7616.4 974.8 1106.5 1120.9 1133.1 1219.1 33934.5 34296.5 34584.3 34736.7 34870.5 1120.2 1156.4 1174.0 1183.8 1236.2 18080.1 18341.3 18488.4 18568.4 18579.7 517464.0 519610.0 524046.0 525719.0 527084.0 62813.4 63338.0 63400.7 63608.7 63687.7 1301.3 1361.4 1396.3 1433.7 1447.6 31363.2 36215.5 36408.6 36622.6 36633.3 53695.2 54279.5 54351.5 54359.2 54381.7 413923.0 416165.0 416326.0 417134.0 417298.0 31368.4 31554.8 31775.7 32167.2 33306.8 52171.7 52623.0 52759.2 52878.7 53848.0 44768.6 46107.0 46453.0 46816.5 47807.8 45971.3 46778.0 46925.3 47100.3 47789.0 44093.6 44988.0 45414.8 46032.3 47794.8 37071.6 37961.6 39801.2 40650.8 41847.6 29225.7 30002.8 30453.7 30686.5 30822.0 07:55:36 INFO - PROCESS | 5493 | 07:55:36 INFO - PROCESS | 5493 | [#2] jquery.html Cycles:180 Average:29796.71 Median:25157.00 stddev:82963.09 (329.8%) stddev-sans-first:83166.44 07:55:36 INFO - PROCESS | 5493 | Values: 387.0 407.6 419.6 422.2 436.7 14926.8 16665.3 20662.9 21253.4 21342.3 20160.8 20450.4 20640.2 21300.0 21452.7 5742.4 5814.1 5836.7 5869.7 5875.3 4267.2 5871.5 5948.8 5954.0 5954.8 4448.2 4476.9 6369.1 6625.3 6680.0 6371.5 6563.6 6652.1 6656.4 6671.7 5883.3 5885.0 5886.2 5893.0 5894.0 24823.8 25090.4 25125.5 25188.5 25347.9 2579.1 2669.7 2851.4 3235.8 3330.4 13663.5 15161.5 15217.9 15494.7 16537.5 1950.3 2025.5 2025.6 2028.3 2088.0 58432.7 74782.3 78384.2 84768.2 86346.1 11056.8 11210.3 11335.5 11390.8 11672.1 16487.8 16608.5 16609.6 16623.3 16648.0 8461.0 8700.4 8773.6 8856.4 8860.1 8466.9 8580.7 8599.1 8726.6 8788.7 6966.0 7114.8 7118.5 7229.3 7257.2 1768.4 1950.0 1977.9 2000.3 2013.3 914.3 958.5 1044.7 1076.3 1084.4 19836.7 19873.6 20623.9 21265.5 21465.7 1020.2 1037.3 1062.2 1068.6 1069.9 11459.5 12301.9 12374.0 12443.8 12489.4 500079.0 500861.0 504879.0 508573.0 512942.0 82173.6 83213.6 83976.6 84504.0 90721.0 1021.8 1046.8 1084.8 1088.5 1093.2 18553.6 18612.2 19004.8 19049.3 19479.8 40408.6 41823.4 43992.0 45982.5 46732.3 54957.7 55554.4 55679.4 62761.0 63067.4 7873.2 8484.0 8518.0 8529.7 8637.5 10524.2 10537.5 13187.3 14089.4 14136.4 12179.6 12360.0 12412.6 12460.3 12646.4 11944.5 12561.1 12573.8 12611.6 12913.2 12874.2 12929.0 12969.9 12970.4 13037.6 8258.6 8299.1 8373.8 8486.5 9001.5 10482.9 11636.6 11983.9 12421.5 13096.5 07:55:36 INFO - PROCESS | 5493 | 07:55:36 INFO - PROCESS | 5493 | [#3] mootools.html Cycles:180 Average:5583.31 Median:5004.24 stddev:3021.00 (60.4%) stddev-sans-first:3013.61 07:55:36 INFO - PROCESS | 5493 | Values: 1464.4 1502.5 1617.9 1741.9 1772.4 7954.6 8488.4 8502.7 8506.5 8512.1 7396.9 7647.0 7990.6 8247.9 8334.6 2339.6 2363.6 2366.2 2369.7 2373.2 2320.8 2340.1 2364.2 2367.7 2371.4 1999.8 2061.8 2275.2 2347.2 2350.6 2380.6 2383.6 2419.3 2420.0 2420.7 2336.5 2338.6 2340.5 2342.4 2344.4 8208.5 8297.3 8307.4 8317.8 8435.9 4923.6 5046.6 5050.1 5051.8 5057.7 3203.9 3214.7 3372.8 3394.4 3398.3 1063.8 1066.7 1074.3 1080.1 1085.5 10704.9 10791.3 10868.2 10874.6 10875.1 4300.2 4362.9 4451.4 4482.5 4498.6 9404.1 9410.5 9410.6 9413.4 9417.2 4981.7 4984.2 5000.0 5003.5 5005.0 4544.8 4578.5 4579.8 4592.8 4672.7 3925.3 3945.8 3984.8 3993.7 4020.1 1112.2 1220.4 1223.2 1233.6 1257.0 871.9 997.3 1002.3 1008.5 1014.7 5780.2 5828.7 5859.9 6005.3 6033.4 988.8 993.7 1001.2 1001.4 1018.0 3981.8 4116.4 4369.3 4518.1 4633.7 10081.8 10235.1 10257.2 10271.4 10280.7 10269.7 10277.4 10280.1 10287.7 10288.1 7313.9 7606.2 7612.7 7674.5 7729.4 5997.6 6131.7 6162.3 6243.8 6245.1 9449.1 9583.9 9637.9 9670.9 9678.9 9517.2 9638.7 9699.6 9712.6 9782.7 7591.1 7726.0 7733.4 7737.6 7747.4 8172.4 8180.9 8205.3 8272.0 8285.3 6336.9 7364.0 7458.5 7490.8 7509.1 7352.0 7406.8 7411.8 7428.7 7447.0 7021.4 7032.9 7049.8 7104.5 7116.9 5264.4 5374.8 5398.3 5446.0 5480.8 5378.9 5433.8 5483.0 5486.7 5630.8 07:55:36 INFO - PROCESS | 5493 | 07:55:36 INFO - PROCESS | 5493 | [#4] prototype.html Cycles:180 Average:4927.02 Median:5684.74 stddev:1461.23 (25.7%) stddev-sans-first:1423.84 07:55:36 INFO - PROCESS | 5493 | Values: 320.8 334.1 337.0 337.5 338.4 5582.8 5668.5 5675.5 5680.1 5703.3 5541.5 5550.8 5613.4 5640.3 5653.7 4016.9 4090.1 4177.7 4202.3 4346.7 4340.2 4352.5 4373.2 4375.6 4381.5 4321.1 4322.9 4327.6 4336.3 4353.0 4226.6 4310.5 4322.0 4371.6 4378.5 3842.9 3849.0 3851.2 3856.1 3865.5 5406.9 5448.5 5459.8 5474.4 5480.2 4842.7 4984.7 5008.3 5013.7 5019.5 5781.7 5791.6 5791.7 5802.1 5802.9 4234.4 4243.8 4250.3 4256.8 4272.6 5736.0 6034.7 6039.0 6058.2 6068.6 4229.0 4235.6 4248.6 4249.7 4252.2 4299.9 4340.7 4341.6 4350.4 4353.6 4806.4 4880.2 4902.1 4938.0 5003.6 5774.0 5806.7 5825.6 5832.6 5848.7 1471.7 1473.9 1478.8 1482.5 1489.5 947.2 950.2 955.2 962.7 982.3 5508.0 5674.0 5682.7 5694.8 5695.9 6014.3 6020.8 6027.8 6047.2 6080.5 5516.5 5659.6 5686.8 5707.6 5724.6 3180.8 3206.7 3211.5 3217.1 3223.6 6038.9 6205.5 6240.6 6287.2 6291.1 6154.7 6158.8 6159.9 6193.8 6201.9 6235.9 6240.9 6244.0 6245.6 6287.4 5713.1 5837.9 5938.8 5957.8 5971.6 5837.6 5893.3 5894.8 5896.6 5897.4 5910.8 6109.5 6120.8 6163.5 6217.0 4119.6 4134.6 4144.5 4149.3 4185.7 6019.5 6065.7 6067.1 6082.2 6086.8 5658.4 5925.9 5929.7 5938.5 5951.8 5846.0 5892.3 5894.3 5901.0 5902.2 5628.7 5790.4 5863.7 5864.4 5895.8 5893.1 5907.8 5908.0 5912.9 5914.4 5756.5 5757.2 5778.5 5789.4 5794.8 07:55:36 INFO - PROCESS | 5493 | 07:55:36 INFO - PROCESS | 5493 | [#5] yui.html Cycles:180 Average:23876.16 Median:26033.11 stddev:35242.17 (135.4%) stddev-sans-first:35298.53 07:55:36 INFO - PROCESS | 5493 | Values: 825.0 837.1 878.9 888.8 900.6 16585.4 17766.1 17767.6 17813.2 17832.4 16265.0 16429.5 16445.9 16470.3 16471.2 701.3 729.1 731.7 732.9 852.8 875.8 883.9 884.1 884.3 890.5 921.4 928.0 931.7 933.7 934.1 922.5 922.6 923.4 923.7 923.9 543.8 562.5 564.7 687.3 688.4 12888.4 15547.0 16051.3 16053.5 16773.4 16506.4 17144.4 17201.0 17252.7 17612.6 13476.6 13591.8 13739.2 13768.7 13834.4 4268.3 4324.8 4351.8 4354.8 4454.8 91263.5 91773.0 97869.0 97980.0 98119.5 12441.1 12459.6 12465.9 12598.1 12706.7 25774.0 25982.0 26012.5 26053.7 26055.9 9071.1 9079.3 9500.6 9565.5 9990.6 4695.6 4940.5 4976.6 5134.5 5198.1 3632.2 3744.3 3751.8 3770.7 3787.6 607.0 608.9 612.8 618.7 619.7 710.3 827.5 841.4 843.6 855.3 10633.7 11056.8 11255.3 11277.3 11563.1 612.5 630.3 633.7 758.5 776.8 5006.4 5219.5 5534.9 5551.3 5555.1 130145.3 134377.3 138130.3 138435.0 141793.0 104253.5 117205.4 120541.0 121272.4 124496.0 104456.0 105326.0 105395.5 105589.0 107993.5 9903.3 9993.1 10052.3 10260.8 10298.8 50208.0 59731.4 60697.4 61565.7 62734.7 50841.7 51127.5 74178.6 76172.0 79115.0 13858.9 14237.1 14268.7 14748.5 14790.0 15766.0 16162.3 16190.2 16663.5 16671.0 15004.8 15579.2 16081.7 16143.4 17046.5 14787.0 15220.9 15458.5 15790.5 16376.2 15120.5 15741.7 15991.6 16710.5 16960.8 12036.9 12486.8 12885.3 12924.7 13656.2 12273.3 12732.4 13074.5 13259.9 13264.4 07:55:36 INFO - PROCESS | 5493 | -------- Summary: end -------- 07:55:36 INFO - PROCESS | 5493 | 07:55:37 INFO - TEST-INFO | 5493: exit 0 07:55:37 INFO - TEST-OK | dromaeo_css | took 1113258ms 07:55:37 INFO - TEST-START | kraken 07:55:37 INFO - operating with platform_type : linux_ 07:55:37 INFO - Initialising browser for kraken test... 07:55:37 INFO - TEST-INFO | started process 5809 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5u_GMv/profile http://localhost:34948/getInfo.html) 07:55:41 INFO - PROCESS | 5809 | __metrics Screen width/height:1600/1200 07:55:41 INFO - PROCESS | 5809 | colorDepth:24 07:55:41 INFO - PROCESS | 5809 | Browser inner width/height: 1024/697 07:55:41 INFO - PROCESS | 5809 | __metrics 07:55:41 INFO - PROCESS | 5809 | 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" 07:55:41 INFO - PROCESS | 5809 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:55:42 INFO - PROCESS | 5809 | console.error: PushService: 07:55:42 INFO - PROCESS | 5809 | stateChangeProcessEnqueue: Error transitioning state 07:55:42 INFO - PROCESS | 5809 | UnknownError 07:55:42 INFO - TEST-INFO | 5809: exit 0 07:55:42 INFO - Browser initialized. 07:55:42 INFO - Running cycle 1/1 for kraken test... 07:55:42 INFO - TEST-INFO | started process 5899 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5u_GMv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:55:54 INFO - PROCESS | 5899 | RSS: Main: 144732160 07:55:54 INFO - PROCESS | 5899 | 07:55:56 INFO - PROCESS | 5899 | Cycle 1(1): loaded http://localhost:34948/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34948/tests/kraken/driver.html?testName=audio-beat-detection) 07:55:57 INFO - PROCESS | 5899 | RSS: Main: 186347520 07:55:57 INFO - PROCESS | 5899 | 07:56:00 INFO - PROCESS | 5899 | Cycle 1(1): loaded http://localhost:34948/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34948/tests/kraken/driver.html?testName=audio-dft) 07:56:00 INFO - PROCESS | 5899 | RSS: Main: 175685632 07:56:00 INFO - PROCESS | 5899 | 07:56:03 INFO - PROCESS | 5899 | Cycle 1(1): loaded http://localhost:34948/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34948/tests/kraken/driver.html?testName=audio-fft) 07:56:04 INFO - PROCESS | 5899 | RSS: Main: 266694656 07:56:04 INFO - PROCESS | 5899 | 07:56:06 INFO - PROCESS | 5899 | Cycle 1(1): loaded http://localhost:34948/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34948/tests/kraken/driver.html?testName=audio-oscillator) 07:56:06 INFO - PROCESS | 5899 | RSS: Main: 172744704 07:56:06 INFO - PROCESS | 5899 | 07:56:09 INFO - PROCESS | 5899 | Cycle 1(1): loaded http://localhost:34948/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34948/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:56:09 INFO - PROCESS | 5899 | RSS: Main: 174292992 07:56:09 INFO - PROCESS | 5899 | 07:56:12 INFO - PROCESS | 5899 | Cycle 1(1): loaded http://localhost:34948/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34948/tests/kraken/driver.html?testName=imaging-darkroom) 07:56:13 INFO - PROCESS | 5899 | RSS: Main: 211189760 07:56:13 INFO - PROCESS | 5899 | 07:56:16 INFO - PROCESS | 5899 | Cycle 1(1): loaded http://localhost:34948/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34948/tests/kraken/driver.html?testName=imaging-desaturate) 07:56:17 INFO - PROCESS | 5899 | RSS: Main: 209911808 07:56:17 INFO - PROCESS | 5899 | 07:56:19 INFO - PROCESS | 5899 | Cycle 1(1): loaded http://localhost:34948/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34948/tests/kraken/driver.html?testName=json-parse-financial) 07:56:20 INFO - PROCESS | 5899 | RSS: Main: 209166336 07:56:20 INFO - PROCESS | 5899 | 07:56:22 INFO - PROCESS | 5899 | Cycle 1(1): loaded http://localhost:34948/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34948/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:56:22 INFO - PROCESS | 5899 | RSS: Main: 171036672 07:56:22 INFO - PROCESS | 5899 | 07:56:24 INFO - PROCESS | 5899 | Cycle 1(1): loaded http://localhost:34948/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34948/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:56:24 INFO - PROCESS | 5899 | RSS: Main: 178470912 07:56:24 INFO - PROCESS | 5899 | 07:56:26 INFO - PROCESS | 5899 | Cycle 1(1): loaded http://localhost:34948/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34948/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:56:27 INFO - PROCESS | 5899 | RSS: Main: 183005184 07:56:27 INFO - PROCESS | 5899 | 07:56:30 INFO - PROCESS | 5899 | Cycle 1(1): loaded http://localhost:34948/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34948/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:56:30 INFO - PROCESS | 5899 | RSS: Main: 183955456 07:56:30 INFO - PROCESS | 5899 | 07:56:33 INFO - PROCESS | 5899 | Cycle 1(1): loaded http://localhost:34948/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34948/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:56:33 INFO - PROCESS | 5899 | RSS: Main: 182759424 07:56:33 INFO - PROCESS | 5899 | 07:56:35 INFO - PROCESS | 5899 | Cycle 1(1): loaded http://localhost:34948/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34948/tests/kraken/driver.html?testName=ai-astar) 07:56:35 INFO - PROCESS | 5899 | RSS: Main: 234610688 07:56:35 INFO - PROCESS | 5899 | 07:56:35 INFO - PROCESS | 5899 | __start_tp_report 07:56:35 INFO - PROCESS | 5899 | _x_x_mozilla_page_load 07:56:35 INFO - PROCESS | 5899 | _x_x_mozilla_page_load_details 07:56:35 INFO - PROCESS | 5899 | |i|pagename|runs| 07:56:35 INFO - PROCESS | 5899 | |0;ai-astar;107;110;112;110;111;110;111;110;108;109 07:56:35 INFO - PROCESS | 5899 | |1;audio-beat-detection;146;118;115;152;118;119;121;119;150;121 07:56:35 INFO - PROCESS | 5899 | |2;audio-dft;162;161;162;161;159;160;162;161;160;160 07:56:35 INFO - PROCESS | 5899 | |3;audio-fft;75;76;76;118;76;76;74;75;96;75 07:56:35 INFO - PROCESS | 5899 | |4;audio-oscillator;81;82;129;82;82;83;104;81;81;112 07:56:35 INFO - PROCESS | 5899 | |5;imaging-gaussian-blur;116;116;117;117;117;117;117;116;117;117 07:56:35 INFO - PROCESS | 5899 | |6;imaging-darkroom;146;145;145;146;146;146;145;146;147;146 07:56:35 INFO - PROCESS | 5899 | |7;imaging-desaturate;95;95;95;95;95;95;94;96;96;96 07:56:35 INFO - PROCESS | 5899 | |8;json-parse-financial;50;50;49;50;50;49;49;49;49;60 07:56:35 INFO - PROCESS | 5899 | |9;json-stringify-tinderbox;59;58;61;59;59;59;58;59;59;59 07:56:35 INFO - PROCESS | 5899 | |10;stanford-crypto-aes;74;76;72;74;73;73;73;74;81;72 07:56:35 INFO - PROCESS | 5899 | |11;stanford-crypto-ccm;123;137;126;129;125;116;117;121;155;125 07:56:35 INFO - PROCESS | 5899 | |12;stanford-crypto-pbkdf2;153;151;155;154;161;150;150;152;149;150 07:56:35 INFO - PROCESS | 5899 | |13;stanford-crypto-sha256-iterative;62;63;65;62;64;61;62;61;60;59 07:56:35 INFO - PROCESS | 5899 | __end_tp_report 07:56:35 INFO - PROCESS | 5899 | __start_cc_report 07:56:35 INFO - PROCESS | 5899 | _x_x_mozilla_cycle_collect,1579 07:56:35 INFO - PROCESS | 5899 | __end_cc_report 07:56:35 INFO - PROCESS | 5899 | __startTimestamp1475247395838__endTimestamp 07:56:35 INFO - PROCESS | 5899 | 07:56:35 INFO - PROCESS | 5899 | ------- Summary: start ------- 07:56:35 INFO - PROCESS | 5899 | Number of tests: 14 07:56:35 INFO - PROCESS | 5899 | 07:56:35 INFO - PROCESS | 5899 | [#0] ai-astar Cycles:10 Average:109.80 Median:110.00 stddev:1.48 (1.3%) stddev-sans-first:1.17 07:56:35 INFO - PROCESS | 5899 | Values: 107.0 110.0 112.0 110.0 111.0 110.0 111.0 110.0 108.0 109.0 07:56:35 INFO - PROCESS | 5899 | 07:56:35 INFO - PROCESS | 5899 | [#1] audio-beat-detection Cycles:10 Average:127.90 Median:121.00 stddev:14.96 (12.4%) stddev-sans-first:14.36 07:56:35 INFO - PROCESS | 5899 | Values: 146.0 118.0 115.0 152.0 118.0 119.0 121.0 119.0 150.0 121.0 07:56:35 INFO - PROCESS | 5899 | 07:56:35 INFO - PROCESS | 5899 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:1.03 (0.6%) stddev-sans-first:1.00 07:56:35 INFO - PROCESS | 5899 | Values: 162.0 161.0 162.0 161.0 159.0 160.0 162.0 161.0 160.0 160.0 07:56:35 INFO - PROCESS | 5899 | 07:56:35 INFO - PROCESS | 5899 | [#3] audio-fft Cycles:10 Average:81.70 Median:76.00 stddev:14.32 (18.8%) stddev-sans-first:14.98 07:56:35 INFO - PROCESS | 5899 | Values: 75.0 76.0 76.0 118.0 76.0 76.0 74.0 75.0 96.0 75.0 07:56:35 INFO - PROCESS | 5899 | 07:56:35 INFO - PROCESS | 5899 | [#4] audio-oscillator Cycles:10 Average:91.70 Median:81.50 stddev:17.18 (21.1%) stddev-sans-first:17.78 07:56:35 INFO - PROCESS | 5899 | Values: 81.0 82.0 129.0 82.0 82.0 83.0 104.0 81.0 81.0 112.0 07:56:35 INFO - PROCESS | 5899 | 07:56:35 INFO - PROCESS | 5899 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 07:56:35 INFO - PROCESS | 5899 | Values: 116.0 116.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 07:56:35 INFO - PROCESS | 5899 | 07:56:35 INFO - PROCESS | 5899 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 07:56:35 INFO - PROCESS | 5899 | Values: 146.0 145.0 145.0 146.0 146.0 146.0 145.0 146.0 147.0 146.0 07:56:35 INFO - PROCESS | 5899 | 07:56:35 INFO - PROCESS | 5899 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 07:56:35 INFO - PROCESS | 5899 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 94.0 96.0 96.0 96.0 07:56:35 INFO - PROCESS | 5899 | 07:56:35 INFO - PROCESS | 5899 | [#8] json-parse-financial Cycles:10 Average:50.50 Median:50.00 stddev:3.37 (6.7%) stddev-sans-first:3.57 07:56:35 INFO - PROCESS | 5899 | Values: 50.0 50.0 49.0 50.0 50.0 49.0 49.0 49.0 49.0 60.0 07:56:35 INFO - PROCESS | 5899 | 07:56:35 INFO - PROCESS | 5899 | [#9] json-stringify-tinderbox Cycles:10 Average:59.00 Median:59.00 stddev:0.82 (1.4%) stddev-sans-first:0.87 07:56:35 INFO - PROCESS | 5899 | Values: 59.0 58.0 61.0 59.0 59.0 59.0 58.0 59.0 59.0 59.0 07:56:35 INFO - PROCESS | 5899 | 07:56:35 INFO - PROCESS | 5899 | [#10] stanford-crypto-aes Cycles:10 Average:74.20 Median:74.00 stddev:2.66 (3.6%) stddev-sans-first:2.82 07:56:35 INFO - PROCESS | 5899 | Values: 74.0 76.0 72.0 74.0 73.0 73.0 73.0 74.0 81.0 72.0 07:56:35 INFO - PROCESS | 5899 | 07:56:35 INFO - PROCESS | 5899 | [#11] stanford-crypto-ccm Cycles:10 Average:127.40 Median:125.50 stddev:11.39 (9.1%) stddev-sans-first:11.97 07:56:35 INFO - PROCESS | 5899 | Values: 123.0 137.0 126.0 129.0 125.0 116.0 117.0 121.0 155.0 125.0 07:56:35 INFO - PROCESS | 5899 | 07:56:35 INFO - PROCESS | 5899 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.50 Median:152.50 stddev:3.57 (2.3%) stddev-sans-first:3.78 07:56:35 INFO - PROCESS | 5899 | Values: 153.0 151.0 155.0 154.0 161.0 150.0 150.0 152.0 149.0 150.0 07:56:35 INFO - PROCESS | 5899 | 07:56:35 INFO - PROCESS | 5899 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.90 Median:62.00 stddev:1.79 (2.9%) stddev-sans-first:1.90 07:56:35 INFO - PROCESS | 5899 | Values: 62.0 63.0 65.0 62.0 64.0 61.0 62.0 61.0 60.0 59.0 07:56:35 INFO - PROCESS | 5899 | -------- Summary: end -------- 07:56:35 INFO - PROCESS | 5899 | 07:56:36 INFO - TEST-INFO | 5899: exit 0 07:56:37 INFO - TEST-OK | kraken | took 59867ms 07:56:37 INFO - SUITE-END | took 1173s 07:56:37 INFO - Completed test suite (00:19:33) 07:56:37 INFO - javascript benchmark 07:56:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3093.938121237575, 3132.8746850125995, 3146.496560550311, 3172.7144556989865, 3181.3160170563324, 5559.416261292562, 5691.146328050784, 5691.8577035574135, 5700.68646224458, 5708.200851880735, 5486.643368181066, 5583.972080139593, 5601.243832082668, 5606.915896261549, 5638.605297629168, 5019.297298378221, 5022.799088036474, 5051.343325367716, 5108.0, 5118.027574760802, 4928.236123400879, 5068.290439338495, 5119.334486516766, 5128.615353848458, 5134.7432628368615, 4914.582260507861, 5031.144705400091, 5059.3169922060415, 5097.9490205097845, 5121.28302037715, 5086.109930762121, 5117.104506711311, 5119.616028797836, 5173.144735332695, 5186.299849520342, 5010.5240002199735, 5073.543381095719, 5126.333576635014, 5143.074246635604, 5165.948340516585, 5400.91898621521, 5401.243825864382, 5469.890602187974, 5493.368262649767, 5518.530924871391, 5822.214001109838, 5830.854228644275, 5841.532677385799, 5903.586748927577, 5908.084246941731, 5945.227120474311, 5948.048312270059, 5951.9107213391835, 5968.492678122365, 5971.074483455028, 5285.524302812765, 5318.069337865897, 5489.64317319373, 5528.86177845549, 5567.415421380778, 6091.878162436727, 6113.786017489456, 6116.26604807426, 6119.051547010221, 6121.204243448412, 5840.474357307863, 5841.970790146107, 5847.970760146172, 5849.064149736022, 5855.414458554111, 5713.914291285634, 5777.740001699934, 5847.386024467455, 5906.911396329058, 5915.881682366328, 5946.0, 5965.0, 5966.940330596725, 5970.044792833126, 5986.910196347058, 5923.703814809242, 5971.790987315423, 5972.283326000907, 5989.401059893976, 5994.250718660168, 4449.0, 4521.163584736769, 4550.271956486946, 4609.262517997105, 4622.2835460503675, 4089.345704687236, 4104.220198162399, 4133.565975572582, 4134.979325103355, 4155.833766649361, 5706.885862282731, 5709.543236541067, 5717.371089180187, 5717.628354156966, 5718.228039214736, 5859.53123750099, 5904.114382842608, 5948.553858460633, 6053.092036194606, 6053.636781793019, 5597.216389705444, 5670.886582268414, 5676.290463692038, 5705.657660540381, 5718.285214348207, 5195.922061169085, 5200.141976573823, 5200.7919683213, 5202.167653175474, 5202.4277329493725, 5754.510866576305, 5858.8242352729485, 6203.4416902479, 6211.0, 6211.440970312603, 5776.451237132465, 5818.94181058184, 5843.94156058434, 5929.851753706192, 5974.940250597612, 6131.448169664752, 6135.662538560422, 6137.815865524041, 6138.785142519991, 6143.262808462834, 5606.355269143937, 5841.970790146022, 5843.474087332161, 5843.853903652443, 5849.883002339844, 5692.203091567101, 5804.303483581997, 5813.680247586423, 5826.359100498857, 5923.940760592339, 5709.3719690834805, 5754.482096611325, 5754.9424505754405, 5758.193852860519, 5765.250517432707, 5728.2266893970445, 5740.0, 5741.827745167651, 5743.913841292468, 5744.281964754406, 5517.641353312182, 5529.889402212014, 5783.537317014713, 5798.478136967693, 5843.29880414353, 5930.28836539618, 5943.970280148572, 5950.256217972754, 5959.986802243716, 5972.641641501524, 5432.619716619799, 5826.73779679907, 5830.387809279964, 5831.387704291158, 5836.182934389104], "value": 5474.511523996815, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [584.6755050946723, 615.3661728419728, 620.5904103291829, 632.6235889645662, 638.1608185236414, 33852.32295354091, 36982.630173698286, 46477.999999999956, 46946.06107877849, 47074.29388559166, 49301.50698493023, 49633.50366496334, 49646.50353496464, 49801.0, 49938.0, 9326.253899688043, 9488.146066853982, 9491.95254023729, 9493.240540756757, 9498.240140788721, 8598.753180788783, 8799.0, 11074.667759967213, 11317.264377815416, 11332.150088743378, 11476.0, 11507.769844603103, 11537.826932596017, 11545.769084618345, 11575.710607234802, 11286.153538484605, 11344.886551134465, 11358.71603209918, 11367.772644547103, 11378.431078446045, 10997.780044399067, 11196.552137914474, 11199.104071674328, 11201.271917325348, 11210.0, 45556.54443455556, 45798.31302530465, 45998.08003839904, 46017.0, 46040.76979615114, 3865.4588357629673, 3976.602339766029, 4044.1305119399176, 4070.267351876661, 4208.74747515147, 34757.0, 34895.65104348975, 35074.64925350739, 35228.119297017525, 35425.64574354249, 4351.282038463682, 4373.0, 4393.143337049277, 4489.506154323022, 4494.1910456117885, 321400.0, 323470.0, 324749.0, 325010.0, 325479.0, 63792.4914129718, 64208.67895660492, 64277.357226428074, 64329.99999999906, 64438.0, 40638.0, 41283.174336513104, 41400.58599413967, 44117.33823992643, 44117.77941110274, 18702.625947481247, 18857.90571047136, 18882.3391181308, 18887.0, 18940.43178704641, 13707.177569345871, 15443.92278038625, 15623.3750649975, 15638.2180890955, 15646.843531564766, 18646.53383665392, 20475.488112797, 21101.894490527757, 21163.576728465345, 21918.342449726533, 7307.451941104438, 7392.778216653509, 7522.172561018284, 7524.510906791151, 7616.428767842434, 974.7563109222694, 1106.5020740666732, 1120.8935151160626, 1133.0935251798528, 1219.1283232488813, 33934.49098263528, 34296.48555271673, 34584.308313833586, 34736.65263347334, 34870.476942845875, 1120.2214460949724, 1156.3928937307812, 1173.988260117405, 1183.8461000069935, 1236.1841184817977, 18080.095995200187, 18341.266349345875, 18488.35290764817, 18568.44294671162, 18579.72130418045, 517464.0, 519610.0, 524046.0, 525719.0, 527084.0, 62813.37186628167, 63338.0, 63400.682996585645, 63608.68195658992, 63687.6815615919, 1301.271288078679, 1361.3805718398105, 1396.2599822094305, 1433.7132573485344, 1447.5874375803057, 31363.215919602193, 36215.45676814902, 36408.635913641585, 36622.633773661924, 36633.26733465369, 53695.19457208067, 54279.45720542902, 54351.45648543464, 54359.184612230056, 54381.72809135929, 413923.0, 416165.0, 416326.0, 417134.0, 417298.0, 31368.372632546765, 31554.842225788725, 31775.68224317727, 32167.195820104684, 33306.83346583252, 52171.73914130405, 52623.0, 52759.20861187162, 52878.73560632172, 53848.0, 44768.55231447644, 46107.0, 46453.0, 46816.53183468122, 47807.760961194974, 45971.3104303429, 46778.0, 46925.296120557534, 47100.29349559691, 47789.0, 44093.559064408946, 44988.0, 45414.77292613648, 46032.30951535796, 47794.76102619465, 37071.62928370682, 37961.62038379581, 39801.22270916178, 40650.79674601608, 41847.58152418437, 29225.7077429223, 30002.84998574993, 30453.695463045973, 30686.539701904934, 30822.0], "value": 21682.526451967864, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.96710779583736, 407.5842640506683, 419.55317586770093, 422.2462903716866, 436.6943139802139, 14926.77609835852, 16665.25006374713, 20662.896685516553, 21253.36239912805, 21342.253021144264, 20160.798392016077, 20450.386488405373, 20640.174393024263, 21300.0, 21452.678209826867, 5742.39704830993, 5814.1278808178795, 5836.708164591774, 5869.706514674271, 5875.265591801025, 4267.231898258327, 5871.530277577805, 5948.7620495180145, 5954.047352423613, 5954.761809527614, 4448.243798554237, 4476.910461790762, 6369.076483909854, 6625.337466253384, 6680.0, 6371.52213583983, 6563.638999855006, 6652.06871038055, 6656.434203092719, 6671.6997735101795, 5883.294004719461, 5884.970575147139, 5886.234789477383, 5892.998190307658, 5894.027485464893, 24823.751762482505, 25090.372740681447, 25125.49749005028, 25188.496230075296, 25347.87326063376, 2579.071534247669, 2669.746374094458, 2851.443968426158, 3235.822029788361, 3330.3672302262494, 13663.453461861514, 15161.545153645407, 15217.923910380488, 15494.690106197926, 16537.503874883652, 1950.3466338776527, 2025.4936265933516, 2025.6151331247015, 2028.3103744726716, 2088.043688910308, 58432.70783646055, 74782.25217747808, 78384.21615783827, 84768.15231847664, 86346.13653863444, 11056.778864422828, 11210.271332363533, 11335.546578136782, 11390.772184556263, 11672.066234701204, 16487.752683709754, 16608.501744947673, 16609.584760381087, 16623.251953662115, 16648.001119932844, 8460.957695211608, 8700.434471759441, 8773.605187766438, 8856.3800533962, 8860.069692682175, 8466.872996905051, 8580.742577722553, 8599.14008599135, 8726.607302671267, 8788.736337909744, 6965.955106734031, 7114.822129446702, 7118.537295075907, 7229.349358557755, 7257.201707812136, 1768.3644309967865, 1949.991508745994, 1977.940661780149, 2000.3398878370103, 2013.3154727392757, 914.3371055984358, 958.5351104714208, 1044.738815296176, 1076.278893141597, 1084.3656460970421, 19836.702449463268, 19873.608847380543, 20623.896880515502, 21265.468363291042, 21465.67801482979, 1020.2297265564599, 1037.2998226197437, 1062.150279776176, 1068.5672302717412, 1069.9037086662238, 11459.541618335339, 12301.876981230253, 12374.0, 12443.808643907045, 12489.375531223403, 500079.0, 500861.0, 504879.0, 508573.0, 512942.0, 82173.58913205395, 83213.58393208116, 83976.58011709902, 84504.0, 90721.0, 1021.7803172317987, 1046.8115739166872, 1084.7776205877776, 1088.455772113943, 1093.2347356850237, 18553.628927421647, 18612.16245268939, 19004.809951900304, 19049.333273335367, 19479.8052019478, 40408.59591404048, 41823.37264940968, 43992.0, 45982.54017459916, 46732.29901551548, 54957.72521137369, 55554.44445555493, 55679.44320556905, 62761.0, 63067.369326307984, 7873.212678732081, 8484.024337201301, 8517.95741021291, 8529.658813647384, 8637.524936128573, 10524.210684198562, 10537.473126343499, 13187.274699891597, 14089.365978530786, 14136.363863625957, 12179.634610961686, 12360.0, 12412.56556020535, 12460.314682692539, 12646.367681616066, 11944.522219111139, 12561.120721549769, 12573.811392828931, 12611.558595449114, 12913.160644558073, 12874.16317939331, 12929.0, 12969.935150324189, 12970.416331265304, 13037.608871733863, 8258.628361723617, 8299.087100418828, 8373.790655233424, 8486.490810551386, 9001.504917229353, 10482.947585262025, 11636.592719254786, 11983.940080299542, 12421.503139874303, 13096.51166278404], "value": 9819.043395408069, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1464.4288727396313, 1502.4591147187011, 1617.9271932763024, 1741.8954862708247, 1772.3796671165094, 7954.64204110815, 8488.363372747037, 8502.702405415812, 8506.532140732257, 8512.106228845967, 7396.926030739691, 7646.96176519118, 7990.560519171458, 8247.91752082479, 8334.624941877615, 2339.6139636959883, 2363.598188307983, 2366.1954935321987, 2369.7156341239074, 2373.2405630198346, 2320.8027317678016, 2340.0756701103055, 2364.160722943356, 2367.715874095111, 2371.3597328721235, 1999.800019998003, 2061.7525896892394, 2275.16956310947, 2347.190219374313, 2350.565145448089, 2380.6310021946624, 2383.5947888858936, 2419.2621250518673, 2419.9516009679883, 2420.673209116782, 2336.497653004612, 2338.5556744218716, 2340.485093279493, 2342.437814924423, 2344.367020904346, 8208.507489550646, 8297.25324720772, 8307.418480706354, 8317.83364332716, 8435.873461898076, 4923.556879880792, 5046.571041461443, 5050.090983622947, 5051.797928082872, 5057.721825299607, 3203.871845126192, 3214.694604012615, 3372.814495202763, 3394.439917413624, 3398.3373242217776, 1063.8138325792954, 1066.7493139112303, 1074.3446497636435, 1080.1304949515636, 1085.4572713643179, 10704.892951070546, 10791.298565593212, 10868.184886133493, 10874.565017399294, 10875.129989600813, 4300.225959327288, 4362.8691139265875, 4451.399061126771, 4482.529334419904, 4498.617617502484, 9404.106609872051, 9410.482423466776, 9410.576524056434, 9413.388129771543, 9417.246620270362, 4981.701097934136, 4984.22744474607, 5000.0, 5003.499650034967, 5004.974975125101, 4544.795484203219, 4578.542145785462, 4579.793909274091, 4592.770361481913, 4672.719636821802, 3925.293447179478, 3945.822437990297, 3984.820683069269, 3993.7004724645767, 4020.115574573589, 1112.1603189591806, 1220.371508673034, 1223.1988047828686, 1233.5929143382841, 1256.9811452828214, 871.877937088808, 997.3467378866853, 1002.2883752535636, 1008.5310330696261, 1014.6702321745462, 5780.219670344533, 5828.6502809831545, 5859.912101318484, 6005.309389420273, 6033.396660333931, 988.7626969527405, 993.7167907146429, 1001.1840350114634, 1001.354126588361, 1017.9643712470177, 3981.761094334291, 4116.361963895601, 4369.300911854088, 4518.118966801508, 4633.745144017112, 10081.798364032678, 10235.078842904175, 10257.17942564593, 10271.435071071162, 10280.691579252634, 10269.691909242585, 10277.383356998604, 10280.07479326864, 10287.74280642975, 10288.12550933179, 7313.926860731431, 7606.23937606246, 7612.695492180251, 7674.501157424861, 7729.4202934779, 5997.610155339889, 6131.724072416841, 6162.322144564005, 6243.750249990131, 6245.0632405139595, 9449.102335278138, 9583.90416095858, 9637.855432168662, 9670.903290967, 9678.903210968081, 9517.191038761783, 9638.710838674851, 9699.563519641773, 9712.562934667816, 9782.657606983721, 7591.089069311497, 7726.0, 7733.381329493514, 7737.5744334062165, 7747.380209583107, 8172.38707096982, 8180.918190818254, 8205.261526462647, 8272.0, 8285.295749861089, 6336.873262534816, 7364.0, 7458.47790654649, 7490.812729681802, 7509.136449308399, 7352.0442342495135, 7406.814829629302, 7411.777646670608, 7428.73999410018, 7446.962765186139, 7021.368076873106, 7032.8593428130125, 7049.7885063448175, 7104.502684812013, 7116.857662846817, 5264.368275806928, 5374.7581358839725, 5398.271233383443, 5445.972770136124, 5480.753366098615, 5378.946210538001, 5433.7554810034435, 5483.0, 5486.723910825925, 5630.831075067755], "value": 4538.489109632412, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.78026551812013, 334.13459140825256, 336.9612494563123, 337.4853348643317, 338.40778637384585, 5582.832515024551, 5668.461496157864, 5675.51758100562, 5680.119581464869, 5703.3441154267275, 5541.501264886159, 5550.806576586033, 5613.354464236614, 5640.294963129609, 5653.717314134287, 4016.915432833148, 4090.141070375217, 4177.749335039892, 4202.348635961415, 4346.695731298811, 4340.240457919867, 4352.499462561815, 4373.1909596724545, 4375.584319489643, 4381.518033016366, 4321.092570560186, 4322.891927701817, 4327.588879056485, 4336.262835318003, 4352.97823510882, 4226.640735537468, 4310.482742070956, 4321.978390108061, 4371.606555410029, 4378.5183629800695, 3842.884713458601, 3849.0, 3851.1912498375373, 3856.132370216707, 3865.4588357629955, 5406.8918621627345, 5448.45515448452, 5459.754311056012, 5474.37044739856, 5480.232767412565, 4842.66101372904, 4984.725840078794, 5008.323876276729, 5013.699178049329, 5019.511439549515, 5781.739821708034, 5791.623544469596, 5791.681457519835, 5802.12968054791, 5802.912956305659, 4234.3860140279485, 4243.787810609457, 4250.256205164084, 4256.787160641956, 4272.572742725733, 5736.0, 6034.668093254869, 6039.0, 6058.182944930629, 6068.605540639888, 4228.9577104228565, 4235.555266697014, 4248.617624413818, 4249.68127390447, 4252.170826688766, 4299.914001719948, 4340.674449416307, 4341.630961368256, 4350.369196466495, 4353.564643535685, 4806.399200099988, 4880.243562247857, 4902.068606964665, 4937.950620493749, 5003.599712023102, 5774.0, 5806.680632565165, 5825.563082768809, 5832.562557808096, 5848.736806843703, 1471.683588028579, 1473.850396690583, 1478.7633978563379, 1482.495951376537, 1489.4935721854622, 947.1901524196712, 950.1923365139714, 955.2310390135868, 962.6775030364906, 982.3271059324386, 5507.972460137674, 5673.971630141823, 5682.687452190086, 5694.772209111589, 5695.943040569624, 6014.338422773491, 6020.849478763066, 6027.758889644453, 6047.15339852421, 6080.513558915276, 5516.503514683617, 5659.603827732101, 5686.800961966312, 5707.6290041147195, 5724.627899186539, 3180.7773455858332, 3206.701285979182, 3211.518272259133, 3217.069758145094, 3223.6453990061545, 6038.852618002566, 6205.486777854976, 6240.625562466266, 6287.245530536365, 6291.1192433058495, 6154.692265386713, 6158.753649854045, 6159.876802463926, 6193.7922105189955, 6201.875962480815, 6235.937640623536, 6240.906386404207, 6243.96878015607, 6245.625962288289, 6287.371262873676, 5713.114467257582, 5837.912431313449, 5938.8218353449465, 5957.76168953237, 5971.552133589911, 5837.591368604157, 5893.263342082239, 5894.793682221273, 5896.616719913192, 5897.405141737621, 5910.822675319747, 6109.450149486567, 6120.8064427438, 6163.476104531165, 6217.0, 4119.608637179464, 4134.607212314825, 4144.544100148921, 4149.336106223111, 4185.686073544557, 6019.458248757633, 6065.72704228302, 6067.120267561268, 6082.239720034996, 6086.756529738761, 5658.377578466283, 5925.881482370414, 5929.733162007634, 5938.465538101592, 5951.791687290924, 5845.970770146122, 5892.263467066617, 5894.322152952465, 5900.970495147497, 5902.1736956826935, 5628.662280263197, 5790.420957904176, 5863.706814659336, 5864.44287792659, 5895.793647222326, 5893.145493903446, 5907.822765317047, 5907.9704601476715, 5912.881742365214, 5914.408559144051, 5756.532084318639, 5757.193992841089, 5778.479936805707, 5789.392113828156, 5794.82615521535], "value": 4453.074605855425, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [824.9628766705498, 837.1377481194372, 878.9340799440041, 888.8000199955011, 900.567727490804, 16585.41951031714, 17766.11169441526, 17767.64464710705, 17813.198406071726, 17832.375866844664, 16265.0, 16429.507114786546, 16445.91777041116, 16470.34118635256, 16471.17644117795, 701.3302296307028, 729.0849983271014, 731.7073170731724, 732.9486935914488, 852.7612268564811, 875.7854325690183, 883.9071897450772, 884.0805562215288, 884.2793123604274, 890.4523717913502, 921.4379228670506, 928.0023974227698, 931.7018554062703, 933.7385532051003, 934.1219253901311, 922.4511415707677, 922.575615217001, 923.3628796130682, 923.7321176858703, 923.8891333040011, 543.7933585237624, 562.5499600319728, 564.6668465605312, 687.3195536418959, 688.3976520544524, 12888.420021099073, 15547.0, 16051.277692503865, 16053.518394448069, 16773.412930547373, 16506.42227522043, 17144.399946001955, 17201.0, 17252.74120888188, 17612.647747044986, 13476.595702128952, 13591.796123058162, 13739.175649461064, 13768.724625507433, 13834.446622135103, 4268.317069268902, 4324.805383757738, 4351.782410879443, 4354.847580334705, 4454.821807127711, 91263.54368228116, 91773.0, 97869.0, 97980.0, 98119.50940245324, 12441.129120961446, 12459.626211213677, 12465.875341246652, 12598.118131730875, 12706.745865082647, 25774.0, 25982.0, 26012.479750405266, 26053.73946260551, 26055.869720651277, 9071.138241867144, 9079.319051071196, 9500.572474238676, 9565.521723913776, 9990.600375984879, 4695.5539223773685, 4940.547975242434, 4976.626752993468, 5134.486551344836, 5198.142306519458, 3632.182758879231, 3744.251149770057, 3751.7561358512157, 3770.736048476635, 3787.6401741834484, 606.9666168360783, 608.9360617135137, 612.788587937161, 618.6535540097469, 619.6715740657459, 710.2897102897103, 827.5034979012544, 841.3984001439001, 843.582426698788, 855.2644725536034, 10633.73415664599, 11056.834147487794, 11255.324680519032, 11277.276288279125, 11563.132765042656, 612.4518555892452, 630.2688880898136, 633.6894921488438, 758.4766511107318, 776.7786180938406, 5006.3992320921, 5219.525037129703, 5534.944650553523, 5551.250581171571, 5555.111182210827, 130145.34927325303, 134377.32811336077, 138130.3093484526, 138435.0, 141793.0, 104253.47873260586, 117205.41397293129, 120541.0, 121272.39363803125, 124496.0, 104456.0, 105326.0, 105395.47302263592, 105589.0, 107993.46003270091, 9903.306768526421, 9993.10062094415, 10052.346597471142, 10260.846087308846, 10298.794024119625, 50208.0, 59731.402685974324, 60697.39302606917, 61565.690401671454, 62734.68632656807, 50841.745791270805, 51127.48872511227, 74178.62910685412, 76172.0, 79115.0, 13858.86141138616, 14237.145771253758, 14268.714625707635, 14748.483803066842, 14790.0, 15766.0, 16162.272697728866, 16190.190490475665, 16663.50009499717, 16671.0, 15004.849951500346, 15579.221038948233, 16081.678366432372, 16143.354265829235, 17046.48860534186, 14787.0, 15220.856841589697, 15458.5362439127, 15790.526284211492, 16376.181190940644, 15120.470783522524, 15741.685166296838, 15991.600209994844, 16710.498685039467, 16960.830391695927, 12036.879631203927, 12486.750264994831, 12885.260300223781, 12924.741505169655, 13656.180629162282, 12273.263604183778, 12732.363381830686, 13074.477020919438, 13259.933700331436, 13264.403101860247], "value": 8362.449151159415, "unit": "score"}], "name": "dromaeo_css", "value": 7628.035764124769, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 110.0, 112.0, 110.0, 111.0, 110.0, 111.0, 110.0, 108.0, 109.0], "value": 109.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 118.0, 115.0, 152.0, 118.0, 119.0, 121.0, 119.0, 150.0, 121.0], "value": 127.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 162.0, 161.0, 159.0, 160.0, 162.0, 161.0, 160.0, 160.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 118.0, 76.0, 76.0, 74.0, 75.0, 96.0, 75.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 129.0, 82.0, 82.0, 83.0, 104.0, 81.0, 81.0, 112.0], "value": 91.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 145.0, 146.0, 146.0, 146.0, 145.0, 146.0, 147.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 96.0, 96.0, 96.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 49.0, 50.0, 50.0, 49.0, 49.0, 49.0, 49.0, 60.0], "value": 50.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 61.0, 59.0, 59.0, 59.0, 58.0, 59.0, 59.0, 59.0], "value": 59.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 72.0, 74.0, 73.0, 73.0, 73.0, 74.0, 81.0, 72.0], "value": 74.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 137.0, 126.0, 129.0, 125.0, 116.0, 117.0, 121.0, 155.0, 125.0], "value": 127.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 151.0, 155.0, 154.0, 161.0, 150.0, 150.0, 152.0, 149.0, 150.0], "value": 152.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 65.0, 62.0, 64.0, 61.0, 62.0, 61.0, 60.0, 59.0], "value": 61.9, "unit": "score"}], "name": "kraken", "value": 1455.1000000000004, "alertThreshold": 2.0}]} 07:56:37 INFO - Return code: 0 07:56:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:56:37 INFO - # TBPL SUCCESS # 07:56:37 INFO - Running post-action listener: _resource_record_post_action 07:56:37 INFO - [mozharness: 2016-09-30 14:56:37.657959Z] Finished run-tests step (success) 07:56:37 INFO - Running post-run listener: _resource_record_post_run 07:56:37 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 0; Write bytes: 358019072; Read time: 0; Write time: 244952 07:56:37 INFO - TinderboxPrint: CPU usage
12.9% 07:56:37 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 07:56:37 INFO - TinderboxPrint: I/O write bytes / time
358,019,072 / 244,952 07:56:37 INFO - TinderboxPrint: CPU idle
8,270.5 (87.1%) 07:56:37 INFO - TinderboxPrint: CPU user
1,184.6 (12.5%) 07:56:37 INFO - TinderboxPrint: Swap in / out
0 / 0 07:56:37 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 107958272; Read time: 0; Write time: 99304 07:56:37 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 249266176; Read time: 0; Write time: 145640 07:56:38 INFO - Running post-run listener: _upload_blobber_files 07:56:38 INFO - Blob upload gear active. 07:56:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:56:38 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:56:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:56:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:56:38 INFO - (blobuploader) - INFO - Open directory for files ... 07:56:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:56:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:56:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:56:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:56:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:56:39 INFO - (blobuploader) - INFO - Done attempting. 07:56:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:56:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:56:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:56:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:56:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:56:40 INFO - (blobuploader) - INFO - Done attempting. 07:56:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:56:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:56:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:56:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:56:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:56:45 INFO - (blobuploader) - INFO - Done attempting. 07:56:45 INFO - (blobuploader) - INFO - Iteration through files over. 07:56:45 INFO - Return code: 0 07:56:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:56:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:56:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/32a11e6e7cc79cb7c27fce2597bcb0b77ed6ff9742aeb41aa1b83b3d9edd90f8cea3220823a56e88974d7830b81935d0674f480446d74a81a3e98c73d3fdcd79", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0cb6e8e926aeabb0450954d0550bdce0b12b9df6121ba058742b5f54133b420b6e73d77258bcff22fae1aa3995275c886f2af680376e9a776ea950c14f749007", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9e33647658861bd68fb4c13fb471de291e816839decadda611fdb8ab9fcb03420ae7f2e45484f446d1a95364a11a8576ff2b23c2d0035deb8ebcd70735605cb6"} 07:56:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:56:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:56:45 INFO - Contents: 07:56:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/32a11e6e7cc79cb7c27fce2597bcb0b77ed6ff9742aeb41aa1b83b3d9edd90f8cea3220823a56e88974d7830b81935d0674f480446d74a81a3e98c73d3fdcd79", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0cb6e8e926aeabb0450954d0550bdce0b12b9df6121ba058742b5f54133b420b6e73d77258bcff22fae1aa3995275c886f2af680376e9a776ea950c14f749007", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9e33647658861bd68fb4c13fb471de291e816839decadda611fdb8ab9fcb03420ae7f2e45484f446d1a95364a11a8576ff2b23c2d0035deb8ebcd70735605cb6"} 07:56:45 INFO - Running post-run listener: copy_logs_to_upload_dir 07:56:45 INFO - Copying logs to upload dir... 07:56:45 INFO - mkdir: /builds/slave/test/build/upload/logs 07:56:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.512711 ========= master_lag: 0.96 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-09-30 07:56:46.386926) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-30 07:56:46.393350) ========= 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=6d15e502d8e29d5d2676d664000001c3-1475243472.858512-887638643 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/32a11e6e7cc79cb7c27fce2597bcb0b77ed6ff9742aeb41aa1b83b3d9edd90f8cea3220823a56e88974d7830b81935d0674f480446d74a81a3e98c73d3fdcd79", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0cb6e8e926aeabb0450954d0550bdce0b12b9df6121ba058742b5f54133b420b6e73d77258bcff22fae1aa3995275c886f2af680376e9a776ea950c14f749007", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9e33647658861bd68fb4c13fb471de291e816839decadda611fdb8ab9fcb03420ae7f2e45484f446d1a95364a11a8576ff2b23c2d0035deb8ebcd70735605cb6"} 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.011248 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/32a11e6e7cc79cb7c27fce2597bcb0b77ed6ff9742aeb41aa1b83b3d9edd90f8cea3220823a56e88974d7830b81935d0674f480446d74a81a3e98c73d3fdcd79", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0cb6e8e926aeabb0450954d0550bdce0b12b9df6121ba058742b5f54133b420b6e73d77258bcff22fae1aa3995275c886f2af680376e9a776ea950c14f749007", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9e33647658861bd68fb4c13fb471de291e816839decadda611fdb8ab9fcb03420ae7f2e45484f446d1a95364a11a8576ff2b23c2d0035deb8ebcd70735605cb6"}' ========= master_lag: 0.08 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-30 07:56:46.488856) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 07:56:46.494990) ========= 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=6d15e502d8e29d5d2676d664000001c3-1475243472.858512-887638643 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004470 ========= master_lag: 0.38 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 07:56:46.884284) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-30 07:56:46.884591) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-30 07:56:46.894090) ========= ========= Total master_lag: 1.49 =========