builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-018 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.121866) ========= 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.122226) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-30 07:36:08.122499) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-30 07:36:08.219712) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 07:36:08.219958) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1475244157.745591-1472413597 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% 46.2M=0s 2016-09-30 07:36:08 (46.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.118248 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 07:36:08.353089) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 07:36:08.353357) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1475244157.745591-1472413597 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018868 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 07:36:08.405346) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-30 07:36:08.405640) ========= 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=62c568580acbfdaac3eec7be000001dc-1475244157.745591-1472413597 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-30 07:36:08,571 truncating revision to first 12 chars 2016-09-30 07:36:08,571 Setting DEBUG logging. 2016-09-30 07:36:08,571 attempt 1/10 2016-09-30 07:36:08,571 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:09,756 unpacking tar archive at: mozilla-aurora-805d5fc3d66c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.548227 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-30 07:36:09.967188) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-30 07:36:09.967490) ========= script_repo_revision: 805d5fc3d66c4fa585a3de407b1e0b055f78eb58 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-30 07:36:09.967906) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-30 07:36:09.968176) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-30 07:36:09.978306) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-09-30 07:36:09.978764) ========= /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=62c568580acbfdaac3eec7be000001dc-1475244157.745591-1472413597 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-018', 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.236033Z] 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.217601Z] Finished clobber step (success) 07:36:11 INFO - [mozharness: 2016-09-30 14:36:11.217701Z] 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": 546, 07:36:11 INFO - "slavename": "talos-linux64-ix-018", 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.220116Z] Finished read-buildbot-config step (success) 07:36:11 INFO - [mozharness: 2016-09-30 14:36:11.220203Z] 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:17 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:22 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:24 INFO - Downloaded 63921663 bytes. 07:36:24 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:24 INFO - mkdir: /builds/slave/test/properties 07:36:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:36:24 INFO - Writing to file /builds/slave/test/properties/build_url 07:36:24 INFO - Contents: 07:36:24 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.403295Z] Finished download-and-extract step (success) 07:36:25 INFO - [mozharness: 2016-09-30 14:36:25.403524Z] 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.422949Z] Finished populate-webroot step (success) 07:36:25 INFO - [mozharness: 2016-09-30 14:36:25.423037Z] 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:27 INFO - Installing pip.................done. 07:36:27 INFO - Return code: 0 07:36:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:36:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:27 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 0x7f27b5dfdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c81ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7ef60>, '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 0x2c7fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c801d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1475244157.745591-1472413597', '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:27 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:27 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:27 INFO - Using env: {'DISPLAY': ':0', 07:36:27 INFO - 'HOME': '/home/cltbld', 07:36:27 INFO - 'LANG': 'en_US.UTF-8', 07:36:27 INFO - 'LANGUAGE': 'en_US:en', 07:36:27 INFO - 'LOGNAME': 'cltbld', 07:36:27 INFO - 'MAIL': '/var/mail/cltbld', 07:36:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:27 INFO - 'MOZ_NO_REMOTE': '1', 07:36:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:36:27 INFO - 'NO_EM_RESTART': '1', 07:36:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:36:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:27 INFO - 'PWD': '/builds/slave/test', 07:36:27 INFO - 'SHELL': '/bin/bash', 07:36:27 INFO - 'SHLVL': '1', 07:36:27 INFO - 'TERM': 'linux', 07:36:27 INFO - 'TMOUT': '86400', 07:36:27 INFO - 'USER': 'cltbld', 07:36:27 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1475244157.745591-1472413597', 07:36:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:27 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 0x7f27b5dfdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c81ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7ef60>, '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 0x2c7fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c801d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1475244157.745591-1472413597', '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': '62c568580acbfdaac3eec7be000001dc-1475244157.745591-1472413597', 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: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_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 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 - Successfully installed psutil 07:36:30 INFO - Cleaning up... 07:36:30 INFO - Return code: 0 07:36:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:36:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:36:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:36:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:36:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:36:30 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 0x7f27b5dfdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c81ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7ef60>, '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 0x2c7fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c801d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1475244157.745591-1472413597', '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:30 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:30 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:30 INFO - Using env: {'DISPLAY': ':0', 07:36:30 INFO - 'HOME': '/home/cltbld', 07:36:30 INFO - 'LANG': 'en_US.UTF-8', 07:36:30 INFO - 'LANGUAGE': 'en_US:en', 07:36:30 INFO - 'LOGNAME': 'cltbld', 07:36:30 INFO - 'MAIL': '/var/mail/cltbld', 07:36:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:36:30 INFO - 'MOZ_NO_REMOTE': '1', 07:36:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:36:30 INFO - 'NO_EM_RESTART': '1', 07:36:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:36:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:36:30 INFO - 'PWD': '/builds/slave/test', 07:36:30 INFO - 'SHELL': '/bin/bash', 07:36:30 INFO - 'SHLVL': '1', 07:36:30 INFO - 'TERM': 'linux', 07:36:30 INFO - 'TMOUT': '86400', 07:36:30 INFO - 'USER': 'cltbld', 07:36:30 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1475244157.745591-1472413597', 07:36:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:36:30 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 0x7f27b5dfdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c81ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7ef60>, '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 0x2c7fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c801d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1475244157.745591-1472413597', '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': '62c568580acbfdaac3eec7be000001dc-1475244157.745591-1472413597', 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: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 - Successfully installed jsonschema functools32 07:36:35 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 0x7f27b5dfdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c81ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7ef60>, '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 0x2c7fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c801d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1475244157.745591-1472413597', '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': '62c568580acbfdaac3eec7be000001dc-1475244157.745591-1472413597', 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 0x7f27b5dfdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c81ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7ef60>, '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 0x2c7fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c801d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1475244157.745591-1472413597', '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': '62c568580acbfdaac3eec7be000001dc-1475244157.745591-1472413597', 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:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:36:39 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:39 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 - 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:39 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:39 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:39 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 0x7f27b5dfdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c81ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7ef60>, '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 0x2c7fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c801d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1475244157.745591-1472413597', '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': '62c568580acbfdaac3eec7be000001dc-1475244157.745591-1472413597', 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-27ETCQ-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-DpO1Pu-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-EcafGI-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-S0niGb-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-5HPegy-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-bc0eKA-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-ELrGs1-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-f8IiBE-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-QB38Xz-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-Ah2QC2-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-KkFHvQ-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-RAIXSM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:36:41 INFO - Running setup.py (path:/tmp/pip-ZknK9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:36:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:36:41 INFO - Running setup.py (path:/tmp/pip-fL2LY7-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-hpoyYg-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-Yr3Cws-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-ljLKjx-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:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:36:42 INFO - Running setup.py install for mozinfo 07:36:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:36:42 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:43 INFO - Running setup.py install for moztest 07:36:43 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 0x7f27b5dfdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c81ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7ef60>, '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 0x2c7fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c801d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1475244157.745591-1472413597', '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': '62c568580acbfdaac3eec7be000001dc-1475244157.745591-1472413597', 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-4HGNLO-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-vzbZVh-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-dWqwG1-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-H_wVlj-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-q6Lack-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-ci_zqm-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-w06bJk-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-8dtmM0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:36:44 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:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:36:44 INFO - Running setup.py (path:/tmp/pip-2_IQNW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:36:44 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:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:36:44 INFO - Running setup.py (path:/tmp/pip-zFi5u9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:36:44 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-QG57lg-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-uJBdsy-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-_O8kmw-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-BeA1lz-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-EZALIH-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-UnIB39-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-X4fYZ0-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:47 INFO - Return code: 0 07:36:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 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 - Current package versions: 07:36:47 INFO - argparse == 1.2.1 07:36:47 INFO - blessings == 1.6 07:36:47 INFO - blobuploader == 1.2.4 07:36:47 INFO - docopt == 0.6.1 07:36:47 INFO - functools32 == 3.2.3-2 07:36:47 INFO - jsonschema == 2.5.1 07:36:47 INFO - manifestparser == 1.1 07:36:47 INFO - mozInstall == 1.12 07:36:47 INFO - mozcrash == 0.17 07:36:47 INFO - mozdebug == 0.1 07:36:47 INFO - mozdevice == 0.48 07:36:47 INFO - mozfile == 1.2 07:36:47 INFO - mozhttpd == 0.7 07:36:47 INFO - mozinfo == 0.9 07:36:47 INFO - mozleak == 0.1 07:36:47 INFO - mozlog == 3.4 07:36:47 INFO - moznetwork == 0.27 07:36:47 INFO - mozprocess == 0.23 07:36:47 INFO - mozprofile == 0.28 07:36:47 INFO - mozrunner == 6.12 07:36:47 INFO - mozscreenshot == 0.1 07:36:47 INFO - mozsystemmonitor == 0.3 07:36:47 INFO - moztest == 0.7 07:36:47 INFO - mozversion == 1.4 07:36:47 INFO - psutil == 3.1.1 07:36:47 INFO - requests == 1.2.3 07:36:47 INFO - wsgiref == 0.1.2 07:36:47 INFO - Installing None 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', '-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 0x7f27b5dfdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c81ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7ef60>, '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 0x2c7fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c801d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1475244157.745591-1472413597', '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', '-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:47 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: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': '62c568580acbfdaac3eec7be000001dc-1475244157.745591-1472413597', 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): 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 0x7f27b5dfdf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bb48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c81ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7ef60>, '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 0x2c7fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c801d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1475244157.745591-1472413597', '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': '62c568580acbfdaac3eec7be000001dc-1475244157.745591-1472413597', 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.692500Z] Finished create-virtualenv step (success) 07:36:47 INFO - [mozharness: 2016-09-30 14:36:47.692953Z] 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:04 INFO - Reading from file tmpfile_stdout 07:37:04 INFO - Output received: 07:37:04 INFO - /builds/slave/test/build/application/firefox/firefox 07:37:04 INFO - Running post-action listener: _resource_record_post_action 07:37:04 INFO - [mozharness: 2016-09-30 14:37:04.666897Z] Finished install step (success) 07:37:04 INFO - [mozharness: 2016-09-30 14:37:04.667143Z] Running run-tests step. 07:37:04 INFO - Running pre-action listener: _resource_record_pre_action 07:37:04 INFO - Running main action method: run_tests 07:37:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:37:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:37:04 INFO - Python 2.7.3 07:37:04 INFO - Return code: 0 07:37:04 INFO - grabbing minidump binary from tooltool 07:37:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:37:04 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 0x2c7ef60>, '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 0x2c7fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c801d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:37:04 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:04 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:04 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:04 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:37:04 INFO - Return code: 0 07:37:04 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:37:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:37:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:37:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:37:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:37:04 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-018', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:37:04 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-018 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:37:04 INFO - Using env: {'DISPLAY': ':0', 07:37:04 INFO - 'HOME': '/home/cltbld', 07:37:04 INFO - 'LANG': 'en_US.UTF-8', 07:37:04 INFO - 'LANGUAGE': 'en_US:en', 07:37:04 INFO - 'LOGNAME': 'cltbld', 07:37:04 INFO - 'MAIL': '/var/mail/cltbld', 07:37:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:37:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:37:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:37:04 INFO - 'MOZ_NO_REMOTE': '1', 07:37:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:37:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:37:04 INFO - 'NO_EM_RESTART': '1', 07:37:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:37:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:37:04 INFO - 'PWD': '/builds/slave/test', 07:37:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:37:04 INFO - 'SHELL': '/bin/bash', 07:37:04 INFO - 'SHLVL': '1', 07:37:04 INFO - 'TERM': 'linux', 07:37:04 INFO - 'TMOUT': '86400', 07:37:04 INFO - 'USER': 'cltbld', 07:37:04 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1475244157.745591-1472413597', 07:37:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:37:04 INFO - '_': '/tools/buildbot/bin/python'} 07:37:04 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-018', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:37:04 INFO - mozversion application_buildid: 20160930013320 07:37:04 INFO - mozversion application_changeset: 805d5fc3d66c4fa585a3de407b1e0b055f78eb58 07:37:04 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:37:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:37:04 INFO - mozversion application_name: Firefox 07:37:04 INFO - mozversion application_remotingname: firefox-dev 07:37:04 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:37:04 INFO - mozversion application_vendor: Mozilla 07:37:04 INFO - mozversion application_version: 51.0a2 07:37:04 INFO - mozversion platform_buildid: 20160930013320 07:37:04 INFO - mozversion platform_changeset: 805d5fc3d66c4fa585a3de407b1e0b055f78eb58 07:37:04 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:37:04 INFO - mozversion platform_version: 51.0a2 07:37:05 INFO - using testdate: 1475246224 07:37:05 INFO - actual date: 1475246224 07:37:05 INFO - starting webserver on 'localhost:56678' 07:37:05 INFO - SUITE-START | Running 2 tests 07:37:05 INFO - TEST-START | dromaeo_css 07:37:05 INFO - operating with platform_type : linux_ 07:37:05 INFO - Initialising browser for dromaeo_css test... 07:37:05 INFO - TEST-INFO | started process 15488 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgocjNg/profile http://localhost:56678/getInfo.html) 07:37:08 INFO - PROCESS | 15488 | __metrics Screen width/height:1600/1200 07:37:08 INFO - PROCESS | 15488 | colorDepth:24 07:37:08 INFO - PROCESS | 15488 | Browser inner width/height: 1024/697 07:37:08 INFO - PROCESS | 15488 | __metrics 07:37:09 INFO - PROCESS | 15488 | 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:09 INFO - PROCESS | 15488 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:37:09 INFO - PROCESS | 15488 | console.error: PushService: 07:37:09 INFO - PROCESS | 15488 | stateChangeProcessEnqueue: Error transitioning state 07:37:09 INFO - PROCESS | 15488 | UnknownError 07:37:10 INFO - TEST-INFO | 15488: exit 0 07:37:10 INFO - Browser initialized. 07:37:10 INFO - Running cycle 1/1 for dromaeo_css test... 07:37:10 INFO - TEST-INFO | started process 15579 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgocjNg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:37:22 INFO - PROCESS | 15579 | RSS: Main: 144416768 07:37:22 INFO - PROCESS | 15579 | 07:38:13 INFO - PROCESS | 15579 | 1475246293196 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:22 INFO - PROCESS | 15579 | Cycle 1(1): loaded http://localhost:56678/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56678/tests/dromaeo/cssquery-ext.html) 07:40:23 INFO - PROCESS | 15579 | RSS: Main: 238759936 07:40:23 INFO - PROCESS | 15579 | 07:43:25 INFO - PROCESS | 15579 | Cycle 1(1): loaded http://localhost:56678/tests/dromaeo/cssquery-ext.html (next: http://localhost:56678/tests/dromaeo/cssquery-jquery.html) 07:43:26 INFO - PROCESS | 15579 | RSS: Main: 230776832 07:43:26 INFO - PROCESS | 15579 | 07:46:28 INFO - PROCESS | 15579 | Cycle 1(1): loaded http://localhost:56678/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56678/tests/dromaeo/cssquery-mootools.html) 07:46:29 INFO - PROCESS | 15579 | RSS: Main: 174080000 07:46:29 INFO - PROCESS | 15579 | 07:49:31 INFO - PROCESS | 15579 | Cycle 1(1): loaded http://localhost:56678/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56678/tests/dromaeo/cssquery-prototype.html) 07:49:31 INFO - PROCESS | 15579 | RSS: Main: 174305280 07:49:31 INFO - PROCESS | 15579 | 07:52:34 INFO - PROCESS | 15579 | Cycle 1(1): loaded http://localhost:56678/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56678/tests/dromaeo/cssquery-yui.html) 07:52:34 INFO - PROCESS | 15579 | RSS: Main: 178810880 07:52:34 INFO - PROCESS | 15579 | 07:55:37 INFO - PROCESS | 15579 | Cycle 1(1): loaded http://localhost:56678/tests/dromaeo/cssquery-yui.html (next: http://localhost:56678/tests/dromaeo/cssquery-dojo.html) 07:55:37 INFO - PROCESS | 15579 | RSS: Main: 267583488 07:55:37 INFO - PROCESS | 15579 | 07:55:37 INFO - PROCESS | 15579 | __start_tp_report 07:55:37 INFO - PROCESS | 15579 | _x_x_mozilla_page_load 07:55:37 INFO - PROCESS | 15579 | _x_x_mozilla_page_load_details 07:55:37 INFO - PROCESS | 15579 | |i|pagename|runs| 07:55:37 INFO - PROCESS | 15579 | |0;dojo.html;3071.4317851197525;3115.9561546881787;3120.0951724000042;3148.228683972427;3151.7005884440982;5484.067708489556;5531.363893152288;5539.196816461611;5551.8889622207535;5556.805511807088;5616.971915140429;5661.8584535386635;5690.9146362804595;5697.344805347387;5702.914456283159;5110.795568177277;5130.692158470485;5173.922391164136;5188.533032027117;5200.297959775438;4926.064047830919;5158.3552055993005;5214.713190774506;5217.139172036609;5219.79120835168;4915.655904086715;4927.1377508935975;4964.503549645025;4967.403911530621;5073.3404657394485;5088.414832294278;5116.999999999982;5117.872053198663;5186.662866913639;5191.195364718475;5030.044291584587;5097.980403919194;5146.948530514722;5156.3296771419855;5188.481151884781;5326.866828329284;5358.892822143535;5474.671519708831;5501.119820828648;5520.641158324696;5930.7034648267845;5940.168376427304;5956.37458066901;6036.63780173191;6040.607360521552;6086.786962456293;6094.8171554853425;6096.359882212349;6097.024476083805;6106.725197366085;5338.332708411449;5367.355917289911;5577.358603760579;5618.578606604521;5639.154126881001;6132.816015519542;6179.134921110958;6180.567360284737;6183.659898705614;6195.535334849904;5909.5863289569315;5916.497097746654;5917.970410147922;5921.437463440966;5923.289205295306;5786.739596718157;5844.795432159854;5892.175095486636;5957.731902064417;5958.2850057992455;5986.341502434729;5994.670293133832;5999.610025348338;6003.459688628044;6030.366811484819;5943.078822782476;5998.4601385875485;6009.2188015557695;6021.879562408815;6022.1870047543025;4486.820527178941;4509.1658043262105;4541.863744087683;4546.545345465427;4620.214563524209;4050.432939388488;4052.4326594276827;4060.878173654795;4060.9796951015055;4097.795110244476;5588.385277619459;5592.105263157875;5607.635503692329;5611.046122159243;5616.5787565932715;5838.7080645967535;5883.558733095035;5901.498372638288;5942.70286485674;5946.07835785454;5551.361593416808;5662.518685911744;5673.432656734293;5692;5697.145428185722;5182.896342073137;5185.299984502044;5189.117849965515;5199.66202196856;5212.40057393397;5827.125931110368;5878.911816322673;6146.938530614726;6151.9384806151365;6157.661328626879;5859.267591551056;5896.734646940812;5909.468147866713;5998.700064996646;6043.486303664238;6155.076738489262;6155.784547540994;6165.876682466415;6167.938320616737;6174.9073763892675;5684.175794509691;5869.4717475427415;5878.676672783038;5881.2942446906645;5881.970590147193;5766.192733017297;5907.232059832366;5915.349311575637;5961.731722072431;6045.335013148638;5811.912821307768;5851.473367396955;5853.736581853912;5857.47282744538;5861.9706901465215;5857.384974577779;5858.912116318344;5860.062390017577;5860.765569377348;5862.443067908542;5507.284053073075;5538.47384498472;5641.40765219646;5667.716614169358;5676.77292908279;5805.970970145122;5812.418758124154;5816.272965879265;5821.185034095145;5822.737976791139;5543.334799823886;5658.377578466448;5660.85847853807;5670.206171136127;5673.205751194753 07:55:37 INFO - PROCESS | 15579 | |1;ext.html;591.6331874237973;613.9785107521237;616.5776443136449;640.107050664323;648.9610623362597;35286.29427411454;38494.23011539771;47242.76378618111;47857.521424785744;48389.27416088753;47887.52112478874;48244.27633585499;48282;48324.758376208156;48357.75821120889;9348.345615806897;9494.240460763122;9500.429974201536;9503.429794212334;9505.429674219535;8425.1153628869;8684.478931264113;10854.565817367296;11116.055135313467;11151.721206969809;11337.829932551018;11433.199676022681;11433.885661143366;11438.313701177956;11440.48517816696;11204.887951120467;11267.492962816652;11269.154813389028;11274.549018039268;11287.887121128766;11072.778544429148;11106.500207490682;11142.888571114347;11147.331160130418;11150.944245278723;46231;46537.76731116357;46700;46717.766411168064;46822.76588617035;3856.730028897978;3912.6256616061874;3950.525936887563;4012.8996775080564;4035.8587449439424;35117;35382;35417.645823541694;35891.28217435637;36395.09012274688;4318.719283246611;4323.481182258118;4338.02286456194;4352.89117772055;4368.257396242647;261159;261298;261778;262808;263147;65903.67048164824;66123;66180;66283.66858165678;66319;40320.193596127916;41570;41643.167136657095;70344;70722.64638676774;20464.079116439796;20725.378238652866;20733.481662958246;20734.79265207359;20747.896260518603;14470.927645361917;15878.52364429069;16080.195990200444;16086.35654573804;16094.275757590936;18592.721109183374;20768.48078798042;21393.89303053475;21656.35030949074;22263;7423.552407280528;7475.887861682079;7533.585652789039;7541.849163016818;7588;1040.1730624153815;1041.7239431550645;1043.9791204175874;1062.665260442958;1099.8955099265556;34848.4999055923;35211.64788352135;35216.11959700977;35308.29383412369;35351.4697279541;1182.6097387861985;1214.799558072908;1243.30996297056;1243.6455610151063;1254.6863284178955;17792;18602.441926741947;18622.441326760218;18633.440996770118;18684.719729204073;552783;553771;555227;555875;556208;63917.36082639207;64328.35671643224;64343.67828160923;64599.35400646027;64601.676991615685;1322.1273959187083;1353.553327401955;1385.210430054859;1442.7042456296224;1458.1761304862719;33555.83222083874;36005.63994360023;36116.63883361133;36191;36484.45273320849;56102.158467623834;56735.14897276627;56785;56832.43167568437;56914.430855690916;399795;403489;403899;404741;405426;30681.846590767796;32240.516392254605;32292;33439.331213376085;33511.16222094467;49955.250671239235;50313.49686503088;50385.244221335975;50487.24269136039;51579;41295;41357.1728565421;41813.37279940742;42075;43198.352024719024;42516.787416062725;42742.14515709606;43303.566964329955;44161.33757993697;44821.55178448174;42372.1525569493;42611;43370.13259734851;43977.34033989429;43984.34023489714;35982.8200858994;38312.23375532418;38659;39037.804810975766;39402.40896386614;28403.573946390406;28824.802737238988;28973.42053158883;29333.7066629331;29563 07:55:37 INFO - PROCESS | 15579 | |2;jquery.html;385.9768413895165;402.4546739168427;413.35516594113176;414.88175869877085;425.9148170365926;14450.566483005501;16299.837001629994;20569.794302056976;21103.788962110375;21228.257011004578;19782.703259451082;20033.899830500864;20118.295859644917;20129.798702013013;20390;5923.970380148104;5955;6123.357047510014;6164.660943648098;6191.690415479208;4302.763348015857;4581.67928245023;6065.423784740465;6155.19982402287;6158.261008678943;6443.452306553949;6689.063531105649;6718.025886246492;6829.93170068298;6840.589564626139;6592.703328350236;6786.219584747743;6790.7623233186605;6823.1812182537915;6833.043373927604;5188.351456067991;6036.0945858121195;6050.45545900871;6052.969735151296;6054.485368743662;13268;14043.1574105554;14051.50819721315;14052.648683782885;17567.5608109797;2364.8108151348024;2373.8457000294925;2471.2251219777713;2787.8048536602446;3001.669816320203;10039.949800251024;10104.98950104991;10301.22740794436;10404.167666586654;10788.352698838016;1976.6837306030966;2106.694529293259;2221.7778222177812;2284.463151159476;2419.1532963462823;82333;84181.4934328038;104506.47746761293;108139.45930270298;109560.4521977393;17771.82228177702;17809.910950445166;17837.554061148316;18287.177077031567;18761.437156885317;34077.65922340745;34229.828850855585;34305.14237144091;34409.82795086008;34484;11290.435478226056;11294.37880916541;11379.089672826154;11409.60066397672;11543.711407214887;9546.90453095474;9564.95217523922;9690;9757;9820.116189542976;8893.199612034949;9254.305927055499;9354.204892584206;9354.485503297248;9562.28282878773;3162.857671404792;3251.414745345813;3311.1391038330207;3320.5849268841393;3605.2685470537867;983.306768728045;1009.8283291840405;1034.9896501034893;1053.6431854282596;1056.3292309383596;19638.70541941872;19747.248022875607;19951.700724489143;20051.298204563063;20636;1022.3610243597751;1026.8665073540542;1034.5086084109987;1044.6448207609449;1062.2404980439023;10037.849432258521;11011.724706882393;11051.17116216287;11074.169437292074;11127.165462590337;505387;510772;511325;520783;525167;75777.62111189519;78119;100250;101077;102472.48763756282;974.8732664753537;1027.2603725317908;1063.0804354233687;1081.4376524207369;1083.0793825248477;17736.733948990517;17753.289868405118;18197.909010455394;18298.817011829713;18523.0738463079;43571.34642980294;44033.55966440294;44533;45039.54960450353;45165.322520161564;63482.68258658677;63895.36104638894;64252.678736606016;65019.67490162519;65136.674316628116;8624.698135565475;8740.34447416459;8755.168259015383;8850.380473366802;8856.804331415176;12493.75012499763;13341.332933353488;13383.665408364868;13597.388117534932;13837.930810345884;11483.885161148615;11506.769864602493;11718.472668730099;11778.352190629597;12233.204841685261;11444.542218311508;11516.942415288206;11627.941860290644;11985.34080625574;12212.938935305267;9443.622255109718;11830;11915.52337906474;11949.940250299042;12174.878251217375;8543.8718419225;8675.696350627697;8821.823563528566;8849.91150088491;9079.228265597498;11513.251638643462;11736.647900562997;11812.232204906997;11870.228435151688;12174.75650487003 07:55:37 INFO - PROCESS | 15579 | |3;mootools.html;1501.7221813964418;1523.2079318754247;1640.4340502526625;1722.8284766358886;1754.5438186071617;7787.844243115142;8319.792005199874;8320.75037748867;8325.29235015023;8327.58362081895;7186.065811444518;7399.704011839533;7825.647845846936;8080.1919808019165;8111.63497642606;2334.2647066174145;2362.137819695816;2364.125273648752;2365.6333268343433;2369.4668699542553;2369.9289021329387;2373.52529494101;2397.1250493569896;2397.19693902542;2404.915827946022;2352.4236562042324;2357.4813541021035;2357.9056837726557;2396.532676128156;2403.591389463795;2276.8178545716305;2302.090674183705;2308.68832707584;2309.549637820626;2317.7798109179603;2254.3913143451177;2258.0854753824565;2261.2650888461317;2264.4112530742127;2265.614845476273;8205.630746616356;8239.258466738022;8311.625976831057;8314.625841837133;8317.750467485925;4897.3878265216845;4899.8040078396825;5012.147934851084;5045.798168073273;5054.039732450822;3230.4669729494603;3270.345930813823;3316.6849149330774;3361.445361515347;3377.1894745261206;1131.1516362727955;1142.0977427832004;2125.2667829598763;2142.528643698384;2147.1733382647635;11015;11075.667729968114;11078.113750899909;11078.889211107948;11095.11239100878;4206.11671548972;4291.592298731615;4418.337249412614;4422.557744225552;4430.069685366045;8863.512506812187;8873.778155546162;8895.866562001574;8900.821983560292;8905.866412003825;5039.5968322534845;5039.82360617384;5044.344235249468;5044.899102017939;5045.217991211368;4099.61053699898;4211.389348544444;4523.886902827456;4686.74222917736;4774.236122220497;3604.2611264690677;3659.2864391443413;3717.553893532793;3734.327820992193;3747.662710356081;1126.5381193710539;1143.4054291768234;1143.634037108134;1150.5282834038005;1152.9942350288363;903.6295119001178;953.1802649721234;969.6353652824687;1050.968505613917;1065.0733861540355;5757.366689664133;5773.740181691834;5938.732757025944;5950.404959504015;5955.52355811534;443.22214513529195;443.3615593545284;443.50327633050546;444.08961628661115;447.8387780399054;4088.5707000765087;4213.747175169438;4302.849400270976;4308.913821723548;4325.718828276152;9575.04249575037;9826.263030272758;9843.803123937481;9895.901040989642;9911.752206194902;9789.559469823875;9796.51017449139;9799.706008819747;9803.264755143251;9805.166560842408;7322.743703970335;7602.46782725204;7620.161782203953;7759.456838021284;7759.844803104019;5841.18223448726;5862.384449632727;5960.523158147251;6070.9089363658695;6084.239470066242;9238.907610923805;9288.907110928803;9415.058494150804;9429.292803039662;9432.434053956784;9336.533173341442;9357.672481463389;9441.244700424088;9441.763955901157;9472.810543788948;6765.627890466071;7003.54476958996;7122.679479423335;7203.603801790951;7299.5255308404785;7897.131315555399;7904.328132108835;8080.474769139987;8092.554909480035;8101.068377136469;7218;7387.150477695134;7399.297066778648;7447.143578488326;7488.101427828696;7113.679884405111;7198.280171982759;7287.41700663935;7313.378362839218;7569;6999.615021173884;7009.474289428415;7030.824229394306;7030.964845175741;7032.894506582507;5168.656457097441;5343.091674415281;5350.678959262456;5351.357837059578;5576.1356989666665;5057.418396884405;5244.134717771678;5261.73691315425;5269.868253293698;5335.359756829205 07:55:37 INFO - PROCESS | 15579 | |4;prototype.html;329.31173846660477;337.5662273977938;337.783818356252;344.77245018287937;345.877213589176;5666.631668941521;5672.659640421567;5683.300432294658;5695.829125126248;5718.513926316257;5599.496045355917;5607.495325420711;5650.5479561635075;5672.659640421578;5675.5459563234945;4096.098858251187;4175.102352994106;4231.97884010581;4238.258304796664;4299.957000429987;4291.334843099309;4342.739435633856;4357.215701173787;4373.518912919576;4388.144311859189;4262.976885547478;4276.144771045788;4299.935500967488;4302.892427689301;4316.8991907063655;4208.831646734127;4285.9785701071605;4316.093620339732;4319.308910574325;4331.956680433218;3783.5459744830796;3791.146991926822;3806.3148633246005;3807.58116607173;3807.7144214184045;5411.945880541183;5431.34823821144;5433.673979561239;5441.714479918443;5457.372402173722;4845.466998630148;4910.140725373046;4958.231474121517;4978.352814134176;4987.401511818569;5778.6243894147165;5798.855028624276;5799.4780469757925;5804.912926306109;5806.44838740319;4220.36694495825;4235.0682849773;4252.723572967747;4258.936115958263;4263.91472170558;5859.472647461749;6058.818235452944;6090.604110732744;6096.237970253718;6134.631922084689;4231.492220933507;4270.42349282849;4274.230638485103;4280.379344994958;4294.573565935237;4347.282698354799;4352.434183556117;4355.5208927018;4360.280553708666;4362.280223763107;4814.927776083362;4891;4947.282644016597;4960.751962401865;5014.724190169576;5817.545613596601;5820.351739958309;5829.912551311733;5859.589828711949;5874.676892770939;1479.667074908137;1491.8806495480335;1493.9626509337354;1496.2369191712087;1507.1333982959843;928.1878356438117;958.5878072428923;958.8993155718689;964.0793042644258;1005.7535903703493;5493.423190564933;5634.464725851038;5666.603337766399;5734.283214598176;5735.426457354315;6069.211002569726;6076.513878889678;6089.695515224221;6105.733432613619;6121.755129794758;5578.219049333096;5633.8591535211945;5678.4037676044245;5707.857303567444;5723.198752174699;3191.8723251069696;3205.6794320568224;3206.887758928426;3212.3093534889645;3215.7266632336045;6017.909731354033;6211.192544969215;6223.3465486123305;6255.562110652301;6259.812205633838;6133.325334213233;6135.202423684892;6156.26124865019;6156.5998210116195;6158.322584515699;6174.351693072343;6179.014535624497;6183.598066125777;6195.504359651307;6201.472874805602;5726;5950.256217972754;5974.312954010345;5988.431099045583;5994.28068631767;5893.557983151195;5901.704914754331;5919.911201331897;5921.822345329647;5978;5929.436703513159;6148.139260503443;6183.196184495987;6220.782272620437;6232.127502149793;4235.407043014042;4243.787810609519;4273.807678654406;4278.764667943293;4279.529251782239;6063.6664983426335;6064.696765161812;6093.146959425595;6101.383133469722;6106.961816491118;5782.797602083907;5812.73842677089;5813.215215945962;5841;5882.499987500939;5798;5820.621659592282;5820.970895145667;5825.970870145622;5867.125108696622;5614.747336369792;5779.104238842986;5814.30228372598;5835.824925252249;5846.889091072682;5867.501262392744;5888.764449422147;5893.764249430146;5924.229850119457;5952.791652292149;5798.275215598051;5814.017398082327;5821.82534523948;5822.7670893163795;5841.4450627190345 07:55:37 INFO - PROCESS | 15579 | |5;yui.html;827.9130691277416;836.9121242269562;854.4275335525198;869.647792643979;900.6307413960278;16727.498175054752;17754.82245177548;17755.378561750324;17757.46727598174;17759.91120044401;12830.422630981604;16435.506934791974;16473.093979831105;16605.169741512906;16620.085895275755;700.2157583506468;845.45045720282;871.5555066915887;874.5714599846061;876.1326286975911;863.6933888469597;871.4422769427572;871.7646235516406;873.2926329672954;873.3319010956624;910.1217325281102;912.3066469483207;914.2320450821309;918.5361392496814;918.8713580098791;747.7457664394105;766.1113108793801;890.461270931087;897.0043251990286;900.2932697832235;661.5732852310281;661.8444665503603;662.076403417235;662.0995446193174;662.2483481248769;15580.220988950621;15636.2181890905;15638.609034774108;15646.608834779108;15648.217589120612;15380.846191538165;15438.9228053859;15439.536813895602;15469.226538673021;15602;14162.645933851632;14167.149971001772;14487.130772153705;14679.706405871822;15003.624909377353;4240.724352917051;4286.249906266391;4343.891402714926;4436.55634436557;4445.755483448409;85069;85515;87516;90325;90785.54607226922;12409.75180496385;12472.314022728655;12510.874891251153;12593.559225427067;12724.236545807282;23054.308370748568;23085.884570577375;23153.652695209585;23209.651855222186;23221.41946451352;9085.727428177166;9209.907900921038;9313.48775817323;9365.578548965177;9451.858222126673;5083.085044691917;5134.614903882149;5189.40321862983;5305.07161246788;5324.733763311819;3494.4408894576745;3615.5661320641693;3621.94567081494;3624.8187590620364;3625.510556074896;539.9757010934518;566.6515093217641;567.5487987050304;581.2414798687669;585.8798946216016;679.67035987546;714.4855703893188;728.2717282717283;799.3205775091242;817.8159914019298;10505.632302869362;10818.188635852342;11214.327140371604;11459.942700286445;11718.296902185895;688.5421194905432;760.9429292803062;772.9420293478012;774.2683164409578;790.9644066017044;4719.5752382285755;5104.464031276737;5135.383753949475;5144.922826157611;5167.767450464663;71952;74892;128408;135623.32188338996;136250;107195;108712.45643771731;110064.4496777527;111552.98011589498;114203;61540.69229653823;81545;82947.5852620733;91267.54366228127;95023;10667.626633067805;10703.608530890935;10820.621278255223;11159;11446;54070.459295406545;54665.72667136639;55793.44206557883;55980.72009639926;56814.147787784044;74966.62516687381;78661.60669196617;78733.60633197027;81120.59439702764;82009;13919.652008699864;13968.860311396757;14485.92757036208;14575.562733118024;14594.416223350947;16507.174641268128;16832.57918551997;16833;17644.470665880042;17888.642227155124;14253.50112746049;14539.85460145385;14910.925445372704;14940;16434.506964791075;15532.844671553139;15621.297041633381;16481.423150189687;16541.338346466488;16885.32458701638;14280.857191428368;15266.542003739905;15611;16344.754828677342;16363.100029498491;12638.431270592662;12988.870111299144;13427.19436833793;13433.261170635318;14089.718205636034;11466.775931163314;12292.81560776607;12744.808827867404;13029.739405212033;13376.729210724965 07:55:37 INFO - PROCESS | 15579 | __end_tp_report 07:55:37 INFO - PROCESS | 15579 | __start_cc_report 07:55:37 INFO - PROCESS | 15579 | _x_x_mozilla_cycle_collect,881 07:55:37 INFO - PROCESS | 15579 | __end_cc_report 07:55:37 INFO - PROCESS | 15579 | __startTimestamp1475247337768__endTimestamp 07:55:37 INFO - PROCESS | 15579 | 07:55:37 INFO - PROCESS | 15579 | ------- Summary: start ------- 07:55:37 INFO - PROCESS | 15579 | Number of tests: 6 07:55:37 INFO - PROCESS | 15579 | 07:55:37 INFO - PROCESS | 15579 | [#0] dojo.html Cycles:165 Average:5530.32 Median:5692.00 stddev:636.67 (11.2%) stddev-sans-first:608.70 07:55:37 INFO - PROCESS | 15579 | Values: 3071.4 3116.0 3120.1 3148.2 3151.7 5484.1 5531.4 5539.2 5551.9 5556.8 5617.0 5661.9 5690.9 5697.3 5702.9 5110.8 5130.7 5173.9 5188.5 5200.3 4926.1 5158.4 5214.7 5217.1 5219.8 4915.7 4927.1 4964.5 4967.4 5073.3 5088.4 5117.0 5117.9 5186.7 5191.2 5030.0 5098.0 5146.9 5156.3 5188.5 5326.9 5358.9 5474.7 5501.1 5520.6 5930.7 5940.2 5956.4 6036.6 6040.6 6086.8 6094.8 6096.4 6097.0 6106.7 5338.3 5367.4 5577.4 5618.6 5639.2 6132.8 6179.1 6180.6 6183.7 6195.5 5909.6 5916.5 5918.0 5921.4 5923.3 5786.7 5844.8 5892.2 5957.7 5958.3 5986.3 5994.7 5999.6 6003.5 6030.4 5943.1 5998.5 6009.2 6021.9 6022.2 4486.8 4509.2 4541.9 4546.5 4620.2 4050.4 4052.4 4060.9 4061.0 4097.8 5588.4 5592.1 5607.6 5611.0 5616.6 5838.7 5883.6 5901.5 5942.7 5946.1 5551.4 5662.5 5673.4 5692.0 5697.1 5182.9 5185.3 5189.1 5199.7 5212.4 5827.1 5878.9 6146.9 6151.9 6157.7 5859.3 5896.7 5909.5 5998.7 6043.5 6155.1 6155.8 6165.9 6167.9 6174.9 5684.2 5869.5 5878.7 5881.3 5882.0 5766.2 5907.2 5915.3 5961.7 6045.3 5811.9 5851.5 5853.7 5857.5 5862.0 5857.4 5858.9 5860.1 5860.8 5862.4 5507.3 5538.5 5641.4 5667.7 5676.8 5806.0 5812.4 5816.3 5821.2 5822.7 5543.3 5658.4 5660.9 5670.2 5673.2 07:55:37 INFO - PROCESS | 15579 | 07:55:37 INFO - PROCESS | 15579 | [#1] ext.html Cycles:180 Average:59260.94 Median:21657.52 stddev:112278.15 (518.4%) stddev-sans-first:112506.71 07:55:37 INFO - PROCESS | 15579 | Values: 591.6 614.0 616.6 640.1 649.0 35286.3 38494.2 47242.8 47857.5 48389.3 47887.5 48244.3 48282.0 48324.8 48357.8 9348.3 9494.2 9500.4 9503.4 9505.4 8425.1 8684.5 10854.6 11116.1 11151.7 11337.8 11433.2 11433.9 11438.3 11440.5 11204.9 11267.5 11269.2 11274.5 11287.9 11072.8 11106.5 11142.9 11147.3 11150.9 46231.0 46537.8 46700.0 46717.8 46822.8 3856.7 3912.6 3950.5 4012.9 4035.9 35117.0 35382.0 35417.6 35891.3 36395.1 4318.7 4323.5 4338.0 4352.9 4368.3 261159.0 261298.0 261778.0 262808.0 263147.0 65903.7 66123.0 66180.0 66283.7 66319.0 40320.2 41570.0 41643.2 70344.0 70722.6 20464.1 20725.4 20733.5 20734.8 20747.9 14470.9 15878.5 16080.2 16086.4 16094.3 18592.7 20768.5 21393.9 21656.4 22263.0 7423.6 7475.9 7533.6 7541.8 7588.0 1040.2 1041.7 1044.0 1062.7 1099.9 34848.5 35211.6 35216.1 35308.3 35351.5 1182.6 1214.8 1243.3 1243.6 1254.7 17792.0 18602.4 18622.4 18633.4 18684.7 552783.0 553771.0 555227.0 555875.0 556208.0 63917.4 64328.4 64343.7 64599.4 64601.7 1322.1 1353.6 1385.2 1442.7 1458.2 33555.8 36005.6 36116.6 36191.0 36484.5 56102.2 56735.1 56785.0 56832.4 56914.4 399795.0 403489.0 403899.0 404741.0 405426.0 30681.8 32240.5 32292.0 33439.3 33511.2 49955.3 50313.5 50385.2 50487.2 51579.0 41295.0 41357.2 41813.4 42075.0 43198.4 42516.8 42742.1 43303.6 44161.3 44821.6 42372.2 42611.0 43370.1 43977.3 43984.3 35982.8 38312.2 38659.0 39037.8 39402.4 28403.6 28824.8 28973.4 29333.7 29563.0 07:55:37 INFO - PROCESS | 15579 | 07:55:37 INFO - PROCESS | 15579 | [#2] jquery.html Cycles:180 Average:31345.71 Median:2253.12 stddev:85018.21 (3773.4%) stddev-sans-first:85224.93 07:55:37 INFO - PROCESS | 15579 | Values: 386.0 402.5 413.4 414.9 425.9 14450.6 16299.8 20569.8 21103.8 21228.3 19782.7 20033.9 20118.3 20129.8 20390.0 5924.0 5955.0 6123.4 6164.7 6191.7 4302.8 4581.7 6065.4 6155.2 6158.3 6443.5 6689.1 6718.0 6829.9 6840.6 6592.7 6786.2 6790.8 6823.2 6833.0 5188.4 6036.1 6050.5 6053.0 6054.5 13268.0 14043.2 14051.5 14052.6 17567.6 2364.8 2373.8 2471.2 2787.8 3001.7 10039.9 10105.0 10301.2 10404.2 10788.4 1976.7 2106.7 2221.8 2284.5 2419.2 82333.0 84181.5 104506.5 108139.5 109560.5 17771.8 17809.9 17837.6 18287.2 18761.4 34077.7 34229.8 34305.1 34409.8 34484.0 11290.4 11294.4 11379.1 11409.6 11543.7 9546.9 9565.0 9690.0 9757.0 9820.1 8893.2 9254.3 9354.2 9354.5 9562.3 3162.9 3251.4 3311.1 3320.6 3605.3 983.3 1009.8 1035.0 1053.6 1056.3 19638.7 19747.2 19951.7 20051.3 20636.0 1022.4 1026.9 1034.5 1044.6 1062.2 10037.8 11011.7 11051.2 11074.2 11127.2 505387.0 510772.0 511325.0 520783.0 525167.0 75777.6 78119.0 100250.0 101077.0 102472.5 974.9 1027.3 1063.1 1081.4 1083.1 17736.7 17753.3 18197.9 18298.8 18523.1 43571.3 44033.6 44533.0 45039.5 45165.3 63482.7 63895.4 64252.7 65019.7 65136.7 8624.7 8740.3 8755.2 8850.4 8856.8 12493.8 13341.3 13383.7 13597.4 13837.9 11483.9 11506.8 11718.5 11778.4 12233.2 11444.5 11516.9 11627.9 11985.3 12212.9 9443.6 11830.0 11915.5 11949.9 12174.9 8543.9 8675.7 8821.8 8849.9 9079.2 11513.3 11736.6 11812.2 11870.2 12174.8 07:55:37 INFO - PROCESS | 15579 | 07:55:37 INFO - PROCESS | 15579 | [#3] mootools.html Cycles:180 Average:5475.31 Median:5265.80 stddev:2941.98 (55.9%) stddev-sans-first:2935.07 07:55:37 INFO - PROCESS | 15579 | Values: 1501.7 1523.2 1640.4 1722.8 1754.5 7787.8 8319.8 8320.8 8325.3 8327.6 7186.1 7399.7 7825.6 8080.2 8111.6 2334.3 2362.1 2364.1 2365.6 2369.5 2369.9 2373.5 2397.1 2397.2 2404.9 2352.4 2357.5 2357.9 2396.5 2403.6 2276.8 2302.1 2308.7 2309.5 2317.8 2254.4 2258.1 2261.3 2264.4 2265.6 8205.6 8239.3 8311.6 8314.6 8317.8 4897.4 4899.8 5012.1 5045.8 5054.0 3230.5 3270.3 3316.7 3361.4 3377.2 1131.2 1142.1 2125.3 2142.5 2147.2 11015.0 11075.7 11078.1 11078.9 11095.1 4206.1 4291.6 4418.3 4422.6 4430.1 8863.5 8873.8 8895.9 8900.8 8905.9 5039.6 5039.8 5044.3 5044.9 5045.2 4099.6 4211.4 4523.9 4686.7 4774.2 3604.3 3659.3 3717.6 3734.3 3747.7 1126.5 1143.4 1143.6 1150.5 1153.0 903.6 953.2 969.6 1051.0 1065.1 5757.4 5773.7 5938.7 5950.4 5955.5 443.2 443.4 443.5 444.1 447.8 4088.6 4213.7 4302.8 4308.9 4325.7 9575.0 9826.3 9843.8 9895.9 9911.8 9789.6 9796.5 9799.7 9803.3 9805.2 7322.7 7602.5 7620.2 7759.5 7759.8 5841.2 5862.4 5960.5 6070.9 6084.2 9238.9 9288.9 9415.1 9429.3 9432.4 9336.5 9357.7 9441.2 9441.8 9472.8 6765.6 7003.5 7122.7 7203.6 7299.5 7897.1 7904.3 8080.5 8092.6 8101.1 7218.0 7387.2 7399.3 7447.1 7488.1 7113.7 7198.3 7287.4 7313.4 7569.0 6999.6 7009.5 7030.8 7031.0 7032.9 5168.7 5343.1 5350.7 5351.4 5576.1 5057.4 5244.1 5261.7 5269.9 5335.4 07:55:37 INFO - PROCESS | 15579 | 07:55:37 INFO - PROCESS | 15579 | [#4] prototype.html Cycles:180 Average:4932.31 Median:5689.56 stddev:1460.81 (25.7%) stddev-sans-first:1423.47 07:55:37 INFO - PROCESS | 15579 | Values: 329.3 337.6 337.8 344.8 345.9 5666.6 5672.7 5683.3 5695.8 5718.5 5599.5 5607.5 5650.5 5672.7 5675.5 4096.1 4175.1 4232.0 4238.3 4300.0 4291.3 4342.7 4357.2 4373.5 4388.1 4263.0 4276.1 4299.9 4302.9 4316.9 4208.8 4286.0 4316.1 4319.3 4332.0 3783.5 3791.1 3806.3 3807.6 3807.7 5411.9 5431.3 5433.7 5441.7 5457.4 4845.5 4910.1 4958.2 4978.4 4987.4 5778.6 5798.9 5799.5 5804.9 5806.4 4220.4 4235.1 4252.7 4258.9 4263.9 5859.5 6058.8 6090.6 6096.2 6134.6 4231.5 4270.4 4274.2 4280.4 4294.6 4347.3 4352.4 4355.5 4360.3 4362.3 4814.9 4891.0 4947.3 4960.8 5014.7 5817.5 5820.4 5829.9 5859.6 5874.7 1479.7 1491.9 1494.0 1496.2 1507.1 928.2 958.6 958.9 964.1 1005.8 5493.4 5634.5 5666.6 5734.3 5735.4 6069.2 6076.5 6089.7 6105.7 6121.8 5578.2 5633.9 5678.4 5707.9 5723.2 3191.9 3205.7 3206.9 3212.3 3215.7 6017.9 6211.2 6223.3 6255.6 6259.8 6133.3 6135.2 6156.3 6156.6 6158.3 6174.4 6179.0 6183.6 6195.5 6201.5 5726.0 5950.3 5974.3 5988.4 5994.3 5893.6 5901.7 5919.9 5921.8 5978.0 5929.4 6148.1 6183.2 6220.8 6232.1 4235.4 4243.8 4273.8 4278.8 4279.5 6063.7 6064.7 6093.1 6101.4 6107.0 5782.8 5812.7 5813.2 5841.0 5882.5 5798.0 5820.6 5821.0 5826.0 5867.1 5614.7 5779.1 5814.3 5835.8 5846.9 5867.5 5888.8 5893.8 5924.2 5952.8 5798.3 5814.0 5821.8 5822.8 5841.4 07:55:37 INFO - PROCESS | 15579 | 07:55:37 INFO - PROCESS | 15579 | [#5] yui.html Cycles:180 Average:22224.08 Median:3623.38 stddev:31347.67 (865.1%) stddev-sans-first:31394.44 07:55:37 INFO - PROCESS | 15579 | Values: 827.9 836.9 854.4 869.6 900.6 16727.5 17754.8 17755.4 17757.5 17759.9 12830.4 16435.5 16473.1 16605.2 16620.1 700.2 845.5 871.6 874.6 876.1 863.7 871.4 871.8 873.3 873.3 910.1 912.3 914.2 918.5 918.9 747.7 766.1 890.5 897.0 900.3 661.6 661.8 662.1 662.1 662.2 15580.2 15636.2 15638.6 15646.6 15648.2 15380.8 15438.9 15439.5 15469.2 15602.0 14162.6 14167.1 14487.1 14679.7 15003.6 4240.7 4286.2 4343.9 4436.6 4445.8 85069.0 85515.0 87516.0 90325.0 90785.5 12409.8 12472.3 12510.9 12593.6 12724.2 23054.3 23085.9 23153.7 23209.7 23221.4 9085.7 9209.9 9313.5 9365.6 9451.9 5083.1 5134.6 5189.4 5305.1 5324.7 3494.4 3615.6 3621.9 3624.8 3625.5 540.0 566.7 567.5 581.2 585.9 679.7 714.5 728.3 799.3 817.8 10505.6 10818.2 11214.3 11459.9 11718.3 688.5 760.9 772.9 774.3 791.0 4719.6 5104.5 5135.4 5144.9 5167.8 71952.0 74892.0 128408.0 135623.3 136250.0 107195.0 108712.5 110064.4 111553.0 114203.0 61540.7 81545.0 82947.6 91267.5 95023.0 10667.6 10703.6 10820.6 11159.0 11446.0 54070.5 54665.7 55793.4 55980.7 56814.1 74966.6 78661.6 78733.6 81120.6 82009.0 13919.7 13968.9 14485.9 14575.6 14594.4 16507.2 16832.6 16833.0 17644.5 17888.6 14253.5 14539.9 14910.9 14940.0 16434.5 15532.8 15621.3 16481.4 16541.3 16885.3 14280.9 15266.5 15611.0 16344.8 16363.1 12638.4 12988.9 13427.2 13433.3 14089.7 11466.8 12292.8 12744.8 13029.7 13376.7 07:55:37 INFO - PROCESS | 15579 | -------- Summary: end -------- 07:55:37 INFO - PROCESS | 15579 | 07:55:38 INFO - TEST-INFO | 15579: exit 0 07:55:38 INFO - TEST-OK | dromaeo_css | took 1113513ms 07:55:38 INFO - TEST-START | kraken 07:55:38 INFO - operating with platform_type : linux_ 07:55:38 INFO - Initialising browser for kraken test... 07:55:38 INFO - TEST-INFO | started process 15900 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5Vi_Cb/profile http://localhost:56678/getInfo.html) 07:55:42 INFO - PROCESS | 15900 | __metrics Screen width/height:1600/1200 07:55:42 INFO - PROCESS | 15900 | colorDepth:24 07:55:42 INFO - PROCESS | 15900 | Browser inner width/height: 1024/697 07:55:42 INFO - PROCESS | 15900 | __metrics 07:55:42 INFO - PROCESS | 15900 | 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:42 INFO - PROCESS | 15900 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:55:43 INFO - PROCESS | 15900 | console.error: PushService: 07:55:43 INFO - PROCESS | 15900 | stateChangeProcessEnqueue: Error transitioning state 07:55:43 INFO - PROCESS | 15900 | UnknownError 07:55:43 INFO - TEST-INFO | 15900: exit 0 07:55:43 INFO - Browser initialized. 07:55:43 INFO - Running cycle 1/1 for kraken test... 07:55:43 INFO - TEST-INFO | started process 16000 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5Vi_Cb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:55:55 INFO - PROCESS | 16000 | RSS: Main: 143908864 07:55:55 INFO - PROCESS | 16000 | 07:55:58 INFO - PROCESS | 16000 | Cycle 1(1): loaded http://localhost:56678/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56678/tests/kraken/driver.html?testName=audio-beat-detection) 07:55:58 INFO - PROCESS | 16000 | RSS: Main: 185954304 07:55:58 INFO - PROCESS | 16000 | 07:56:01 INFO - PROCESS | 16000 | Cycle 1(1): loaded http://localhost:56678/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56678/tests/kraken/driver.html?testName=audio-dft) 07:56:02 INFO - PROCESS | 16000 | RSS: Main: 175419392 07:56:02 INFO - PROCESS | 16000 | 07:56:04 INFO - PROCESS | 16000 | Cycle 1(1): loaded http://localhost:56678/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56678/tests/kraken/driver.html?testName=audio-fft) 07:56:05 INFO - PROCESS | 16000 | RSS: Main: 267313152 07:56:05 INFO - PROCESS | 16000 | 07:56:07 INFO - PROCESS | 16000 | Cycle 1(1): loaded http://localhost:56678/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56678/tests/kraken/driver.html?testName=audio-oscillator) 07:56:08 INFO - PROCESS | 16000 | RSS: Main: 172851200 07:56:08 INFO - PROCESS | 16000 | 07:56:10 INFO - PROCESS | 16000 | Cycle 1(1): loaded http://localhost:56678/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56678/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:56:10 INFO - PROCESS | 16000 | RSS: Main: 174469120 07:56:10 INFO - PROCESS | 16000 | 07:56:13 INFO - PROCESS | 16000 | Cycle 1(1): loaded http://localhost:56678/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56678/tests/kraken/driver.html?testName=imaging-darkroom) 07:56:14 INFO - PROCESS | 16000 | RSS: Main: 210513920 07:56:14 INFO - PROCESS | 16000 | 07:56:17 INFO - PROCESS | 16000 | Cycle 1(1): loaded http://localhost:56678/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56678/tests/kraken/driver.html?testName=imaging-desaturate) 07:56:18 INFO - PROCESS | 16000 | RSS: Main: 209956864 07:56:18 INFO - PROCESS | 16000 | 07:56:21 INFO - PROCESS | 16000 | Cycle 1(1): loaded http://localhost:56678/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56678/tests/kraken/driver.html?testName=json-parse-financial) 07:56:21 INFO - PROCESS | 16000 | RSS: Main: 209092608 07:56:21 INFO - PROCESS | 16000 | 07:56:23 INFO - PROCESS | 16000 | Cycle 1(1): loaded http://localhost:56678/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56678/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:56:23 INFO - PROCESS | 16000 | RSS: Main: 171216896 07:56:23 INFO - PROCESS | 16000 | 07:56:25 INFO - PROCESS | 16000 | Cycle 1(1): loaded http://localhost:56678/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56678/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:56:26 INFO - PROCESS | 16000 | RSS: Main: 178946048 07:56:26 INFO - PROCESS | 16000 | 07:56:28 INFO - PROCESS | 16000 | Cycle 1(1): loaded http://localhost:56678/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56678/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:56:28 INFO - PROCESS | 16000 | RSS: Main: 183382016 07:56:28 INFO - PROCESS | 16000 | 07:56:31 INFO - PROCESS | 16000 | Cycle 1(1): loaded http://localhost:56678/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56678/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:56:31 INFO - PROCESS | 16000 | RSS: Main: 184311808 07:56:31 INFO - PROCESS | 16000 | 07:56:34 INFO - PROCESS | 16000 | Cycle 1(1): loaded http://localhost:56678/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56678/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:56:35 INFO - PROCESS | 16000 | RSS: Main: 182857728 07:56:35 INFO - PROCESS | 16000 | 07:56:36 INFO - PROCESS | 16000 | Cycle 1(1): loaded http://localhost:56678/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56678/tests/kraken/driver.html?testName=ai-astar) 07:56:36 INFO - PROCESS | 16000 | RSS: Main: 233836544 07:56:36 INFO - PROCESS | 16000 | 07:56:36 INFO - PROCESS | 16000 | __start_tp_report 07:56:36 INFO - PROCESS | 16000 | _x_x_mozilla_page_load 07:56:36 INFO - PROCESS | 16000 | _x_x_mozilla_page_load_details 07:56:36 INFO - PROCESS | 16000 | |i|pagename|runs| 07:56:36 INFO - PROCESS | 16000 | |0;ai-astar;110;111;110;110;112;110;110;110;110;110 07:56:36 INFO - PROCESS | 16000 | |1;audio-beat-detection;147;122;118;157;119;118;116;123;153;117 07:56:36 INFO - PROCESS | 16000 | |2;audio-dft;162;160;162;159;161;160;162;161;161;160 07:56:36 INFO - PROCESS | 16000 | |3;audio-fft;76;76;76;110;73;75;76;75;106;73 07:56:36 INFO - PROCESS | 16000 | |4;audio-oscillator;81;83;125;83;83;83;101;82;82;109 07:56:36 INFO - PROCESS | 16000 | |5;imaging-gaussian-blur;117;117;117;117;116;117;116;116;116;117 07:56:36 INFO - PROCESS | 16000 | |6;imaging-darkroom;146;147;146;147;147;147;147;147;146;146 07:56:36 INFO - PROCESS | 16000 | |7;imaging-desaturate;95;95;95;95;94;95;94;95;95;95 07:56:36 INFO - PROCESS | 16000 | |8;json-parse-financial;51;49;49;49;49;50;49;50;50;49 07:56:36 INFO - PROCESS | 16000 | |9;json-stringify-tinderbox;58;59;61;58;60;58;59;59;59;59 07:56:36 INFO - PROCESS | 16000 | |10;stanford-crypto-aes;82;77;74;77;73;74;74;79;77;73 07:56:36 INFO - PROCESS | 16000 | |11;stanford-crypto-ccm;129;120;118;123;123;115;116;117;151;124 07:56:36 INFO - PROCESS | 16000 | |12;stanford-crypto-pbkdf2;154;150;153;157;152;150;151;150;149;164 07:56:36 INFO - PROCESS | 16000 | |13;stanford-crypto-sha256-iterative;62;61;62;62;60;62;61;61;61;61 07:56:36 INFO - PROCESS | 16000 | __end_tp_report 07:56:36 INFO - PROCESS | 16000 | __start_cc_report 07:56:36 INFO - PROCESS | 16000 | _x_x_mozilla_cycle_collect,1593 07:56:36 INFO - PROCESS | 16000 | __end_cc_report 07:56:36 INFO - PROCESS | 16000 | __startTimestamp1475247396976__endTimestamp 07:56:36 INFO - PROCESS | 16000 | 07:56:36 INFO - PROCESS | 16000 | ------- Summary: start ------- 07:56:36 INFO - PROCESS | 16000 | Number of tests: 14 07:56:36 INFO - PROCESS | 16000 | 07:56:36 INFO - PROCESS | 16000 | [#0] ai-astar Cycles:10 Average:110.30 Median:110.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 07:56:36 INFO - PROCESS | 16000 | Values: 110.0 111.0 110.0 110.0 112.0 110.0 110.0 110.0 110.0 110.0 07:56:36 INFO - PROCESS | 16000 | 07:56:36 INFO - PROCESS | 16000 | [#1] audio-beat-detection Cycles:10 Average:129.00 Median:122.50 stddev:16.41 (13.4%) stddev-sans-first:16.06 07:56:36 INFO - PROCESS | 16000 | Values: 147.0 122.0 118.0 157.0 119.0 118.0 116.0 123.0 153.0 117.0 07:56:36 INFO - PROCESS | 16000 | 07:56:36 INFO - PROCESS | 16000 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:1.03 (0.6%) stddev-sans-first:1.00 07:56:36 INFO - PROCESS | 16000 | Values: 162.0 160.0 162.0 159.0 161.0 160.0 162.0 161.0 161.0 160.0 07:56:36 INFO - PROCESS | 16000 | 07:56:36 INFO - PROCESS | 16000 | [#3] audio-fft Cycles:10 Average:81.60 Median:75.50 stddev:13.99 (18.5%) stddev-sans-first:14.70 07:56:36 INFO - PROCESS | 16000 | Values: 76.0 76.0 76.0 110.0 73.0 75.0 76.0 75.0 106.0 73.0 07:56:36 INFO - PROCESS | 16000 | 07:56:36 INFO - PROCESS | 16000 | [#4] audio-oscillator Cycles:10 Average:91.20 Median:82.50 stddev:15.27 (18.5%) stddev-sans-first:15.74 07:56:36 INFO - PROCESS | 16000 | Values: 81.0 83.0 125.0 83.0 83.0 83.0 101.0 82.0 82.0 109.0 07:56:36 INFO - PROCESS | 16000 | 07:56:36 INFO - PROCESS | 16000 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 07:56:36 INFO - PROCESS | 16000 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 116.0 116.0 116.0 117.0 07:56:36 INFO - PROCESS | 16000 | 07:56:36 INFO - PROCESS | 16000 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 07:56:36 INFO - PROCESS | 16000 | Values: 146.0 147.0 146.0 147.0 147.0 147.0 147.0 147.0 146.0 146.0 07:56:36 INFO - PROCESS | 16000 | 07:56:36 INFO - PROCESS | 16000 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 07:56:36 INFO - PROCESS | 16000 | Values: 95.0 95.0 95.0 95.0 94.0 95.0 94.0 95.0 95.0 95.0 07:56:36 INFO - PROCESS | 16000 | 07:56:36 INFO - PROCESS | 16000 | [#8] json-parse-financial Cycles:10 Average:49.50 Median:49.50 stddev:0.71 (1.4%) stddev-sans-first:0.50 07:56:36 INFO - PROCESS | 16000 | Values: 51.0 49.0 49.0 49.0 49.0 50.0 49.0 50.0 50.0 49.0 07:56:36 INFO - PROCESS | 16000 | 07:56:36 INFO - PROCESS | 16000 | [#9] json-stringify-tinderbox Cycles:10 Average:59.00 Median:59.00 stddev:0.94 (1.6%) stddev-sans-first:0.93 07:56:36 INFO - PROCESS | 16000 | Values: 58.0 59.0 61.0 58.0 60.0 58.0 59.0 59.0 59.0 59.0 07:56:36 INFO - PROCESS | 16000 | 07:56:36 INFO - PROCESS | 16000 | [#10] stanford-crypto-aes Cycles:10 Average:76.00 Median:77.00 stddev:2.94 (3.8%) stddev-sans-first:2.18 07:56:36 INFO - PROCESS | 16000 | Values: 82.0 77.0 74.0 77.0 73.0 74.0 74.0 79.0 77.0 73.0 07:56:36 INFO - PROCESS | 16000 | 07:56:36 INFO - PROCESS | 16000 | [#11] stanford-crypto-ccm Cycles:10 Average:123.60 Median:123.00 stddev:10.54 (8.6%) stddev-sans-first:11.00 07:56:36 INFO - PROCESS | 16000 | Values: 129.0 120.0 118.0 123.0 123.0 115.0 116.0 117.0 151.0 124.0 07:56:36 INFO - PROCESS | 16000 | 07:56:36 INFO - PROCESS | 16000 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.00 Median:152.50 stddev:4.55 (3.0%) stddev-sans-first:4.81 07:56:36 INFO - PROCESS | 16000 | Values: 154.0 150.0 153.0 157.0 152.0 150.0 151.0 150.0 149.0 164.0 07:56:36 INFO - PROCESS | 16000 | 07:56:36 INFO - PROCESS | 16000 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.30 Median:61.50 stddev:0.67 (1.1%) stddev-sans-first:0.67 07:56:36 INFO - PROCESS | 16000 | Values: 62.0 61.0 62.0 62.0 60.0 62.0 61.0 61.0 61.0 61.0 07:56:36 INFO - PROCESS | 16000 | -------- Summary: end -------- 07:56:36 INFO - PROCESS | 16000 | 07:56:37 INFO - TEST-INFO | 16000: exit 0 07:56:37 INFO - TEST-OK | kraken | took 59466ms 07:56:37 INFO - SUITE-END | took 1172s 07:56:38 INFO - Completed test suite (00:19:33) 07:56:38 INFO - javascript benchmark 07:56:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3071.4317851197525, 3115.9561546881787, 3120.0951724000042, 3148.228683972427, 3151.7005884440982, 5484.067708489556, 5531.363893152288, 5539.196816461611, 5551.8889622207535, 5556.805511807088, 5616.971915140429, 5661.8584535386635, 5690.9146362804595, 5697.344805347387, 5702.914456283159, 5110.795568177277, 5130.692158470485, 5173.922391164136, 5188.533032027117, 5200.297959775438, 4926.064047830919, 5158.3552055993005, 5214.713190774506, 5217.139172036609, 5219.79120835168, 4915.655904086715, 4927.1377508935975, 4964.503549645025, 4967.403911530621, 5073.3404657394485, 5088.414832294278, 5116.999999999982, 5117.872053198663, 5186.662866913639, 5191.195364718475, 5030.044291584587, 5097.980403919194, 5146.948530514722, 5156.3296771419855, 5188.481151884781, 5326.866828329284, 5358.892822143535, 5474.671519708831, 5501.119820828648, 5520.641158324696, 5930.7034648267845, 5940.168376427304, 5956.37458066901, 6036.63780173191, 6040.607360521552, 6086.786962456293, 6094.8171554853425, 6096.359882212349, 6097.024476083805, 6106.725197366085, 5338.332708411449, 5367.355917289911, 5577.358603760579, 5618.578606604521, 5639.154126881001, 6132.816015519542, 6179.134921110958, 6180.567360284737, 6183.659898705614, 6195.535334849904, 5909.5863289569315, 5916.497097746654, 5917.970410147922, 5921.437463440966, 5923.289205295306, 5786.739596718157, 5844.795432159854, 5892.175095486636, 5957.731902064417, 5958.2850057992455, 5986.341502434729, 5994.670293133832, 5999.610025348338, 6003.459688628044, 6030.366811484819, 5943.078822782476, 5998.4601385875485, 6009.2188015557695, 6021.879562408815, 6022.1870047543025, 4486.820527178941, 4509.1658043262105, 4541.863744087683, 4546.545345465427, 4620.214563524209, 4050.432939388488, 4052.4326594276827, 4060.878173654795, 4060.9796951015055, 4097.795110244476, 5588.385277619459, 5592.105263157875, 5607.635503692329, 5611.046122159243, 5616.5787565932715, 5838.7080645967535, 5883.558733095035, 5901.498372638288, 5942.70286485674, 5946.07835785454, 5551.361593416808, 5662.518685911744, 5673.432656734293, 5692.0, 5697.145428185722, 5182.896342073137, 5185.299984502044, 5189.117849965515, 5199.66202196856, 5212.40057393397, 5827.125931110368, 5878.911816322673, 6146.938530614726, 6151.9384806151365, 6157.661328626879, 5859.267591551056, 5896.734646940812, 5909.468147866713, 5998.700064996646, 6043.486303664238, 6155.076738489262, 6155.784547540994, 6165.876682466415, 6167.938320616737, 6174.9073763892675, 5684.175794509691, 5869.4717475427415, 5878.676672783038, 5881.2942446906645, 5881.970590147193, 5766.192733017297, 5907.232059832366, 5915.349311575637, 5961.731722072431, 6045.335013148638, 5811.912821307768, 5851.473367396955, 5853.736581853912, 5857.47282744538, 5861.9706901465215, 5857.384974577779, 5858.912116318344, 5860.062390017577, 5860.765569377348, 5862.443067908542, 5507.284053073075, 5538.47384498472, 5641.40765219646, 5667.716614169358, 5676.77292908279, 5805.970970145122, 5812.418758124154, 5816.272965879265, 5821.185034095145, 5822.737976791139, 5543.334799823886, 5658.377578466448, 5660.85847853807, 5670.206171136127, 5673.205751194753], "value": 5486.079262622339, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [591.6331874237973, 613.9785107521237, 616.5776443136449, 640.107050664323, 648.9610623362597, 35286.29427411454, 38494.23011539771, 47242.76378618111, 47857.521424785744, 48389.27416088753, 47887.52112478874, 48244.27633585499, 48282.0, 48324.758376208156, 48357.75821120889, 9348.345615806897, 9494.240460763122, 9500.429974201536, 9503.429794212334, 9505.429674219535, 8425.1153628869, 8684.478931264113, 10854.565817367296, 11116.055135313467, 11151.721206969809, 11337.829932551018, 11433.199676022681, 11433.885661143366, 11438.313701177956, 11440.48517816696, 11204.887951120467, 11267.492962816652, 11269.154813389028, 11274.549018039268, 11287.887121128766, 11072.778544429148, 11106.500207490682, 11142.888571114347, 11147.331160130418, 11150.944245278723, 46231.0, 46537.76731116357, 46700.0, 46717.766411168064, 46822.76588617035, 3856.730028897978, 3912.6256616061874, 3950.525936887563, 4012.8996775080564, 4035.8587449439424, 35117.0, 35382.0, 35417.645823541694, 35891.28217435637, 36395.09012274688, 4318.719283246611, 4323.481182258118, 4338.02286456194, 4352.89117772055, 4368.257396242647, 261159.0, 261298.0, 261778.0, 262808.0, 263147.0, 65903.67048164824, 66123.0, 66180.0, 66283.66858165678, 66319.0, 40320.193596127916, 41570.0, 41643.167136657095, 70344.0, 70722.64638676774, 20464.079116439796, 20725.378238652866, 20733.481662958246, 20734.79265207359, 20747.896260518603, 14470.927645361917, 15878.52364429069, 16080.195990200444, 16086.35654573804, 16094.275757590936, 18592.721109183374, 20768.48078798042, 21393.89303053475, 21656.35030949074, 22263.0, 7423.552407280528, 7475.887861682079, 7533.585652789039, 7541.849163016818, 7588.0, 1040.1730624153815, 1041.7239431550645, 1043.9791204175874, 1062.665260442958, 1099.8955099265556, 34848.4999055923, 35211.64788352135, 35216.11959700977, 35308.29383412369, 35351.4697279541, 1182.6097387861985, 1214.799558072908, 1243.30996297056, 1243.6455610151063, 1254.6863284178955, 17792.0, 18602.441926741947, 18622.441326760218, 18633.440996770118, 18684.719729204073, 552783.0, 553771.0, 555227.0, 555875.0, 556208.0, 63917.36082639207, 64328.35671643224, 64343.67828160923, 64599.35400646027, 64601.676991615685, 1322.1273959187083, 1353.553327401955, 1385.210430054859, 1442.7042456296224, 1458.1761304862719, 33555.83222083874, 36005.63994360023, 36116.63883361133, 36191.0, 36484.45273320849, 56102.158467623834, 56735.14897276627, 56785.0, 56832.43167568437, 56914.430855690916, 399795.0, 403489.0, 403899.0, 404741.0, 405426.0, 30681.846590767796, 32240.516392254605, 32292.0, 33439.331213376085, 33511.16222094467, 49955.250671239235, 50313.49686503088, 50385.244221335975, 50487.24269136039, 51579.0, 41295.0, 41357.1728565421, 41813.37279940742, 42075.0, 43198.352024719024, 42516.787416062725, 42742.14515709606, 43303.566964329955, 44161.33757993697, 44821.55178448174, 42372.1525569493, 42611.0, 43370.13259734851, 43977.34033989429, 43984.34023489714, 35982.8200858994, 38312.23375532418, 38659.0, 39037.804810975766, 39402.40896386614, 28403.573946390406, 28824.802737238988, 28973.42053158883, 29333.7066629331, 29563.0], "value": 21639.536463824505, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.9768413895165, 402.4546739168427, 413.35516594113176, 414.88175869877085, 425.9148170365926, 14450.566483005501, 16299.837001629994, 20569.794302056976, 21103.788962110375, 21228.257011004578, 19782.703259451082, 20033.899830500864, 20118.295859644917, 20129.798702013013, 20390.0, 5923.970380148104, 5955.0, 6123.357047510014, 6164.660943648098, 6191.690415479208, 4302.763348015857, 4581.67928245023, 6065.423784740465, 6155.19982402287, 6158.261008678943, 6443.452306553949, 6689.063531105649, 6718.025886246492, 6829.93170068298, 6840.589564626139, 6592.703328350236, 6786.219584747743, 6790.7623233186605, 6823.1812182537915, 6833.043373927604, 5188.351456067991, 6036.0945858121195, 6050.45545900871, 6052.969735151296, 6054.485368743662, 13268.0, 14043.1574105554, 14051.50819721315, 14052.648683782885, 17567.5608109797, 2364.8108151348024, 2373.8457000294925, 2471.2251219777713, 2787.8048536602446, 3001.669816320203, 10039.949800251024, 10104.98950104991, 10301.22740794436, 10404.167666586654, 10788.352698838016, 1976.6837306030966, 2106.694529293259, 2221.7778222177812, 2284.463151159476, 2419.1532963462823, 82333.0, 84181.4934328038, 104506.47746761293, 108139.45930270298, 109560.4521977393, 17771.82228177702, 17809.910950445166, 17837.554061148316, 18287.177077031567, 18761.437156885317, 34077.65922340745, 34229.828850855585, 34305.14237144091, 34409.82795086008, 34484.0, 11290.435478226056, 11294.37880916541, 11379.089672826154, 11409.60066397672, 11543.711407214887, 9546.90453095474, 9564.95217523922, 9690.0, 9757.0, 9820.116189542976, 8893.199612034949, 9254.305927055499, 9354.204892584206, 9354.485503297248, 9562.28282878773, 3162.857671404792, 3251.414745345813, 3311.1391038330207, 3320.5849268841393, 3605.2685470537867, 983.306768728045, 1009.8283291840405, 1034.9896501034893, 1053.6431854282596, 1056.3292309383596, 19638.70541941872, 19747.248022875607, 19951.700724489143, 20051.298204563063, 20636.0, 1022.3610243597751, 1026.8665073540542, 1034.5086084109987, 1044.6448207609449, 1062.2404980439023, 10037.849432258521, 11011.724706882393, 11051.17116216287, 11074.169437292074, 11127.165462590337, 505387.0, 510772.0, 511325.0, 520783.0, 525167.0, 75777.62111189519, 78119.0, 100250.0, 101077.0, 102472.48763756282, 974.8732664753537, 1027.2603725317908, 1063.0804354233687, 1081.4376524207369, 1083.0793825248477, 17736.733948990517, 17753.289868405118, 18197.909010455394, 18298.817011829713, 18523.0738463079, 43571.34642980294, 44033.55966440294, 44533.0, 45039.54960450353, 45165.322520161564, 63482.68258658677, 63895.36104638894, 64252.678736606016, 65019.67490162519, 65136.674316628116, 8624.698135565475, 8740.34447416459, 8755.168259015383, 8850.380473366802, 8856.804331415176, 12493.75012499763, 13341.332933353488, 13383.665408364868, 13597.388117534932, 13837.930810345884, 11483.885161148615, 11506.769864602493, 11718.472668730099, 11778.352190629597, 12233.204841685261, 11444.542218311508, 11516.942415288206, 11627.941860290644, 11985.34080625574, 12212.938935305267, 9443.622255109718, 11830.0, 11915.52337906474, 11949.940250299042, 12174.878251217375, 8543.8718419225, 8675.696350627697, 8821.823563528566, 8849.91150088491, 9079.228265597498, 11513.251638643462, 11736.647900562997, 11812.232204906997, 11870.228435151688, 12174.75650487003], "value": 10286.6296557037, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1501.7221813964418, 1523.2079318754247, 1640.4340502526625, 1722.8284766358886, 1754.5438186071617, 7787.844243115142, 8319.792005199874, 8320.75037748867, 8325.29235015023, 8327.58362081895, 7186.065811444518, 7399.704011839533, 7825.647845846936, 8080.1919808019165, 8111.63497642606, 2334.2647066174145, 2362.137819695816, 2364.125273648752, 2365.6333268343433, 2369.4668699542553, 2369.9289021329387, 2373.52529494101, 2397.1250493569896, 2397.19693902542, 2404.915827946022, 2352.4236562042324, 2357.4813541021035, 2357.9056837726557, 2396.532676128156, 2403.591389463795, 2276.8178545716305, 2302.090674183705, 2308.68832707584, 2309.549637820626, 2317.7798109179603, 2254.3913143451177, 2258.0854753824565, 2261.2650888461317, 2264.4112530742127, 2265.614845476273, 8205.630746616356, 8239.258466738022, 8311.625976831057, 8314.625841837133, 8317.750467485925, 4897.3878265216845, 4899.8040078396825, 5012.147934851084, 5045.798168073273, 5054.039732450822, 3230.4669729494603, 3270.345930813823, 3316.6849149330774, 3361.445361515347, 3377.1894745261206, 1131.1516362727955, 1142.0977427832004, 2125.2667829598763, 2142.528643698384, 2147.1733382647635, 11015.0, 11075.667729968114, 11078.113750899909, 11078.889211107948, 11095.11239100878, 4206.11671548972, 4291.592298731615, 4418.337249412614, 4422.557744225552, 4430.069685366045, 8863.512506812187, 8873.778155546162, 8895.866562001574, 8900.821983560292, 8905.866412003825, 5039.5968322534845, 5039.82360617384, 5044.344235249468, 5044.899102017939, 5045.217991211368, 4099.61053699898, 4211.389348544444, 4523.886902827456, 4686.74222917736, 4774.236122220497, 3604.2611264690677, 3659.2864391443413, 3717.553893532793, 3734.327820992193, 3747.662710356081, 1126.5381193710539, 1143.4054291768234, 1143.634037108134, 1150.5282834038005, 1152.9942350288363, 903.6295119001178, 953.1802649721234, 969.6353652824687, 1050.968505613917, 1065.0733861540355, 5757.366689664133, 5773.740181691834, 5938.732757025944, 5950.404959504015, 5955.52355811534, 443.22214513529195, 443.3615593545284, 443.50327633050546, 444.08961628661115, 447.8387780399054, 4088.5707000765087, 4213.747175169438, 4302.849400270976, 4308.913821723548, 4325.718828276152, 9575.04249575037, 9826.263030272758, 9843.803123937481, 9895.901040989642, 9911.752206194902, 9789.559469823875, 9796.51017449139, 9799.706008819747, 9803.264755143251, 9805.166560842408, 7322.743703970335, 7602.46782725204, 7620.161782203953, 7759.456838021284, 7759.844803104019, 5841.18223448726, 5862.384449632727, 5960.523158147251, 6070.9089363658695, 6084.239470066242, 9238.907610923805, 9288.907110928803, 9415.058494150804, 9429.292803039662, 9432.434053956784, 9336.533173341442, 9357.672481463389, 9441.244700424088, 9441.763955901157, 9472.810543788948, 6765.627890466071, 7003.54476958996, 7122.679479423335, 7203.603801790951, 7299.5255308404785, 7897.131315555399, 7904.328132108835, 8080.474769139987, 8092.554909480035, 8101.068377136469, 7218.0, 7387.150477695134, 7399.297066778648, 7447.143578488326, 7488.101427828696, 7113.679884405111, 7198.280171982759, 7287.41700663935, 7313.378362839218, 7569.0, 6999.615021173884, 7009.474289428415, 7030.824229394306, 7030.964845175741, 7032.894506582507, 5168.656457097441, 5343.091674415281, 5350.678959262456, 5351.357837059578, 5576.1356989666665, 5057.418396884405, 5244.134717771678, 5261.73691315425, 5269.868253293698, 5335.359756829205], "value": 4423.46903230291, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.31173846660477, 337.5662273977938, 337.783818356252, 344.77245018287937, 345.877213589176, 5666.631668941521, 5672.659640421567, 5683.300432294658, 5695.829125126248, 5718.513926316257, 5599.496045355917, 5607.495325420711, 5650.5479561635075, 5672.659640421578, 5675.5459563234945, 4096.098858251187, 4175.102352994106, 4231.97884010581, 4238.258304796664, 4299.957000429987, 4291.334843099309, 4342.739435633856, 4357.215701173787, 4373.518912919576, 4388.144311859189, 4262.976885547478, 4276.144771045788, 4299.935500967488, 4302.892427689301, 4316.8991907063655, 4208.831646734127, 4285.9785701071605, 4316.093620339732, 4319.308910574325, 4331.956680433218, 3783.5459744830796, 3791.146991926822, 3806.3148633246005, 3807.58116607173, 3807.7144214184045, 5411.945880541183, 5431.34823821144, 5433.673979561239, 5441.714479918443, 5457.372402173722, 4845.466998630148, 4910.140725373046, 4958.231474121517, 4978.352814134176, 4987.401511818569, 5778.6243894147165, 5798.855028624276, 5799.4780469757925, 5804.912926306109, 5806.44838740319, 4220.36694495825, 4235.0682849773, 4252.723572967747, 4258.936115958263, 4263.91472170558, 5859.472647461749, 6058.818235452944, 6090.604110732744, 6096.237970253718, 6134.631922084689, 4231.492220933507, 4270.42349282849, 4274.230638485103, 4280.379344994958, 4294.573565935237, 4347.282698354799, 4352.434183556117, 4355.5208927018, 4360.280553708666, 4362.280223763107, 4814.927776083362, 4891.0, 4947.282644016597, 4960.751962401865, 5014.724190169576, 5817.545613596601, 5820.351739958309, 5829.912551311733, 5859.589828711949, 5874.676892770939, 1479.667074908137, 1491.8806495480335, 1493.9626509337354, 1496.2369191712087, 1507.1333982959843, 928.1878356438117, 958.5878072428923, 958.8993155718689, 964.0793042644258, 1005.7535903703493, 5493.423190564933, 5634.464725851038, 5666.603337766399, 5734.283214598176, 5735.426457354315, 6069.211002569726, 6076.513878889678, 6089.695515224221, 6105.733432613619, 6121.755129794758, 5578.219049333096, 5633.8591535211945, 5678.4037676044245, 5707.857303567444, 5723.198752174699, 3191.8723251069696, 3205.6794320568224, 3206.887758928426, 3212.3093534889645, 3215.7266632336045, 6017.909731354033, 6211.192544969215, 6223.3465486123305, 6255.562110652301, 6259.812205633838, 6133.325334213233, 6135.202423684892, 6156.26124865019, 6156.5998210116195, 6158.322584515699, 6174.351693072343, 6179.014535624497, 6183.598066125777, 6195.504359651307, 6201.472874805602, 5726.0, 5950.256217972754, 5974.312954010345, 5988.431099045583, 5994.28068631767, 5893.557983151195, 5901.704914754331, 5919.911201331897, 5921.822345329647, 5978.0, 5929.436703513159, 6148.139260503443, 6183.196184495987, 6220.782272620437, 6232.127502149793, 4235.407043014042, 4243.787810609519, 4273.807678654406, 4278.764667943293, 4279.529251782239, 6063.6664983426335, 6064.696765161812, 6093.146959425595, 6101.383133469722, 6106.961816491118, 5782.797602083907, 5812.73842677089, 5813.215215945962, 5841.0, 5882.499987500939, 5798.0, 5820.621659592282, 5820.970895145667, 5825.970870145622, 5867.125108696622, 5614.747336369792, 5779.104238842986, 5814.30228372598, 5835.824925252249, 5846.889091072682, 5867.501262392744, 5888.764449422147, 5893.764249430146, 5924.229850119457, 5952.791652292149, 5798.275215598051, 5814.017398082327, 5821.82534523948, 5822.7670893163795, 5841.4450627190345], "value": 4460.607956919068, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [827.9130691277416, 836.9121242269562, 854.4275335525198, 869.647792643979, 900.6307413960278, 16727.498175054752, 17754.82245177548, 17755.378561750324, 17757.46727598174, 17759.91120044401, 12830.422630981604, 16435.506934791974, 16473.093979831105, 16605.169741512906, 16620.085895275755, 700.2157583506468, 845.45045720282, 871.5555066915887, 874.5714599846061, 876.1326286975911, 863.6933888469597, 871.4422769427572, 871.7646235516406, 873.2926329672954, 873.3319010956624, 910.1217325281102, 912.3066469483207, 914.2320450821309, 918.5361392496814, 918.8713580098791, 747.7457664394105, 766.1113108793801, 890.461270931087, 897.0043251990286, 900.2932697832235, 661.5732852310281, 661.8444665503603, 662.076403417235, 662.0995446193174, 662.2483481248769, 15580.220988950621, 15636.2181890905, 15638.609034774108, 15646.608834779108, 15648.217589120612, 15380.846191538165, 15438.9228053859, 15439.536813895602, 15469.226538673021, 15602.0, 14162.645933851632, 14167.149971001772, 14487.130772153705, 14679.706405871822, 15003.624909377353, 4240.724352917051, 4286.249906266391, 4343.891402714926, 4436.55634436557, 4445.755483448409, 85069.0, 85515.0, 87516.0, 90325.0, 90785.54607226922, 12409.75180496385, 12472.314022728655, 12510.874891251153, 12593.559225427067, 12724.236545807282, 23054.308370748568, 23085.884570577375, 23153.652695209585, 23209.651855222186, 23221.41946451352, 9085.727428177166, 9209.907900921038, 9313.48775817323, 9365.578548965177, 9451.858222126673, 5083.085044691917, 5134.614903882149, 5189.40321862983, 5305.07161246788, 5324.733763311819, 3494.4408894576745, 3615.5661320641693, 3621.94567081494, 3624.8187590620364, 3625.510556074896, 539.9757010934518, 566.6515093217641, 567.5487987050304, 581.2414798687669, 585.8798946216016, 679.67035987546, 714.4855703893188, 728.2717282717283, 799.3205775091242, 817.8159914019298, 10505.632302869362, 10818.188635852342, 11214.327140371604, 11459.942700286445, 11718.296902185895, 688.5421194905432, 760.9429292803062, 772.9420293478012, 774.2683164409578, 790.9644066017044, 4719.5752382285755, 5104.464031276737, 5135.383753949475, 5144.922826157611, 5167.767450464663, 71952.0, 74892.0, 128408.0, 135623.32188338996, 136250.0, 107195.0, 108712.45643771731, 110064.4496777527, 111552.98011589498, 114203.0, 61540.69229653823, 81545.0, 82947.5852620733, 91267.54366228127, 95023.0, 10667.626633067805, 10703.608530890935, 10820.621278255223, 11159.0, 11446.0, 54070.459295406545, 54665.72667136639, 55793.44206557883, 55980.72009639926, 56814.147787784044, 74966.62516687381, 78661.60669196617, 78733.60633197027, 81120.59439702764, 82009.0, 13919.652008699864, 13968.860311396757, 14485.92757036208, 14575.562733118024, 14594.416223350947, 16507.174641268128, 16832.57918551997, 16833.0, 17644.470665880042, 17888.642227155124, 14253.50112746049, 14539.85460145385, 14910.925445372704, 14940.0, 16434.506964791075, 15532.844671553139, 15621.297041633381, 16481.423150189687, 16541.338346466488, 16885.32458701638, 14280.857191428368, 15266.542003739905, 15611.0, 16344.754828677342, 16363.100029498491, 12638.431270592662, 12988.870111299144, 13427.19436833793, 13433.261170635318, 14089.718205636034, 11466.775931163314, 12292.81560776607, 12744.808827867404, 13029.739405212033, 13376.729210724965], "value": 8206.128984610898, "unit": "score"}], "name": "dromaeo_css", "value": 7632.869540863453, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 110.0, 110.0, 112.0, 110.0, 110.0, 110.0, 110.0, 110.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 122.0, 118.0, 157.0, 119.0, 118.0, 116.0, 123.0, 153.0, 117.0], "value": 129.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 162.0, 159.0, 161.0, 160.0, 162.0, 161.0, 161.0, 160.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 110.0, 73.0, 75.0, 76.0, 75.0, 106.0, 73.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 125.0, 83.0, 83.0, 83.0, 101.0, 82.0, 82.0, 109.0], "value": 91.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 116.0, 116.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 147.0, 147.0, 147.0, 147.0, 147.0, 146.0, 146.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 49.0, 49.0, 49.0, 50.0, 49.0, 50.0, 50.0, 49.0], "value": 49.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 61.0, 58.0, 60.0, 58.0, 59.0, 59.0, 59.0, 59.0], "value": 59.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 77.0, 74.0, 77.0, 73.0, 74.0, 74.0, 79.0, 77.0, 73.0], "value": 76.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 120.0, 118.0, 123.0, 123.0, 115.0, 116.0, 117.0, 151.0, 124.0], "value": 123.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 150.0, 153.0, 157.0, 152.0, 150.0, 151.0, 150.0, 149.0, 164.0], "value": 153.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 62.0, 62.0, 60.0, 62.0, 61.0, 61.0, 61.0, 61.0], "value": 61.3, "unit": "score"}], "name": "kraken", "value": 1453.3, "alertThreshold": 2.0}]} 07:56:38 INFO - Return code: 0 07:56:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:56:38 INFO - # TBPL SUCCESS # 07:56:38 INFO - Running post-action listener: _resource_record_post_action 07:56:38 INFO - [mozharness: 2016-09-30 14:56:38.307853Z] Finished run-tests step (success) 07:56:38 INFO - Running post-run listener: _resource_record_post_run 07:56:38 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 1998848; Write bytes: 375021568; Read time: 60; Write time: 259680 07:56:38 INFO - TinderboxPrint: CPU usage
12.9% 07:56:38 INFO - TinderboxPrint: I/O read bytes / time
1,998,848 / 60 07:56:38 INFO - TinderboxPrint: I/O write bytes / time
375,021,568 / 259,680 07:56:38 INFO - TinderboxPrint: CPU idle
8,268.2 (87.1%) 07:56:38 INFO - TinderboxPrint: CPU user
1,184.9 (12.5%) 07:56:38 INFO - TinderboxPrint: Swap in / out
0 / 0 07:56:38 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 108187648; Read time: 0; Write time: 86356 07:56:38 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 1998848; Write bytes: 250011648; Read time: 60; Write time: 168200 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:39 INFO - (blobuploader) - INFO - Open directory for files ... 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/dromaeojs_raw.log ... 07:56:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:56:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:56:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:56:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:56:42 INFO - (blobuploader) - INFO - Done attempting. 07:56:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:56:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:56:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:56:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:56:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:56:50 INFO - (blobuploader) - INFO - Done attempting. 07:56:50 INFO - (blobuploader) - INFO - Iteration through files over. 07:56:50 INFO - Return code: 0 07:56:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:56:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:56:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/785424506ea35f276933b23a49efd385be78a3148451f8b0957e3a7255ae6385c10dd1ddfccecd0809a7a0dc734f0e4327aed9b29db7984c5f72cf4d84475885", "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/7095c829fd77642fdbb1112de97f98873df8a2a8394e66d650b10c818e9219f58c5899d837031197acb880c2a78aa6d1673863f4df3e2df673efbdeb127ba993"} 07:56:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:56:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:56:50 INFO - Contents: 07:56:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/785424506ea35f276933b23a49efd385be78a3148451f8b0957e3a7255ae6385c10dd1ddfccecd0809a7a0dc734f0e4327aed9b29db7984c5f72cf4d84475885", "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/7095c829fd77642fdbb1112de97f98873df8a2a8394e66d650b10c818e9219f58c5899d837031197acb880c2a78aa6d1673863f4df3e2df673efbdeb127ba993"} 07:56:50 INFO - Running post-run listener: copy_logs_to_upload_dir 07:56:50 INFO - Copying logs to upload dir... 07:56:50 INFO - mkdir: /builds/slave/test/build/upload/logs 07:56:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.973364 ========= master_lag: 0.41 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-09-30 07:56:50.366571) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-30 07:56:50.377103) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1475244157.745591-1472413597 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/785424506ea35f276933b23a49efd385be78a3148451f8b0957e3a7255ae6385c10dd1ddfccecd0809a7a0dc734f0e4327aed9b29db7984c5f72cf4d84475885", "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/7095c829fd77642fdbb1112de97f98873df8a2a8394e66d650b10c818e9219f58c5899d837031197acb880c2a78aa6d1673863f4df3e2df673efbdeb127ba993"} 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.011346 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/785424506ea35f276933b23a49efd385be78a3148451f8b0957e3a7255ae6385c10dd1ddfccecd0809a7a0dc734f0e4327aed9b29db7984c5f72cf4d84475885", "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/7095c829fd77642fdbb1112de97f98873df8a2a8394e66d650b10c818e9219f58c5899d837031197acb880c2a78aa6d1673863f4df3e2df673efbdeb127ba993"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-30 07:56:50.418055) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 07:56:50.418346) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=62c568580acbfdaac3eec7be000001dc-1475244157.745591-1472413597 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004528 ========= master_lag: 0.90 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-30 07:56:51.322072) ========= ========= Started reboot skipped (results: 3, elapsed: 1 secs) (at 2016-09-30 07:56:51.322370) ========= ========= Finished reboot skipped (results: 3, elapsed: 1 secs) (at 2016-09-30 07:56:52.993006) ========= ========= Total master_lag: 1.40 =========