builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-029 starttime: 1478883933.31 results: success (0) buildid: 20161111062220 builduid: 9ead417dada74c53bdb7920e9ee6f726 revision: 7a7e256d58b69f0fdf92cead9ff17aab7a38e130 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-11 09:05:33.307929) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-11 09:05:33.308366) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-11 09:05:33.308632) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-11 09:05:33.323032) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 09:05:33.323289) ========= 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=c7c495d6f76fb52e1ad9f659000001da-1478883932.753165-1788267099 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-11 09:05:33-- 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: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 100M=0s 2016-11-11 09:05:33 (100 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.074274 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 09:05:33.411160) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 09:05:33.411462) ========= 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=c7c495d6f76fb52e1ad9f659000001da-1478883932.753165-1788267099 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015802 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 09:05:33.460875) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 09:05:33.461214) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7a7e256d58b69f0fdf92cead9ff17aab7a38e130 --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 7a7e256d58b69f0fdf92cead9ff17aab7a38e130 --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=c7c495d6f76fb52e1ad9f659000001da-1478883932.753165-1788267099 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-11 09:05:33,739 truncating revision to first 12 chars 2016-11-11 09:05:33,740 Setting DEBUG logging. 2016-11-11 09:05:33,740 attempt 1/10 2016-11-11 09:05:33,740 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/7a7e256d58b6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-11 09:05:34,059 unpacking tar archive at: mozilla-aurora-7a7e256d58b6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.718367 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 09:05:34.192798) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-11 09:05:34.193123) ========= script_repo_revision: 7a7e256d58b69f0fdf92cead9ff17aab7a38e130 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-11 09:05:34.193492) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-11 09:05:34.193765) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-11 09:05:34.204062) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-11-11 09:05:34.204324) ========= /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=c7c495d6f76fb52e1ad9f659000001da-1478883932.753165-1788267099 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:05:34 INFO - MultiFileLogger online at 20161111 09:05:34 in /builds/slave/test 09:05:34 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 09:05:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:05:34 INFO - {'append_to_log': False, 09:05:34 INFO - 'base_work_dir': '/builds/slave/test', 09:05:34 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:05:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:05:34 INFO - 'branch': 'Mozilla-Aurora', 09:05:34 INFO - 'buildbot_json_path': 'buildprops.json', 09:05:34 INFO - 'config_files': ('talos/linux_config.py',), 09:05:34 INFO - 'default_actions': ('clobber', 09:05:34 INFO - 'read-buildbot-config', 09:05:34 INFO - 'download-and-extract', 09:05:34 INFO - 'populate-webroot', 09:05:34 INFO - 'create-virtualenv', 09:05:34 INFO - 'install', 09:05:34 INFO - 'run-tests'), 09:05:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:05:34 INFO - 'download_minidump_stackwalk': True, 09:05:34 INFO - 'download_symbols': 'ondemand', 09:05:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:05:34 INFO - 'tooltool.py': '/tools/tooltool.py', 09:05:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:05:34 INFO - '/tools/misc-python/virtualenv.py')}, 09:05:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:05:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:05:34 INFO - 'installer_path': 'installer.exe', 09:05:34 INFO - 'log_level': 'info', 09:05:34 INFO - 'log_name': 'talos', 09:05:34 INFO - 'log_to_console': True, 09:05:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:05:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:05:34 INFO - 'opt_config_files': (), 09:05:34 INFO - 'pip_index': False, 09:05:34 INFO - 'sps_profile': False, 09:05:34 INFO - 'sps_profile_interval': 0, 09:05:34 INFO - 'suite': 'dromaeojs', 09:05:34 INFO - 'system_bits': '32', 09:05:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:05:34 INFO - 'title': 'talos-linux64-ix-029', 09:05:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:05:34 INFO - 'use_talos_json': True, 09:05:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:05:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:05:34 INFO - 'work_dir': 'build'} 09:05:34 INFO - [mozharness: 2016-11-11 17:05:34.581803Z] Running clobber step. 09:05:34 INFO - Running pre-action listener: _resource_record_pre_action 09:05:34 INFO - Running main action method: clobber 09:05:34 INFO - rmtree: /builds/slave/test/build 09:05:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:05:35 INFO - Running post-action listener: _resource_record_post_action 09:05:35 INFO - [mozharness: 2016-11-11 17:05:35.072866Z] Finished clobber step (success) 09:05:35 INFO - [mozharness: 2016-11-11 17:05:35.072968Z] Running read-buildbot-config step. 09:05:35 INFO - Running pre-action listener: _resource_record_pre_action 09:05:35 INFO - Running main action method: read_buildbot_config 09:05:35 INFO - Using buildbot properties: 09:05:35 INFO - { 09:05:35 INFO - "project": "", 09:05:35 INFO - "product": "firefox", 09:05:35 INFO - "script_repo_revision": "production", 09:05:35 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:05:35 INFO - "repository": "", 09:05:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:05:35 INFO - "buildid": "20161111062220", 09:05:35 INFO - "pgo_build": "True", 09:05:35 INFO - "basedir": "/builds/slave/test", 09:05:35 INFO - "buildnumber": 614, 09:05:35 INFO - "slavename": "talos-linux64-ix-029", 09:05:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:05:35 INFO - "platform": "ubuntu64_hw", 09:05:35 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:05:35 INFO - "branch": "mozilla-aurora", 09:05:35 INFO - "repo_path": "releases/mozilla-aurora", 09:05:35 INFO - "revision": "7a7e256d58b69f0fdf92cead9ff17aab7a38e130", 09:05:35 INFO - "stage_platform": "linux64-pgo", 09:05:35 INFO - "builduid": "9ead417dada74c53bdb7920e9ee6f726", 09:05:35 INFO - "slavebuilddir": "test" 09:05:35 INFO - } 09:05:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/EM1W6ERWS_mJhpE3BbKGeg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 09:05:35 INFO - Running post-action listener: _resource_record_post_action 09:05:35 INFO - [mozharness: 2016-11-11 17:05:35.075401Z] Finished read-buildbot-config step (success) 09:05:35 INFO - [mozharness: 2016-11-11 17:05:35.075488Z] Running download-and-extract step. 09:05:35 INFO - Running pre-action listener: _resource_record_pre_action 09:05:35 INFO - Running main action method: download_and_extract 09:05:35 INFO - mkdir: /builds/slave/test/build/tests 09:05:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:35 INFO - trying https://queue.taskcluster.net/v1/task/EM1W6ERWS_mJhpE3BbKGeg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 09:05:35 INFO - Downloading https://queue.taskcluster.net/v1/task/EM1W6ERWS_mJhpE3BbKGeg/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 09:05:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EM1W6ERWS_mJhpE3BbKGeg/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 09:05:35 INFO - Downloaded 1361 bytes. 09:05:35 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 09:05:35 INFO - Using the following test package requirements: 09:05:35 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 09:05:35 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:05:35 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:05:35 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:05:35 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 09:05:35 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:05:35 INFO - u'jsshell-linux-x86_64.zip'], 09:05:35 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:05:35 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:05:35 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 09:05:35 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:05:35 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:05:35 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:05:35 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:05:35 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:05:35 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:05:35 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:05:35 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:05:35 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:05:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EM1W6ERWS_mJhpE3BbKGeg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 09:05:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EM1W6ERWS_mJhpE3BbKGeg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:05:35 INFO - Fetch https://queue.taskcluster.net/v1/task/EM1W6ERWS_mJhpE3BbKGeg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 09:05:36 INFO - Expected file size: 21962050 09:05:40 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 09:05:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EM1W6ERWS_mJhpE3BbKGeg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 09:05:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EM1W6ERWS_mJhpE3BbKGeg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:05:40 INFO - Fetch https://queue.taskcluster.net/v1/task/EM1W6ERWS_mJhpE3BbKGeg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 09:05:41 INFO - Expected file size: 21962050 09:05:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EM1W6ERWS_mJhpE3BbKGeg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 09:05:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EM1W6ERWS_mJhpE3BbKGeg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:05:45 INFO - Fetch https://queue.taskcluster.net/v1/task/EM1W6ERWS_mJhpE3BbKGeg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 09:05:46 INFO - Expected file size: 14414089 09:05:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:46 INFO - trying https://queue.taskcluster.net/v1/task/EM1W6ERWS_mJhpE3BbKGeg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:05:46 INFO - Downloading https://queue.taskcluster.net/v1/task/EM1W6ERWS_mJhpE3BbKGeg/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 09:05:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EM1W6ERWS_mJhpE3BbKGeg/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 09:05:48 INFO - Downloaded 64051396 bytes. 09:05:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EM1W6ERWS_mJhpE3BbKGeg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:05:48 INFO - mkdir: /builds/slave/test/properties 09:05:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:05:48 INFO - Writing to file /builds/slave/test/properties/build_url 09:05:48 INFO - Contents: 09:05:48 INFO - build_url:https://queue.taskcluster.net/v1/task/EM1W6ERWS_mJhpE3BbKGeg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:05:48 INFO - Running post-action listener: _resource_record_post_action 09:05:48 INFO - Running post-action listener: set_extra_try_arguments 09:05:48 INFO - [mozharness: 2016-11-11 17:05:48.910432Z] Finished download-and-extract step (success) 09:05:48 INFO - [mozharness: 2016-11-11 17:05:48.910659Z] Running populate-webroot step. 09:05:48 INFO - Running pre-action listener: _resource_record_pre_action 09:05:48 INFO - Running main action method: populate_webroot 09:05:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:05:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:05:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:05:48 INFO - '--tppagecycles', 09:05:48 INFO - '7'], 09:05:48 INFO - 'tests': ['tsvgm']}}, 09:05:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:05:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:05:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:05:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:05:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:05:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:05:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:05:48 INFO - 'talos_options': ['--disable-e10s'], 09:05:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:05:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:05:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:05:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:05:48 INFO - 'talos_options': ['--disable-e10s'], 09:05:48 INFO - 'tests': ['damp', 'tps']}, 09:05:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:05:48 INFO - 'tests': ['damp', 'tps']}, 09:05:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:05:48 INFO - 'tests': ['dromaeo_dom']}, 09:05:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:05:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:05:48 INFO - 'tests': ['basic_compositor_video']}, 09:05:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:05:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:05:48 INFO - 'tests': ['a11yr', 09:05:48 INFO - 'ts_paint', 09:05:48 INFO - 'tpaint', 09:05:48 INFO - 'sessionrestore', 09:05:48 INFO - 'sessionrestore_no_auto_restore', 09:05:48 INFO - 'tabpaint']}, 09:05:48 INFO - 'other-e10s': {'tests': ['a11yr', 09:05:48 INFO - 'ts_paint', 09:05:48 INFO - 'tpaint', 09:05:48 INFO - 'sessionrestore', 09:05:48 INFO - 'sessionrestore_no_auto_restore', 09:05:48 INFO - 'tabpaint']}, 09:05:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:05:48 INFO - 'ts_paint', 09:05:48 INFO - 'tpaint', 09:05:48 INFO - 'sessionrestore', 09:05:48 INFO - 'sessionrestore_no_auto_restore', 09:05:48 INFO - 'tabpaint']}, 09:05:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:05:48 INFO - 'ts_paint', 09:05:48 INFO - 'tpaint', 09:05:48 INFO - 'sessionrestore', 09:05:48 INFO - 'sessionrestore_no_auto_restore', 09:05:48 INFO - 'tabpaint']}, 09:05:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:05:48 INFO - 'tests': ['a11yr', 09:05:48 INFO - 'ts_paint', 09:05:48 INFO - 'tpaint', 09:05:48 INFO - 'sessionrestore', 09:05:48 INFO - 'sessionrestore_no_auto_restore', 09:05:48 INFO - 'tabpaint']}, 09:05:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:05:48 INFO - 'tests': ['a11yr', 09:05:48 INFO - 'ts_paint', 09:05:48 INFO - 'tpaint', 09:05:48 INFO - 'sessionrestore', 09:05:48 INFO - 'sessionrestore_no_auto_restore', 09:05:48 INFO - 'tabpaint']}, 09:05:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:05:48 INFO - 'tests': ['tsvgx', 09:05:48 INFO - 'tsvgr_opacity', 09:05:48 INFO - 'tart', 09:05:48 INFO - 'tscrollx', 09:05:48 INFO - 'cart']}, 09:05:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:05:48 INFO - 'tsvgr_opacity', 09:05:48 INFO - 'tart', 09:05:48 INFO - 'tscrollx', 09:05:48 INFO - 'cart']}, 09:05:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:05:48 INFO - 'talos_options': ['--disable-e10s'], 09:05:48 INFO - 'tests': ['tp5o']}, 09:05:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:05:48 INFO - 'tests': ['tp5o']}, 09:05:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:05:48 INFO - 'talos_options': ['--disable-e10s', 09:05:48 INFO - '--xperf_path', 09:05:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:05:48 INFO - 'tests': ['tp5n']}, 09:05:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:05:48 INFO - 'talos_options': ['--xperf_path', 09:05:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:05:48 INFO - 'tests': ['tp5n']}}, 09:05:48 INFO - 'talos.zip': {'path': '', 09:05:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:05:48 INFO - Running post-action listener: _resource_record_post_action 09:05:48 INFO - [mozharness: 2016-11-11 17:05:48.929761Z] Finished populate-webroot step (success) 09:05:48 INFO - [mozharness: 2016-11-11 17:05:48.929850Z] Running create-virtualenv step. 09:05:48 INFO - Running pre-action listener: _resource_record_pre_action 09:05:48 INFO - Running main action method: create_virtualenv 09:05:48 INFO - Creating virtualenv /builds/slave/test/build/venv 09:05:48 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:05:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:05:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:05:49 INFO - Using real prefix '/usr' 09:05:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:05:49 INFO - Installing distribute.............................................................................................................................................................................................done. 09:05:51 INFO - Installing pip.................done. 09:05:51 INFO - Return code: 0 09:05:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:05:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:51 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 0x7feb91927f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af5f60>, '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 0x1af6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1af71d0>, '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': 'c7c495d6f76fb52e1ad9f659000001da-1478883932.753165-1788267099', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:05:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:05:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:05:51 INFO - Using env: {'DISPLAY': ':0', 09:05:51 INFO - 'HOME': '/home/cltbld', 09:05:51 INFO - 'LANG': 'en_US.UTF-8', 09:05:51 INFO - 'LANGUAGE': 'en_US:en', 09:05:51 INFO - 'LOGNAME': 'cltbld', 09:05:51 INFO - 'MAIL': '/var/mail/cltbld', 09:05:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:51 INFO - 'MOZ_NO_REMOTE': '1', 09:05:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:05:51 INFO - 'NO_EM_RESTART': '1', 09:05:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:05:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:51 INFO - 'PWD': '/builds/slave/test', 09:05:51 INFO - 'SHELL': '/bin/bash', 09:05:51 INFO - 'SHLVL': '1', 09:05:51 INFO - 'TERM': 'linux', 09:05:51 INFO - 'TMOUT': '86400', 09:05:51 INFO - 'USER': 'cltbld', 09:05:51 INFO - 'XDG_SESSION_COOKIE': 'c7c495d6f76fb52e1ad9f659000001da-1478883932.753165-1788267099', 09:05:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:51 INFO - '_': '/tools/buildbot/bin/python'} 09:05:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:51 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:05:51 INFO - Cleaning up... 09:05:51 INFO - Return code: 0 09:05:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:05:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:51 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 0x7feb91927f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af5f60>, '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 0x1af6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1af71d0>, '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': 'c7c495d6f76fb52e1ad9f659000001da-1478883932.753165-1788267099', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:05:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:05:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:05:51 INFO - Using env: {'DISPLAY': ':0', 09:05:51 INFO - 'HOME': '/home/cltbld', 09:05:51 INFO - 'LANG': 'en_US.UTF-8', 09:05:51 INFO - 'LANGUAGE': 'en_US:en', 09:05:51 INFO - 'LOGNAME': 'cltbld', 09:05:51 INFO - 'MAIL': '/var/mail/cltbld', 09:05:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:51 INFO - 'MOZ_NO_REMOTE': '1', 09:05:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:05:51 INFO - 'NO_EM_RESTART': '1', 09:05:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:05:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:51 INFO - 'PWD': '/builds/slave/test', 09:05:51 INFO - 'SHELL': '/bin/bash', 09:05:51 INFO - 'SHLVL': '1', 09:05:51 INFO - 'TERM': 'linux', 09:05:51 INFO - 'TMOUT': '86400', 09:05:51 INFO - 'USER': 'cltbld', 09:05:51 INFO - 'XDG_SESSION_COOKIE': 'c7c495d6f76fb52e1ad9f659000001da-1478883932.753165-1788267099', 09:05:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:51 INFO - '_': '/tools/buildbot/bin/python'} 09:05:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:51 INFO - Downloading/unpacking psutil>=3.1.1 09:05:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:05:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:05:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:05:53 INFO - Installing collected packages: psutil 09:05:53 INFO - Running setup.py install for psutil 09:05:54 INFO - building 'psutil._psutil_linux' extension 09:05:54 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:05:54 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:05:54 INFO - building 'psutil._psutil_posix' extension 09:05:54 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:05:54 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:05:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:05:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:05:54 INFO - Successfully installed psutil 09:05:54 INFO - Cleaning up... 09:05:54 INFO - Return code: 0 09:05:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:05:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:54 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 0x7feb91927f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af5f60>, '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 0x1af6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1af71d0>, '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': 'c7c495d6f76fb52e1ad9f659000001da-1478883932.753165-1788267099', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:05:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:05:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:05:54 INFO - Using env: {'DISPLAY': ':0', 09:05:54 INFO - 'HOME': '/home/cltbld', 09:05:54 INFO - 'LANG': 'en_US.UTF-8', 09:05:54 INFO - 'LANGUAGE': 'en_US:en', 09:05:54 INFO - 'LOGNAME': 'cltbld', 09:05:54 INFO - 'MAIL': '/var/mail/cltbld', 09:05:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:54 INFO - 'MOZ_NO_REMOTE': '1', 09:05:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:05:54 INFO - 'NO_EM_RESTART': '1', 09:05:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:05:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:54 INFO - 'PWD': '/builds/slave/test', 09:05:54 INFO - 'SHELL': '/bin/bash', 09:05:54 INFO - 'SHLVL': '1', 09:05:54 INFO - 'TERM': 'linux', 09:05:54 INFO - 'TMOUT': '86400', 09:05:54 INFO - 'USER': 'cltbld', 09:05:54 INFO - 'XDG_SESSION_COOKIE': 'c7c495d6f76fb52e1ad9f659000001da-1478883932.753165-1788267099', 09:05:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:54 INFO - '_': '/tools/buildbot/bin/python'} 09:05:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:05:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:05:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:05:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:05:56 INFO - Installing collected packages: mozsystemmonitor 09:05:56 INFO - Running setup.py install for mozsystemmonitor 09:05:56 INFO - Successfully installed mozsystemmonitor 09:05:56 INFO - Cleaning up... 09:05:56 INFO - Return code: 0 09:05:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:05:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:56 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 0x7feb91927f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af5f60>, '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 0x1af6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1af71d0>, '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': 'c7c495d6f76fb52e1ad9f659000001da-1478883932.753165-1788267099', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:05:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:05:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:05:56 INFO - Using env: {'DISPLAY': ':0', 09:05:56 INFO - 'HOME': '/home/cltbld', 09:05:56 INFO - 'LANG': 'en_US.UTF-8', 09:05:56 INFO - 'LANGUAGE': 'en_US:en', 09:05:56 INFO - 'LOGNAME': 'cltbld', 09:05:56 INFO - 'MAIL': '/var/mail/cltbld', 09:05:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:56 INFO - 'MOZ_NO_REMOTE': '1', 09:05:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:05:56 INFO - 'NO_EM_RESTART': '1', 09:05:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:05:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:56 INFO - 'PWD': '/builds/slave/test', 09:05:56 INFO - 'SHELL': '/bin/bash', 09:05:56 INFO - 'SHLVL': '1', 09:05:56 INFO - 'TERM': 'linux', 09:05:56 INFO - 'TMOUT': '86400', 09:05:56 INFO - 'USER': 'cltbld', 09:05:56 INFO - 'XDG_SESSION_COOKIE': 'c7c495d6f76fb52e1ad9f659000001da-1478883932.753165-1788267099', 09:05:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:56 INFO - '_': '/tools/buildbot/bin/python'} 09:05:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:57 INFO - Downloading/unpacking jsonschema==2.5.1 09:05:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:05:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:05:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:59 INFO - Downloading functools32-3.2.3-2.tar.gz 09:05:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:05:59 INFO - warning: no files found matching '*.txt' 09:05:59 INFO - no previously-included directories found matching 'build' 09:05:59 INFO - no previously-included directories found matching 'dist' 09:05:59 INFO - no previously-included directories found matching '.git*' 09:05:59 INFO - Installing collected packages: jsonschema, functools32 09:05:59 INFO - Running setup.py install for functools32 09:05:59 INFO - warning: no files found matching '*.txt' 09:05:59 INFO - no previously-included directories found matching 'build' 09:05:59 INFO - no previously-included directories found matching 'dist' 09:05:59 INFO - no previously-included directories found matching '.git*' 09:05:59 INFO - Successfully installed jsonschema functools32 09:05:59 INFO - Cleaning up... 09:05:59 INFO - Return code: 0 09:05:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:05:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:59 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 0x7feb91927f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af5f60>, '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 0x1af6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1af71d0>, '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': 'c7c495d6f76fb52e1ad9f659000001da-1478883932.753165-1788267099', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:05:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:05:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:05:59 INFO - Using env: {'DISPLAY': ':0', 09:05:59 INFO - 'HOME': '/home/cltbld', 09:05:59 INFO - 'LANG': 'en_US.UTF-8', 09:05:59 INFO - 'LANGUAGE': 'en_US:en', 09:05:59 INFO - 'LOGNAME': 'cltbld', 09:05:59 INFO - 'MAIL': '/var/mail/cltbld', 09:05:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:59 INFO - 'MOZ_NO_REMOTE': '1', 09:05:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:05:59 INFO - 'NO_EM_RESTART': '1', 09:05:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:05:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:59 INFO - 'PWD': '/builds/slave/test', 09:05:59 INFO - 'SHELL': '/bin/bash', 09:05:59 INFO - 'SHLVL': '1', 09:05:59 INFO - 'TERM': 'linux', 09:05:59 INFO - 'TMOUT': '86400', 09:05:59 INFO - 'USER': 'cltbld', 09:05:59 INFO - 'XDG_SESSION_COOKIE': 'c7c495d6f76fb52e1ad9f659000001da-1478883932.753165-1788267099', 09:05:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:59 INFO - '_': '/tools/buildbot/bin/python'} 09:05:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:05:59 INFO - Cleaning up... 09:05:59 INFO - Return code: 0 09:05:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:05:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:59 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 0x7feb91927f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af5f60>, '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 0x1af6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1af71d0>, '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': 'c7c495d6f76fb52e1ad9f659000001da-1478883932.753165-1788267099', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:05:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:05:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:05:59 INFO - Using env: {'DISPLAY': ':0', 09:05:59 INFO - 'HOME': '/home/cltbld', 09:05:59 INFO - 'LANG': 'en_US.UTF-8', 09:05:59 INFO - 'LANGUAGE': 'en_US:en', 09:05:59 INFO - 'LOGNAME': 'cltbld', 09:05:59 INFO - 'MAIL': '/var/mail/cltbld', 09:05:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:59 INFO - 'MOZ_NO_REMOTE': '1', 09:05:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:05:59 INFO - 'NO_EM_RESTART': '1', 09:05:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:05:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:59 INFO - 'PWD': '/builds/slave/test', 09:05:59 INFO - 'SHELL': '/bin/bash', 09:05:59 INFO - 'SHLVL': '1', 09:05:59 INFO - 'TERM': 'linux', 09:05:59 INFO - 'TMOUT': '86400', 09:05:59 INFO - 'USER': 'cltbld', 09:05:59 INFO - 'XDG_SESSION_COOKIE': 'c7c495d6f76fb52e1ad9f659000001da-1478883932.753165-1788267099', 09:05:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:59 INFO - '_': '/tools/buildbot/bin/python'} 09:05:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:59 INFO - Downloading/unpacking blobuploader==1.2.4 09:05:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:06:01 INFO - Downloading blobuploader-1.2.4.tar.gz 09:06:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:06:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:06:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:06:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:06:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:06:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:06:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:06:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:06:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:06:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:06:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:06:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:06:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:06:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:06:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:06:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:06:02 INFO - Downloading docopt-0.6.1.tar.gz 09:06:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:06:02 INFO - Installing collected packages: blobuploader, requests, docopt 09:06:02 INFO - Running setup.py install for blobuploader 09:06:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:06:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:06:02 INFO - Running setup.py install for requests 09:06:03 INFO - Running setup.py install for docopt 09:06:03 INFO - Successfully installed blobuploader requests docopt 09:06:03 INFO - Cleaning up... 09:06:03 INFO - Return code: 0 09:06:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:06:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:03 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 0x7feb91927f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af5f60>, '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 0x1af6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1af71d0>, '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': 'c7c495d6f76fb52e1ad9f659000001da-1478883932.753165-1788267099', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:06:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:06:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:06:03 INFO - Using env: {'DISPLAY': ':0', 09:06:03 INFO - 'HOME': '/home/cltbld', 09:06:03 INFO - 'LANG': 'en_US.UTF-8', 09:06:03 INFO - 'LANGUAGE': 'en_US:en', 09:06:03 INFO - 'LOGNAME': 'cltbld', 09:06:03 INFO - 'MAIL': '/var/mail/cltbld', 09:06:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:03 INFO - 'MOZ_NO_REMOTE': '1', 09:06:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:03 INFO - 'NO_EM_RESTART': '1', 09:06:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:03 INFO - 'PWD': '/builds/slave/test', 09:06:03 INFO - 'SHELL': '/bin/bash', 09:06:03 INFO - 'SHLVL': '1', 09:06:03 INFO - 'TERM': 'linux', 09:06:03 INFO - 'TMOUT': '86400', 09:06:03 INFO - 'USER': 'cltbld', 09:06:03 INFO - 'XDG_SESSION_COOKIE': 'c7c495d6f76fb52e1ad9f659000001da-1478883932.753165-1788267099', 09:06:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:03 INFO - '_': '/tools/buildbot/bin/python'} 09:06:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:06:03 INFO - Running setup.py (path:/tmp/pip-r04zh2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:06:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:06:03 INFO - Running setup.py (path:/tmp/pip-TvsdbG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:06:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:06:03 INFO - Running setup.py (path:/tmp/pip-EvCShQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:06:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:06:03 INFO - Running setup.py (path:/tmp/pip-8DyWFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:06:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:06:03 INFO - Running setup.py (path:/tmp/pip-xzq7ob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:06:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:06:03 INFO - Running setup.py (path:/tmp/pip-s0DOLn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:06:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:06:03 INFO - Running setup.py (path:/tmp/pip-xma8o4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:06:04 INFO - Running setup.py (path:/tmp/pip-Qny8Ow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:06:04 INFO - Running setup.py (path:/tmp/pip-3VAn4q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:06:04 INFO - Running setup.py (path:/tmp/pip-LVRfxR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:06:04 INFO - Running setup.py (path:/tmp/pip-y4NI4x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:06:04 INFO - Running setup.py (path:/tmp/pip-nlkGCG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:06:04 INFO - Running setup.py (path:/tmp/pip-AC4HtV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:06:04 INFO - Running setup.py (path:/tmp/pip-UvepRO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:06:04 INFO - Running setup.py (path:/tmp/pip-lYzKES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:06:04 INFO - Running setup.py (path:/tmp/pip-3gDEK1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:06:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:06:04 INFO - Running setup.py (path:/tmp/pip-aUiJ2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:06:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:06:04 INFO - Running setup.py install for manifestparser 09:06:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:06:04 INFO - Running setup.py install for mozcrash 09:06:04 INFO - Running setup.py install for mozdebug 09:06:05 INFO - Running setup.py install for mozdevice 09:06:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:06:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:06:05 INFO - Running setup.py install for mozfile 09:06:05 INFO - Running setup.py install for mozhttpd 09:06:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:06:05 INFO - Running setup.py install for mozinfo 09:06:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:06:05 INFO - Running setup.py install for mozInstall 09:06:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:06:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:06:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:06:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:06:05 INFO - Running setup.py install for mozleak 09:06:05 INFO - Running setup.py install for mozlog 09:06:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:06:05 INFO - Running setup.py install for moznetwork 09:06:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:06:05 INFO - Running setup.py install for mozprocess 09:06:05 INFO - Running setup.py install for mozprofile 09:06:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:06:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:06:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:06:06 INFO - Running setup.py install for mozrunner 09:06:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:06:06 INFO - Running setup.py install for mozscreenshot 09:06:06 INFO - Running setup.py install for moztest 09:06:06 INFO - Running setup.py install for mozversion 09:06:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:06:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:06:06 INFO - Cleaning up... 09:06:06 INFO - Return code: 0 09:06:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:06:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:06 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 0x7feb91927f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af5f60>, '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 0x1af6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1af71d0>, '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': 'c7c495d6f76fb52e1ad9f659000001da-1478883932.753165-1788267099', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:06:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:06:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:06:06 INFO - Using env: {'DISPLAY': ':0', 09:06:06 INFO - 'HOME': '/home/cltbld', 09:06:06 INFO - 'LANG': 'en_US.UTF-8', 09:06:06 INFO - 'LANGUAGE': 'en_US:en', 09:06:06 INFO - 'LOGNAME': 'cltbld', 09:06:06 INFO - 'MAIL': '/var/mail/cltbld', 09:06:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:06 INFO - 'MOZ_NO_REMOTE': '1', 09:06:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:06 INFO - 'NO_EM_RESTART': '1', 09:06:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:06 INFO - 'PWD': '/builds/slave/test', 09:06:06 INFO - 'SHELL': '/bin/bash', 09:06:06 INFO - 'SHLVL': '1', 09:06:06 INFO - 'TERM': 'linux', 09:06:06 INFO - 'TMOUT': '86400', 09:06:06 INFO - 'USER': 'cltbld', 09:06:06 INFO - 'XDG_SESSION_COOKIE': 'c7c495d6f76fb52e1ad9f659000001da-1478883932.753165-1788267099', 09:06:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:06 INFO - '_': '/tools/buildbot/bin/python'} 09:06:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:06:06 INFO - Running setup.py (path:/tmp/pip-3VKRV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:06:06 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:06:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:06:06 INFO - Running setup.py (path:/tmp/pip-pGDeu0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:06:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:06:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:06:06 INFO - Running setup.py (path:/tmp/pip-mLwY3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:06:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:06:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:06:06 INFO - Running setup.py (path:/tmp/pip-6356kH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:06:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:06:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:06:07 INFO - Running setup.py (path:/tmp/pip-_5VlWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:06:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:06:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:06:07 INFO - Running setup.py (path:/tmp/pip-v2UdoZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:06:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:06:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:06:07 INFO - Running setup.py (path:/tmp/pip-YJEk7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:06:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:06:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:06:07 INFO - Running setup.py (path:/tmp/pip-m6P26X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:06:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:06:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:06:07 INFO - Running setup.py (path:/tmp/pip-QXRRgQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:06:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:06:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:06:07 INFO - Running setup.py (path:/tmp/pip-62iYQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:06:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:06:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:06:07 INFO - Running setup.py (path:/tmp/pip-GqHyLJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:06:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:06:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:06:07 INFO - Running setup.py (path:/tmp/pip-bYFpO_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:06:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:06:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:06:07 INFO - Running setup.py (path:/tmp/pip-Ok8i6d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:06:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:06:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:06:07 INFO - Running setup.py (path:/tmp/pip-eABMbq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:06:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:06:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:06:07 INFO - Running setup.py (path:/tmp/pip-h6yioq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:06:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:06:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:06:07 INFO - Running setup.py (path:/tmp/pip-Turow9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:06:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:06:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:06:07 INFO - Running setup.py (path:/tmp/pip-Ki8nIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:06:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:06:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:06:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:06:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:06:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:06:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:06:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:06:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:06:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:06:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:06:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:06:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:06:09 INFO - Downloading blessings-1.6.tar.gz 09:06:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:06:10 INFO - Installing collected packages: blessings 09:06:10 INFO - Running setup.py install for blessings 09:06:10 INFO - Successfully installed blessings 09:06:10 INFO - Cleaning up... 09:06:10 INFO - Return code: 0 09:06:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:06:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:06:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:06:10 INFO - Reading from file tmpfile_stdout 09:06:10 INFO - Current package versions: 09:06:10 INFO - argparse == 1.2.1 09:06:10 INFO - blessings == 1.6 09:06:10 INFO - blobuploader == 1.2.4 09:06:10 INFO - docopt == 0.6.1 09:06:10 INFO - functools32 == 3.2.3-2 09:06:10 INFO - jsonschema == 2.5.1 09:06:10 INFO - manifestparser == 1.1 09:06:10 INFO - mozInstall == 1.12 09:06:10 INFO - mozcrash == 0.17 09:06:10 INFO - mozdebug == 0.1 09:06:10 INFO - mozdevice == 0.48 09:06:10 INFO - mozfile == 1.2 09:06:10 INFO - mozhttpd == 0.7 09:06:10 INFO - mozinfo == 0.9 09:06:10 INFO - mozleak == 0.1 09:06:10 INFO - mozlog == 3.4 09:06:10 INFO - moznetwork == 0.27 09:06:10 INFO - mozprocess == 0.23 09:06:10 INFO - mozprofile == 0.28 09:06:10 INFO - mozrunner == 6.12 09:06:10 INFO - mozscreenshot == 0.1 09:06:10 INFO - mozsystemmonitor == 0.3 09:06:10 INFO - moztest == 0.7 09:06:10 INFO - mozversion == 1.4 09:06:10 INFO - psutil == 3.1.1 09:06:10 INFO - requests == 1.2.3 09:06:10 INFO - wsgiref == 0.1.2 09:06:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:06:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:10 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 0x7feb91927f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af5f60>, '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 0x1af6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1af71d0>, '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': 'c7c495d6f76fb52e1ad9f659000001da-1478883932.753165-1788267099', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:06:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:06:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:06:10 INFO - Using env: {'DISPLAY': ':0', 09:06:10 INFO - 'HOME': '/home/cltbld', 09:06:10 INFO - 'LANG': 'en_US.UTF-8', 09:06:10 INFO - 'LANGUAGE': 'en_US:en', 09:06:10 INFO - 'LOGNAME': 'cltbld', 09:06:10 INFO - 'MAIL': '/var/mail/cltbld', 09:06:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:10 INFO - 'MOZ_NO_REMOTE': '1', 09:06:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:10 INFO - 'NO_EM_RESTART': '1', 09:06:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:10 INFO - 'PWD': '/builds/slave/test', 09:06:10 INFO - 'SHELL': '/bin/bash', 09:06:10 INFO - 'SHLVL': '1', 09:06:10 INFO - 'TERM': 'linux', 09:06:10 INFO - 'TMOUT': '86400', 09:06:10 INFO - 'USER': 'cltbld', 09:06:10 INFO - 'XDG_SESSION_COOKIE': 'c7c495d6f76fb52e1ad9f659000001da-1478883932.753165-1788267099', 09:06:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:10 INFO - '_': '/tools/buildbot/bin/python'} 09:06:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:06:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:06:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:06:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:06:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:06:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:06:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:06:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:06:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:06:10 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:06:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:06:10 INFO - Cleaning up... 09:06:10 INFO - Return code: 0 09:06:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:06:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:10 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 0x7feb91927f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a2b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1af8ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1af5f60>, '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 0x1af6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1af71d0>, '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': 'c7c495d6f76fb52e1ad9f659000001da-1478883932.753165-1788267099', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:06:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:06:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:06:10 INFO - Using env: {'DISPLAY': ':0', 09:06:10 INFO - 'HOME': '/home/cltbld', 09:06:10 INFO - 'LANG': 'en_US.UTF-8', 09:06:10 INFO - 'LANGUAGE': 'en_US:en', 09:06:10 INFO - 'LOGNAME': 'cltbld', 09:06:10 INFO - 'MAIL': '/var/mail/cltbld', 09:06:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:10 INFO - 'MOZ_NO_REMOTE': '1', 09:06:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:10 INFO - 'NO_EM_RESTART': '1', 09:06:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:10 INFO - 'PWD': '/builds/slave/test', 09:06:10 INFO - 'SHELL': '/bin/bash', 09:06:10 INFO - 'SHLVL': '1', 09:06:10 INFO - 'TERM': 'linux', 09:06:10 INFO - 'TMOUT': '86400', 09:06:10 INFO - 'USER': 'cltbld', 09:06:10 INFO - 'XDG_SESSION_COOKIE': 'c7c495d6f76fb52e1ad9f659000001da-1478883932.753165-1788267099', 09:06:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:10 INFO - '_': '/tools/buildbot/bin/python'} 09:06:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:06:10 INFO - Cleaning up... 09:06:10 INFO - Return code: 0 09:06:10 INFO - Running post-action listener: _resource_record_post_action 09:06:10 INFO - Running post-action listener: _start_resource_monitoring 09:06:10 INFO - Starting resource monitoring. 09:06:10 INFO - [mozharness: 2016-11-11 17:06:10.872638Z] Finished create-virtualenv step (success) 09:06:10 INFO - [mozharness: 2016-11-11 17:06:10.873137Z] Running install step. 09:06:10 INFO - Running pre-action listener: _resource_record_pre_action 09:06:10 INFO - Running main action method: install 09:06:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:06:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:06:11 INFO - Reading from file tmpfile_stdout 09:06:11 INFO - Detecting whether we're running mozinstall >=1.0... 09:06:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:06:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:06:11 INFO - Reading from file tmpfile_stdout 09:06:11 INFO - Output received: 09:06:11 INFO - Usage: mozinstall [options] installer 09:06:11 INFO - Options: 09:06:11 INFO - -h, --help show this help message and exit 09:06:11 INFO - -d DEST, --destination=DEST 09:06:11 INFO - Directory to install application into. [default: 09:06:11 INFO - "/builds/slave/test"] 09:06:11 INFO - --app=APP Application being installed. [default: firefox] 09:06:11 INFO - mkdir: /builds/slave/test/build/application 09:06:11 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'] 09:06:11 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 09:06:27 INFO - Reading from file tmpfile_stdout 09:06:27 INFO - Output received: 09:06:27 INFO - /builds/slave/test/build/application/firefox/firefox 09:06:27 INFO - Running post-action listener: _resource_record_post_action 09:06:27 INFO - [mozharness: 2016-11-11 17:06:27.131411Z] Finished install step (success) 09:06:27 INFO - [mozharness: 2016-11-11 17:06:27.131710Z] Running run-tests step. 09:06:27 INFO - Running pre-action listener: _resource_record_pre_action 09:06:27 INFO - Running main action method: run_tests 09:06:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:06:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:06:27 INFO - Python 2.7.3 09:06:27 INFO - Return code: 0 09:06:27 INFO - grabbing minidump binary from tooltool 09:06:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:27 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 0x1af5f60>, '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 0x1af6d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1af71d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:06:27 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:06:27 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:06:27 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:06:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:06:27 INFO - Return code: 0 09:06:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:06:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:06:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:06:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:06:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:06:27 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/EM1W6ERWS_mJhpE3BbKGeg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:06:27 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/EM1W6ERWS_mJhpE3BbKGeg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-029 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:06:27 INFO - Using env: {'DISPLAY': ':0', 09:06:27 INFO - 'HOME': '/home/cltbld', 09:06:27 INFO - 'LANG': 'en_US.UTF-8', 09:06:27 INFO - 'LANGUAGE': 'en_US:en', 09:06:27 INFO - 'LOGNAME': 'cltbld', 09:06:27 INFO - 'MAIL': '/var/mail/cltbld', 09:06:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:06:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:06:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:27 INFO - 'MOZ_NO_REMOTE': '1', 09:06:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:06:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:06:27 INFO - 'NO_EM_RESTART': '1', 09:06:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:06:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:27 INFO - 'PWD': '/builds/slave/test', 09:06:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:06:27 INFO - 'SHELL': '/bin/bash', 09:06:27 INFO - 'SHLVL': '1', 09:06:27 INFO - 'TERM': 'linux', 09:06:27 INFO - 'TMOUT': '86400', 09:06:27 INFO - 'USER': 'cltbld', 09:06:27 INFO - 'XDG_SESSION_COOKIE': 'c7c495d6f76fb52e1ad9f659000001da-1478883932.753165-1788267099', 09:06:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:27 INFO - '_': '/tools/buildbot/bin/python'} 09:06:27 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/EM1W6ERWS_mJhpE3BbKGeg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:06:27 INFO - mozversion application_buildid: 20161111062220 09:06:27 INFO - mozversion application_changeset: 7a7e256d58b69f0fdf92cead9ff17aab7a38e130 09:06:27 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:06:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:06:27 INFO - mozversion application_name: Firefox 09:06:27 INFO - mozversion application_remotingname: firefox-dev 09:06:27 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:06:27 INFO - mozversion application_vendor: Mozilla 09:06:27 INFO - mozversion application_version: 51.0a2 09:06:27 INFO - mozversion platform_buildid: 20161111062220 09:06:27 INFO - mozversion platform_changeset: 7a7e256d58b69f0fdf92cead9ff17aab7a38e130 09:06:27 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:06:27 INFO - mozversion platform_version: 51.0a2 09:06:27 INFO - using testdate: 1478883987 09:06:27 INFO - actual date: 1478883987 09:06:27 INFO - starting webserver on 'localhost:38418' 09:06:27 INFO - SUITE-START | Running 2 tests 09:06:27 INFO - TEST-START | dromaeo_css 09:06:27 INFO - operating with platform_type : linux_ 09:06:27 INFO - Initialising browser for dromaeo_css test... 09:06:27 INFO - TEST-INFO | started process 31078 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfacxu6/profile http://localhost:38418/getInfo.html) 09:06:31 INFO - PROCESS | 31078 | __metrics Screen width/height:1600/1200 09:06:31 INFO - PROCESS | 31078 | colorDepth:24 09:06:31 INFO - PROCESS | 31078 | Browser inner width/height: 1024/697 09:06:31 INFO - PROCESS | 31078 | __metrics 09:06:31 INFO - PROCESS | 31078 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:06:31 INFO - PROCESS | 31078 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:06:32 INFO - PROCESS | 31078 | console.error: PushService: 09:06:32 INFO - PROCESS | 31078 | stateChangeProcessEnqueue: Error transitioning state 09:06:32 INFO - PROCESS | 31078 | UnknownError 09:06:32 INFO - TEST-INFO | 31078: exit 0 09:06:32 INFO - Browser initialized. 09:06:32 INFO - Running cycle 1/1 for dromaeo_css test... 09:06:33 INFO - TEST-INFO | started process 31166 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfacxu6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:06:44 INFO - PROCESS | 31166 | RSS: Main: 144449536 09:06:44 INFO - PROCESS | 31166 | 09:07:36 INFO - PROCESS | 31166 | 1478884056096 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:09:45 INFO - PROCESS | 31166 | Cycle 1(1): loaded http://localhost:38418/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38418/tests/dromaeo/cssquery-ext.html) 09:09:46 INFO - PROCESS | 31166 | RSS: Main: 246030336 09:09:46 INFO - PROCESS | 31166 | 09:12:48 INFO - PROCESS | 31166 | Cycle 1(1): loaded http://localhost:38418/tests/dromaeo/cssquery-ext.html (next: http://localhost:38418/tests/dromaeo/cssquery-jquery.html) 09:12:49 INFO - PROCESS | 31166 | RSS: Main: 225845248 09:12:49 INFO - PROCESS | 31166 | 09:15:51 INFO - PROCESS | 31166 | Cycle 1(1): loaded http://localhost:38418/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38418/tests/dromaeo/cssquery-mootools.html) 09:15:51 INFO - PROCESS | 31166 | RSS: Main: 174563328 09:15:51 INFO - PROCESS | 31166 | 09:18:53 INFO - PROCESS | 31166 | Cycle 1(1): loaded http://localhost:38418/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38418/tests/dromaeo/cssquery-prototype.html) 09:18:54 INFO - PROCESS | 31166 | RSS: Main: 175673344 09:18:54 INFO - PROCESS | 31166 | 09:21:57 INFO - PROCESS | 31166 | Cycle 1(1): loaded http://localhost:38418/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38418/tests/dromaeo/cssquery-yui.html) 09:21:57 INFO - PROCESS | 31166 | RSS: Main: 172965888 09:21:57 INFO - PROCESS | 31166 | 09:25:00 INFO - PROCESS | 31166 | Cycle 1(1): loaded http://localhost:38418/tests/dromaeo/cssquery-yui.html (next: http://localhost:38418/tests/dromaeo/cssquery-dojo.html) 09:25:00 INFO - PROCESS | 31166 | RSS: Main: 266141696 09:25:00 INFO - PROCESS | 31166 | 09:25:00 INFO - PROCESS | 31166 | __start_tp_report 09:25:00 INFO - PROCESS | 31166 | _x_x_mozilla_page_load 09:25:00 INFO - PROCESS | 31166 | _x_x_mozilla_page_load_details 09:25:00 INFO - PROCESS | 31166 | |i|pagename|runs| 09:25:00 INFO - PROCESS | 31166 | |0;dojo.html;3170.3183815479674;3208.9358212835746;3235.838208089595;3254.772165948382;3274.1487213324526;5657.688827114507;5729.484346408823;5747.885042299156;5750.079987202049;5764.7982320618785;5711.200431939521;5769.249997500319;5770.798022069229;5780.82657520274;5784.1902133701315;5197.272381866542;5204.063268611649;5225.477452254782;5239.423663397023;5296.682199068068;5031.974840125794;5053.898922021557;5296.3379577552805;5303.098473259555;5338.359396872381;5078.26365177051;5106.897862042757;5120.4623514530995;5140.126178549637;5179.792808287664;5163.896722065557;5241.86895327616;5249.238860365244;5250.264962905197;5285.180796976475;4982.90034199314;5074.619403544749;5143.974280128613;5184.7148406837605;5212.50481204285;5497.752601132958;5503.944960550383;5523.337199536081;5526.944730552683;5576.191452239443;5953.166556682068;5992.31088424828;6082.847928801815;6089.543284253698;6091.360407157229;5901.3508514063415;6111.052786818051;6112.327643959161;6125.264968203799;6132.969335153341;5552.472515111058;5670.6030577859565;5741.540676745892;5744.626599271118;5789.247397838254;6336.049592561153;6357.332480089616;6365.363463653597;6366.395192456709;6368.968155159195;5759.740811663485;6098.908516372258;6101.572889897753;6110.29731580865;6110.602810817282;5895.970520147458;6058.242719660043;6123.510119190454;6131.264248290234;6237.625742455467;6189.288231853305;6193.6903154842075;6199.070139479024;6200.038993955944;6204.937950620526;6153.84615384619;6220.564560480813;6221.06683997404;6224.968875155686;6226.128342032119;4514.255147900625;4547.658925580595;4596.908061838744;4609.492955774862;4625.468071171791;4156.418101465797;4157.043879907638;4177.248095342868;4190.8533201337805;4192.329227323614;5806.04200306953;5818.5345172386105;5824.446677565625;5825.417458254141;5830.358660547421;5919.822405327847;5981.970090149608;6083.756649733961;6186.969065154645;6207.286162091417;5778.508826749689;5833.4749872511675;5845.649261044351;5873.119032145212;5879.176915231871;5262.842114736564;5279.815206467755;5292.285541451854;5293.735313234323;5293.814716484904;6087.086936959491;6134.4172303631085;6342.714577844008;6408.775692850728;6410.0705397717065;6044.455998960115;6094.695265236809;6098.176746139215;6184.041473571604;6189.35011823752;6344.460720838781;6359.90460143089;6360.077788720702;6369.363063693593;6370.044493326038;5801.448862358238;6052.788152414644;6052.969735151296;6057.061155520902;6066.272047354346;6020.157177995172;6025.909611355745;6055.6063855849225;6068.302145253176;6135.814547850547;6034.064719968412;6038.8792224154395;6041.879162416815;6045.848853778515;6062.5453091019235;6015.729292181775;6021.578489505692;6024.5481588881885;6026.096085587197;6026.397360263939;5688.345840228261;5824.854378640568;5905.881882362414;5952.8809423812145;5981.551383646157;6038.909416358846;6084.951795445132;6086.169334381299;6092.421219984094;6122.632642041492;5598.608097433141;5950.761969521171;5961.195238642727;5963.701814909324;5975.581709280309 09:25:00 INFO - PROCESS | 31166 | |1;ext.html;591.8224532640207;616.3713012727019;631.9589226700264;642.5855323316463;651.3161180760201;34474.13814654632;46637.533624663745;46733;46794;46870.76564617181;48031;48544;48549.757251213785;48619;48639.75680121603;8512.659493620278;9210.677626283064;9467.195288400495;9478.905210947905;9479.099485548897;8389.496630202177;8651.913480865174;8659.393842431033;10957.232993690444;11238.494267757971;11374;11431.714207144805;11433.485493152786;11435.028022618088;11437.370944598044;11227.719307017309;11276.548938042468;11292.661220163325;11310.547578096866;11311.943440282828;11102.111831053497;11146.996770290632;11176.161787865862;11179.273347232403;11182.384968826711;47449;47717.76141119307;47802.5219747805;47837.28244076342;47965;3712.7586706863967;3757.6993840492696;3814.5231846019246;3892.6107389260847;4030.8186131624147;35061.82469087664;35234.8238258807;35280.29439411223;35370.29259414823;35518;4677.4620918594455;4704.905901881977;4746.406699162605;4759.5002524734755;4833.685810422347;251488;253142;253225;253459;253716;66461;66721;66894.66552667302;66921.33078669151;66962.66518667375;41593.58406415958;42161.57838421638;42168.578314216466;43541.12917741691;70676.293237068;18665;18732.719009214874;18753.624927501372;18818.623627527373;18862.339818106302;15687.764683529527;15813.762793557875;15824;15825.971311864692;15829.208539573207;18843.246270149313;19275.4217373479;20798.688019679717;21251.574968500543;21282.78717212808;7515.248475152441;7712.344450721639;7721.884171737428;7783.688652453951;7864.174261702553;997.0826839307854;1096.1669131460148;1115.9944200278946;1143.6912033750841;1180.6694125644833;35597.644023559435;36022.45966310507;36147.819260903525;36162;36179.63820361763;1153.405995912106;1157.89578937896;1183.893449589541;1200.1778781534676;1230.3110258255406;17779.377721779936;18729.662762580607;18994.24023039063;19057.90471047636;19064.14211360492;611417;613073;617689;618548;622110;64785;65498;65551.67224163849;65562.67218663877;65718.34281657219;1348.6830594810212;1395.232622057864;1402.382951501336;1459.846716094816;1475.4688312207597;29576.704232957396;35571;35996;38360.616393835706;39155;62200;62774.37225627685;62845.371546283954;62895;62920;488548;492537;494529;496185;496619;28878.133655990354;28966.27584310325;28997;31770;32343.514847276838;48904;49445.033298002236;49608.75195623999;49862.252066219764;50276.49723502865;41380.58619413767;42292.36561451642;42412;42547.36178957256;43449.34825977676;41337.79331103325;42060;42483.57516424796;42875.35686964761;44260.33609495796;42743.572564273956;43050;43680.344794828736;44330.335044973704;44496;35564.466533001505;37272.627273728;38196.236075278895;38367.42448863325;38715;28718.71281287244;29388;29497.410051799274;29642.258943526584;29690 09:25:00 INFO - PROCESS | 31166 | |2;jquery.html;399.0522509041024;407.69830325559093;424.64117820441726;427.22885192228017;438.6162108155364;14522.201278929668;16307.918460407696;19993;20541.178352865903;21045.473863153427;19381.127849246783;19950.30173943912;20344.59310813783;20548.17807287707;20823.27118550851;5763.1355296705415;5828;5869.442402971711;5925.940740592582;5927;4362.1275744851;5792.478676919077;5868.149118377854;5876.177335173079;5876.382979787104;4400.7579583122915;6446.5165112616505;6469.061986012025;6499.122618446497;6526.510511711641;6434.292227854909;6516.543841931067;6528.902066469007;6535.15043044406;6549.770758023494;5671.092625179951;5772.682502462363;5804.59367844251;5809.128630705386;5810.622309549865;23787.405314867094;23793;23852.16517421882;24023.519529609486;24267.393315167086;2566.1275166443497;2585.896564137432;2714.226445463053;2916.737493625563;3240.5139229115584;14938.178400187986;14995.700085998218;15051.096934183985;15064.472743453927;15111.622209444742;1832.3220408448826;1900.125942066651;2011.1955217912953;2060.6084843879767;2091.0799248330686;63375.366246337406;63526;70648.29351706468;74976.99999999946;85652;10957.671269861918;11102.888971110347;11291.49188286529;11298.265612735146;11481.598144064918;16264.349426023064;16292.103934283492;16297.429589964295;16309;16310.592235193977;8852.601632926533;8883.067277935723;9094.136057074567;9311;9332.113449222314;8938.240249578728;8957.193852553302;9005.954970225239;9128.22410095136;9172.954135229282;6855.5543889647015;6911.101556797584;6939.004472773654;6954.9304506955295;7127.89308160378;2002.6195022945594;2024.331970449748;2059.8764074155297;2062.391594479632;2082.1775398717723;955.47926380123;1046.5918291866178;1066.434789561518;1108.3959242213014;1135.0262312740508;20632.896835515727;21122.683159752614;21190.47023824386;21341.46646333823;21546.569068618854;1048.4128887822692;1083.4041277297456;1088.1403691084113;1089.2212068370993;1128.785530749155;11150.888491114985;12071;12204.145709800228;12218.633440996784;12250.754984900252;369102;376366;378128;505367;507715;91128.54435727911;97751;98829;101724;103032.48483757683;1066.802641511323;1067.066316972649;1104.3263609198382;1107.894749998749;1118.0999295566985;18208.36270730518;18363.54091147732;18613.348532801287;18648.254069837596;18925.716114258572;39668;43433.3484997719;44344;44693.329600055375;44813.32780008237;60336;61069;62214.68892655508;62495;62767;8866.246369058445;8931;8952.14954579314;9244.214241789254;9289.117533834275;10130.594776209164;13444.327783610976;13794;14058.789118163031;14341.211233382264;12196.57311994076;12481.750364992467;12552.874471255536;12629.684257893627;13095.083344165816;12166.45250963691;12272.693182670148;12493;12591.055670824542;12753.872461275269;12070.75858482843;12093.334866582423;12603.117781755556;12663.113582049167;12735.87264127384;9182.632694692138;9411.529423528933;9451.149396554343;9637.855432168382;9644.987276335884;10839.783204335712;11564.306141631529;12205.633830984729;12422.503099875903;12560.12079154487 09:25:00 INFO - PROCESS | 31166 | |3;mootools.html;1450.2821103553729;1570.8743300535955;1588.6425554250293;1682.9663406731866;1820.0262859370239;8533.91466085339;9321.860172097415;9578.281628877827;9591.23270138389;9868.210543156549;9643.566039528221;9760.804783904317;9767.413955162696;9769.462679552622;9777.364471309378;2568.948621027578;2596.5196438658813;2601.1676263595655;2602.622619720149;2603.947921041578;2526.886290116949;2551.7703406693395;2556.2586849813533;2557.6802899637546;2561.449288402993;2471.1351027140536;2488.029668429314;2544.6819147606548;2558.731333210014;2563.74362563744;2415.1788391946734;2431.2463136427764;2437.6465412515263;2494.550980823451;2495.5508008558454;2403.4592216751266;2419.758024197584;2424.6484259782233;2427;2448.192096608115;9380.812383752354;9550.61797528098;9631.951840240754;9631.951840240825;9747.463889486075;5791.681457519835;5959.851003724898;5966.642001479925;5968.045112781978;5970.373110823344;3734.663880250763;3737.8131093445218;3777.112378591029;3839.308924393608;3856.402257650069;1324.543032653733;1331.5938638715188;1334.833145856768;1354.1536539662711;1362.1554636125666;13712.65718357049;13726.176429414267;13767.518136865161;13913.791293130611;13915.095518791246;4910.453207239708;4955.900881982412;4978.676386034968;5011.674241174312;5116.181410974226;10840.945795271131;10856.077233435088;10859.674209773719;10862.293950893325;10867.78264434707;5795.710214489259;5806.129080637938;5806.709664516758;5807.390224026501;5813.360530341659;4718.41019872516;4769.785359658824;5190.948090519122;5287.259783630295;5411.02601531728;3818.2936156810524;3898.1813819098306;3930.9213815723524;3946.6842652587725;3993.800309984489;1070.710908054821;1080.7730376620998;1091.8362245663213;1096.52849274811;1098.1489345756943;957.9281553883486;1029.2177944762034;1061.4745700878116;1063.1176123817368;1066.7013236293851;6635.933640663531;6716.059751634775;6761.898571521431;6794.830129246809;6872.931270687229;516.094254583204;530.2391068816261;535.9222912677718;536.8094326514109;541.1990254423475;4628.421447319085;4645.744484053342;4646.976765116153;4651.348811166439;4674.906501869944;11705.648830535098;12076.396180190955;12115.939420302842;12116.575919842764;12123.515059397701;11343.432828358715;11826.290422574673;11827.467763950643;12059.577914773115;12061.035117190604;8962.955185224033;9045.909540904508;9097.817283753202;9181.770455738526;9190.40262382943;6602.382416308035;6717.865642687217;6786.728530858711;6877.828054298682;6959.19969203548;10683.94658026705;11133.443327833738;11149.944250278697;11288.2662626929;11470.541178353105;11287.097032237238;11411.54353825871;11429.771404572028;11445.599404020819;11544.307341559534;7682.193369696101;7898.526088434713;8350.33197344199;8604.741857744277;8835.911640883765;9395.107464790834;9716.757081072747;9740.269479788902;9747.9025209747;9759.707208783748;8384.161583841811;8425.452345597516;8477.576121193792;8504.872426913726;8523.872141918;8300.792480188044;8307.127751585997;8316.043654979754;8351.540665263468;8603.956980215058;7776.88334674969;7796.883046754417;7805.297523222937;7862.253085956825;7873.685052597832;5380.811671591476;5626;5646.971765141148;5704.429557044263;5730.083186690276;5689.516391106637;5797.449242321973;5801.82594522165;5852.912206316823;5902.763889444546 09:25:00 INFO - PROCESS | 31166 | |4;prototype.html;350.11421104605347;353.9380608393532;357.2622534466326;358.3531725235948;363.99818000909977;5514.972425137874;5522.723863806808;5546.584006199531;5551.222828803966;5585.273914391134;5465.890682186354;5469.480399362064;5475.151351540518;5481.890362192754;5483.314585676791;3784.375578029622;4115.279826030448;4253.914921701564;4324.84863029794;4351.65186785058;4338.609525142737;4342.500612429563;4345.587169218935;4345.69580129391;4351.6518678505645;4309.461317335333;4321.611055005049;4328.718633288826;4332.1119170569955;4344.45694288214;4050.18996200761;4130.215259100792;4186.769727664977;4337.696361254713;4348.804303806321;3858.2862170498265;3865.594112618191;3873.7288389812725;3877.243937432202;3892.260470510594;5293.470652934714;5304.36347638282;5305.965336759334;5311.4065780266155;5362.2224777407055;4787.449443314029;4921.335619691332;4957;4965.925511117336;4977.950220497785;5693.459121383504;5697.6866272355;5697.886042279131;5707;5717.885642287172;4232.407462955189;4236.343366778155;4255.829766809324;4263.850765223233;4269.231538323101;5573.749181286852;5680.403557626431;5756.395578464243;5852.941470585325;5897.4102589741115;4204.684648651363;4319.805608747551;4323.286657701507;4323.783810809447;4329.112531930947;4385.864107163394;4386.385905973167;4387.517373088957;4391.297392417198;4392.780360982002;4771.878608526993;4791.873909631234;4921.434035085939;4943.307936888838;4955.529224723646;5647.519960803295;5675.631083979611;5677.637367031883;5684.147377893349;5695.202671625976;1495.53638372105;1510.8715759160596;1515.7120147172002;1517.7419838627459;1520.4526370506608;920.60987908259;967.8403063494444;982.1995074014656;983.5672304186136;991.9702408927744;4860.6111511079025;4871.878203044952;4881.4630390657;4882.778335857177;4887.95112048882;5123.513266239701;5131.435542090367;5151.922721159186;5175.894463321977;5178.326817513699;4538.704984176018;4862.562369386772;4870.733609261618;4891.26631005352;4897.314375987365;2720.1231150506087;2723.945521089567;2724.931876703098;2739.2330147558714;2742.6023226632424;5270.973645131749;5272.841814745564;5275.261463395128;5276.153346328754;5277.2875661785165;4858.489858564871;5188.9740551297;5200.947990520046;5206.3752349717515;5225.790968361299;5232.659877107976;5242.554382877422;5248.396434410091;5263.34208223972;5275.050490911673;5087.720175390389;5093.337866077534;5106.217111674072;5114.5652619527755;5117.692938423706;4734.573888349927;5014.5737612303365;5064.797408103635;5065.265536497261;5067.366579177603;5261.421573528003;5803.361630220588;5883.911741323798;5951.464368206882;6109;4322.610965013164;4354.716943398669;4363.498197707304;4380.956190438055;4399.318105693623;5663.4053424391495;5875.383084776037;5894.671604716027;5897.64614123154;5945.672987985702;5703.714814259353;5721.942780572141;5742.799002035076;5746.9712651436475;5751.424857514216;5680.2047713321;5713.942860571341;5726.885462290647;5729;5731.770729170787;5414.377346605191;5669.54643628517;5681.630694004876;5682.715864206856;5713.314402271754;5707.400722924033;5719.968416739135;5722.742476588647;5724.370319264961;5758.769649214152;5613.635113717595;5618.915716264341;5646.745896434588;5663.5469162467725;5665.858353541194 09:25:00 INFO - PROCESS | 31166 | |5;yui.html;781.6365390093605;816.738643634037;824.7113510271402;845.4504572028181;877.2411863737868;16499.257533410997;18249.81750182498;18253.90873045633;18274.08629568521;18328.175232114554;16213.270402831871;16376;16415.58961025975;16500.17499125042;16517.174141292948;732.5787672088544;747.6411322565172;875.2210532625984;880.8458519759049;908.550267617527;879.3536750488386;880.7798050487378;881.4799268431624;881.7266647339345;882.2148288023664;930.0002497315379;932.1703683721494;933.0669330669331;933.3186773655236;933.3373304953491;770.6108415250319;790.0401012769483;913.1690161952591;925.3291363761286;930.4696323095839;681.2234053179379;683.1187767779559;683.1460674157304;683.8119517132828;683.9281875403061;16451.177441127897;16466;16493.6701265974;16500.092494912777;16536.338546458246;15598.376064957389;15605.45380911674;15783.210839457981;15998.280077396545;16052.839471605252;14065.296735163303;14095.22476263805;14319.212443315613;14354;14381.822987577125;4676.134915040694;4708.764561771898;4727.101850648366;4757.857264282077;4871.8782030449165;84242.57878710628;85810.57094714549;85816.57091714564;87416;89607;12467.189632673848;12482.625521234377;12570.182938108994;12614.180078295065;12851.742965140646;23954.40113997164;24040.639390409156;24083.879580601984;24102.758972410404;24480.755192447847;8114.066882308492;8601.655933762704;8644.740657780276;8806.251468625238;8892.421992570462;4868.123737727243;5014.222795466636;5039.924401133986;5114.28400023997;5217;3741.8877433677035;3883.941740873889;3892.980535097363;3894;3894.084890050836;650.5641220382355;657.6152950523901;661.9805499530765;667.7562689618254;670.735059651435;688.5868478912612;727.8689835829496;730.1967835381145;817.8999246014101;845.0662018469626;10391.84412233817;10476.947615262028;10692.09117225029;11314.603988860516;11388.829167562493;779.2091027606983;796.6335485676653;811.0267678785481;818.2390376949494;819.2954059508818;4988.950110498921;5130.7947682093045;5161.174212126042;5232.816851410183;5272.762725677354;128342;131349;131449;133211;133346.33326833497;111189;115188;116001.4199928995;117170;118868.40565797116;82622.58688706638;100732.49633751785;101471;102148.48925755324;103034.48482757538;9877.587579617662;10391.116755075904;10463.476826158814;10536.894631053592;10662.520186591777;56213.43786562082;57614.423855760906;57894.710526447096;58356.708216458646;58853.41146588479;76310.23689763031;81835.18164818276;84638.57680711558;84767.5761621188;88899.55550222208;13586.728265434438;13660.453581856615;13892.791608126088;13945.721085578434;13987;16305.347786088423;16395;16660.58348541296;17634.82365176332;17660;13545.864541354855;13567.674113008947;13655.726885462434;14001.719965600427;14080.014398991972;12883.677908052374;13160.934195328962;13413.86586134126;13704.588862334145;14000.0199986;13625.114367566077;14086.22525761093;14601;14765.630859228175;15500.224988750742;11780.293182409083;12126.939365303117;12315.137940344448;12335.198212116184;12663.493460261487;12741.235525868478;12748.171368860994;12918.93540532329;13191.538296159588;13282.601521954357 09:25:00 INFO - PROCESS | 31166 | __end_tp_report 09:25:00 INFO - PROCESS | 31166 | __start_cc_report 09:25:00 INFO - PROCESS | 31166 | _x_x_mozilla_cycle_collect,918 09:25:00 INFO - PROCESS | 31166 | __end_cc_report 09:25:00 INFO - PROCESS | 31166 | __startTimestamp1478885100424__endTimestamp 09:25:00 INFO - PROCESS | 31166 | 09:25:00 INFO - PROCESS | 31166 | ------- Summary: start ------- 09:25:00 INFO - PROCESS | 31166 | Number of tests: 6 09:25:00 INFO - PROCESS | 31166 | 09:25:00 INFO - PROCESS | 31166 | [#0] dojo.html Cycles:165 Average:5678.61 Median:5905.88 stddev:668.16 (11.3%) stddev-sans-first:640.58 09:25:00 INFO - PROCESS | 31166 | Values: 3170.3 3208.9 3235.8 3254.8 3274.1 5657.7 5729.5 5747.9 5750.1 5764.8 5711.2 5769.2 5770.8 5780.8 5784.2 5197.3 5204.1 5225.5 5239.4 5296.7 5032.0 5053.9 5296.3 5303.1 5338.4 5078.3 5106.9 5120.5 5140.1 5179.8 5163.9 5241.9 5249.2 5250.3 5285.2 4982.9 5074.6 5144.0 5184.7 5212.5 5497.8 5503.9 5523.3 5526.9 5576.2 5953.2 5992.3 6082.8 6089.5 6091.4 5901.4 6111.1 6112.3 6125.3 6133.0 5552.5 5670.6 5741.5 5744.6 5789.2 6336.0 6357.3 6365.4 6366.4 6369.0 5759.7 6098.9 6101.6 6110.3 6110.6 5896.0 6058.2 6123.5 6131.3 6237.6 6189.3 6193.7 6199.1 6200.0 6204.9 6153.8 6220.6 6221.1 6225.0 6226.1 4514.3 4547.7 4596.9 4609.5 4625.5 4156.4 4157.0 4177.2 4190.9 4192.3 5806.0 5818.5 5824.4 5825.4 5830.4 5919.8 5982.0 6083.8 6187.0 6207.3 5778.5 5833.5 5845.6 5873.1 5879.2 5262.8 5279.8 5292.3 5293.7 5293.8 6087.1 6134.4 6342.7 6408.8 6410.1 6044.5 6094.7 6098.2 6184.0 6189.4 6344.5 6359.9 6360.1 6369.4 6370.0 5801.4 6052.8 6053.0 6057.1 6066.3 6020.2 6025.9 6055.6 6068.3 6135.8 6034.1 6038.9 6041.9 6045.8 6062.5 6015.7 6021.6 6024.5 6026.1 6026.4 5688.3 5824.9 5905.9 5952.9 5981.6 6038.9 6085.0 6086.2 6092.4 6122.6 5598.6 5950.8 5961.2 5963.7 5975.6 09:25:00 INFO - PROCESS | 31166 | 09:25:00 INFO - PROCESS | 31166 | [#1] ext.html Cycles:180 Average:63227.75 Median:21592.91 stddev:127586.49 (590.9%) stddev-sans-first:127857.73 09:25:00 INFO - PROCESS | 31166 | Values: 591.8 616.4 632.0 642.6 651.3 34474.1 46637.5 46733.0 46794.0 46870.8 48031.0 48544.0 48549.8 48619.0 48639.8 8512.7 9210.7 9467.2 9478.9 9479.1 8389.5 8651.9 8659.4 10957.2 11238.5 11374.0 11431.7 11433.5 11435.0 11437.4 11227.7 11276.5 11292.7 11310.5 11311.9 11102.1 11147.0 11176.2 11179.3 11182.4 47449.0 47717.8 47802.5 47837.3 47965.0 3712.8 3757.7 3814.5 3892.6 4030.8 35061.8 35234.8 35280.3 35370.3 35518.0 4677.5 4704.9 4746.4 4759.5 4833.7 251488.0 253142.0 253225.0 253459.0 253716.0 66461.0 66721.0 66894.7 66921.3 66962.7 41593.6 42161.6 42168.6 43541.1 70676.3 18665.0 18732.7 18753.6 18818.6 18862.3 15687.8 15813.8 15824.0 15826.0 15829.2 18843.2 19275.4 20798.7 21251.6 21282.8 7515.2 7712.3 7721.9 7783.7 7864.2 997.1 1096.2 1116.0 1143.7 1180.7 35597.6 36022.5 36147.8 36162.0 36179.6 1153.4 1157.9 1183.9 1200.2 1230.3 17779.4 18729.7 18994.2 19057.9 19064.1 611417.0 613073.0 617689.0 618548.0 622110.0 64785.0 65498.0 65551.7 65562.7 65718.3 1348.7 1395.2 1402.4 1459.8 1475.5 29576.7 35571.0 35996.0 38360.6 39155.0 62200.0 62774.4 62845.4 62895.0 62920.0 488548.0 492537.0 494529.0 496185.0 496619.0 28878.1 28966.3 28997.0 31770.0 32343.5 48904.0 49445.0 49608.8 49862.3 50276.5 41380.6 42292.4 42412.0 42547.4 43449.3 41337.8 42060.0 42483.6 42875.4 44260.3 42743.6 43050.0 43680.3 44330.3 44496.0 35564.5 37272.6 38196.2 38367.4 38715.0 28718.7 29388.0 29497.4 29642.3 29690.0 09:25:00 INFO - PROCESS | 31166 | 09:25:00 INFO - PROCESS | 31166 | [#2] jquery.html Cycles:180 Average:27943.07 Median:22666.99 stddev:71608.36 (315.9%) stddev-sans-first:71779.38 09:25:00 INFO - PROCESS | 31166 | Values: 399.1 407.7 424.6 427.2 438.6 14522.2 16307.9 19993.0 20541.2 21045.5 19381.1 19950.3 20344.6 20548.2 20823.3 5763.1 5828.0 5869.4 5925.9 5927.0 4362.1 5792.5 5868.1 5876.2 5876.4 4400.8 6446.5 6469.1 6499.1 6526.5 6434.3 6516.5 6528.9 6535.2 6549.8 5671.1 5772.7 5804.6 5809.1 5810.6 23787.4 23793.0 23852.2 24023.5 24267.4 2566.1 2585.9 2714.2 2916.7 3240.5 14938.2 14995.7 15051.1 15064.5 15111.6 1832.3 1900.1 2011.2 2060.6 2091.1 63375.4 63526.0 70648.3 74977.0 85652.0 10957.7 11102.9 11291.5 11298.3 11481.6 16264.3 16292.1 16297.4 16309.0 16310.6 8852.6 8883.1 9094.1 9311.0 9332.1 8938.2 8957.2 9006.0 9128.2 9173.0 6855.6 6911.1 6939.0 6954.9 7127.9 2002.6 2024.3 2059.9 2062.4 2082.2 955.5 1046.6 1066.4 1108.4 1135.0 20632.9 21122.7 21190.5 21341.5 21546.6 1048.4 1083.4 1088.1 1089.2 1128.8 11150.9 12071.0 12204.1 12218.6 12250.8 369102.0 376366.0 378128.0 505367.0 507715.0 91128.5 97751.0 98829.0 101724.0 103032.5 1066.8 1067.1 1104.3 1107.9 1118.1 18208.4 18363.5 18613.3 18648.3 18925.7 39668.0 43433.3 44344.0 44693.3 44813.3 60336.0 61069.0 62214.7 62495.0 62767.0 8866.2 8931.0 8952.1 9244.2 9289.1 10130.6 13444.3 13794.0 14058.8 14341.2 12196.6 12481.8 12552.9 12629.7 13095.1 12166.5 12272.7 12493.0 12591.1 12753.9 12070.8 12093.3 12603.1 12663.1 12735.9 9182.6 9411.5 9451.1 9637.9 9645.0 10839.8 11564.3 12205.6 12422.5 12560.1 09:25:00 INFO - PROCESS | 31166 | 09:25:00 INFO - PROCESS | 31166 | [#3] mootools.html Cycles:180 Average:6355.67 Median:4840.12 stddev:3674.19 (75.9%) stddev-sans-first:3666.00 09:25:00 INFO - PROCESS | 31166 | Values: 1450.3 1570.9 1588.6 1683.0 1820.0 8533.9 9321.9 9578.3 9591.2 9868.2 9643.6 9760.8 9767.4 9769.5 9777.4 2568.9 2596.5 2601.2 2602.6 2603.9 2526.9 2551.8 2556.3 2557.7 2561.4 2471.1 2488.0 2544.7 2558.7 2563.7 2415.2 2431.2 2437.6 2494.6 2495.6 2403.5 2419.8 2424.6 2427.0 2448.2 9380.8 9550.6 9632.0 9632.0 9747.5 5791.7 5959.9 5966.6 5968.0 5970.4 3734.7 3737.8 3777.1 3839.3 3856.4 1324.5 1331.6 1334.8 1354.2 1362.2 13712.7 13726.2 13767.5 13913.8 13915.1 4910.5 4955.9 4978.7 5011.7 5116.2 10840.9 10856.1 10859.7 10862.3 10867.8 5795.7 5806.1 5806.7 5807.4 5813.4 4718.4 4769.8 5190.9 5287.3 5411.0 3818.3 3898.2 3930.9 3946.7 3993.8 1070.7 1080.8 1091.8 1096.5 1098.1 957.9 1029.2 1061.5 1063.1 1066.7 6635.9 6716.1 6761.9 6794.8 6872.9 516.1 530.2 535.9 536.8 541.2 4628.4 4645.7 4647.0 4651.3 4674.9 11705.6 12076.4 12115.9 12116.6 12123.5 11343.4 11826.3 11827.5 12059.6 12061.0 8963.0 9045.9 9097.8 9181.8 9190.4 6602.4 6717.9 6786.7 6877.8 6959.2 10683.9 11133.4 11149.9 11288.3 11470.5 11287.1 11411.5 11429.8 11445.6 11544.3 7682.2 7898.5 8350.3 8604.7 8835.9 9395.1 9716.8 9740.3 9747.9 9759.7 8384.2 8425.5 8477.6 8504.9 8523.9 8300.8 8307.1 8316.0 8351.5 8604.0 7776.9 7796.9 7805.3 7862.3 7873.7 5380.8 5626.0 5647.0 5704.4 5730.1 5689.5 5797.4 5801.8 5852.9 5902.8 09:25:00 INFO - PROCESS | 31166 | 09:25:00 INFO - PROCESS | 31166 | [#4] prototype.html Cycles:180 Average:4660.13 Median:5120.60 stddev:1323.19 (25.8%) stddev-sans-first:1286.75 09:25:00 INFO - PROCESS | 31166 | Values: 350.1 353.9 357.3 358.4 364.0 5515.0 5522.7 5546.6 5551.2 5585.3 5465.9 5469.5 5475.2 5481.9 5483.3 3784.4 4115.3 4253.9 4324.8 4351.7 4338.6 4342.5 4345.6 4345.7 4351.7 4309.5 4321.6 4328.7 4332.1 4344.5 4050.2 4130.2 4186.8 4337.7 4348.8 3858.3 3865.6 3873.7 3877.2 3892.3 5293.5 5304.4 5306.0 5311.4 5362.2 4787.4 4921.3 4957.0 4965.9 4978.0 5693.5 5697.7 5697.9 5707.0 5717.9 4232.4 4236.3 4255.8 4263.9 4269.2 5573.7 5680.4 5756.4 5852.9 5897.4 4204.7 4319.8 4323.3 4323.8 4329.1 4385.9 4386.4 4387.5 4391.3 4392.8 4771.9 4791.9 4921.4 4943.3 4955.5 5647.5 5675.6 5677.6 5684.1 5695.2 1495.5 1510.9 1515.7 1517.7 1520.5 920.6 967.8 982.2 983.6 992.0 4860.6 4871.9 4881.5 4882.8 4888.0 5123.5 5131.4 5151.9 5175.9 5178.3 4538.7 4862.6 4870.7 4891.3 4897.3 2720.1 2723.9 2724.9 2739.2 2742.6 5271.0 5272.8 5275.3 5276.2 5277.3 4858.5 5189.0 5200.9 5206.4 5225.8 5232.7 5242.6 5248.4 5263.3 5275.1 5087.7 5093.3 5106.2 5114.6 5117.7 4734.6 5014.6 5064.8 5065.3 5067.4 5261.4 5803.4 5883.9 5951.5 6109.0 4322.6 4354.7 4363.5 4381.0 4399.3 5663.4 5875.4 5894.7 5897.6 5945.7 5703.7 5721.9 5742.8 5747.0 5751.4 5680.2 5713.9 5726.9 5729.0 5731.8 5414.4 5669.5 5681.6 5682.7 5713.3 5707.4 5720.0 5722.7 5724.4 5758.8 5613.6 5618.9 5646.7 5663.5 5665.9 09:25:00 INFO - PROCESS | 31166 | 09:25:00 INFO - PROCESS | 31166 | [#5] yui.html Cycles:180 Average:23446.61 Median:24093.32 stddev:34330.03 (142.5%) stddev-sans-first:34384.15 09:25:00 INFO - PROCESS | 31166 | Values: 781.6 816.7 824.7 845.5 877.2 16499.3 18249.8 18253.9 18274.1 18328.2 16213.3 16376.0 16415.6 16500.2 16517.2 732.6 747.6 875.2 880.8 908.6 879.4 880.8 881.5 881.7 882.2 930.0 932.2 933.1 933.3 933.3 770.6 790.0 913.2 925.3 930.5 681.2 683.1 683.1 683.8 683.9 16451.2 16466.0 16493.7 16500.1 16536.3 15598.4 15605.5 15783.2 15998.3 16052.8 14065.3 14095.2 14319.2 14354.0 14381.8 4676.1 4708.8 4727.1 4757.9 4871.9 84242.6 85810.6 85816.6 87416.0 89607.0 12467.2 12482.6 12570.2 12614.2 12851.7 23954.4 24040.6 24083.9 24102.8 24480.8 8114.1 8601.7 8644.7 8806.3 8892.4 4868.1 5014.2 5039.9 5114.3 5217.0 3741.9 3883.9 3893.0 3894.0 3894.1 650.6 657.6 662.0 667.8 670.7 688.6 727.9 730.2 817.9 845.1 10391.8 10476.9 10692.1 11314.6 11388.8 779.2 796.6 811.0 818.2 819.3 4989.0 5130.8 5161.2 5232.8 5272.8 128342.0 131349.0 131449.0 133211.0 133346.3 111189.0 115188.0 116001.4 117170.0 118868.4 82622.6 100732.5 101471.0 102148.5 103034.5 9877.6 10391.1 10463.5 10536.9 10662.5 56213.4 57614.4 57894.7 58356.7 58853.4 76310.2 81835.2 84638.6 84767.6 88899.6 13586.7 13660.5 13892.8 13945.7 13987.0 16305.3 16395.0 16660.6 17634.8 17660.0 13545.9 13567.7 13655.7 14001.7 14080.0 12883.7 13160.9 13413.9 13704.6 14000.0 13625.1 14086.2 14601.0 14765.6 15500.2 11780.3 12126.9 12315.1 12335.2 12663.5 12741.2 12748.2 12918.9 13191.5 13282.6 09:25:00 INFO - PROCESS | 31166 | -------- Summary: end -------- 09:25:00 INFO - PROCESS | 31166 | 09:25:01 INFO - TEST-INFO | 31166: exit 0 09:25:01 INFO - TEST-OK | dromaeo_css | took 1113772ms 09:25:01 INFO - TEST-START | kraken 09:25:01 INFO - operating with platform_type : linux_ 09:25:01 INFO - Initialising browser for kraken test... 09:25:01 INFO - TEST-INFO | started process 31487 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFHk7W7/profile http://localhost:38418/getInfo.html) 09:25:05 INFO - PROCESS | 31487 | __metrics Screen width/height:1600/1200 09:25:05 INFO - PROCESS | 31487 | colorDepth:24 09:25:05 INFO - PROCESS | 31487 | Browser inner width/height: 1024/697 09:25:05 INFO - PROCESS | 31487 | __metrics 09:25:05 INFO - PROCESS | 31487 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:25:05 INFO - PROCESS | 31487 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:25:06 INFO - PROCESS | 31487 | console.error: PushService: 09:25:06 INFO - PROCESS | 31487 | stateChangeProcessEnqueue: Error transitioning state 09:25:06 INFO - PROCESS | 31487 | UnknownError 09:25:06 INFO - TEST-INFO | 31487: exit 0 09:25:06 INFO - Browser initialized. 09:25:06 INFO - Running cycle 1/1 for kraken test... 09:25:06 INFO - TEST-INFO | started process 31578 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFHk7W7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:25:18 INFO - PROCESS | 31578 | RSS: Main: 145080320 09:25:18 INFO - PROCESS | 31578 | 09:25:21 INFO - PROCESS | 31578 | Cycle 1(1): loaded http://localhost:38418/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38418/tests/kraken/driver.html?testName=audio-beat-detection) 09:25:21 INFO - PROCESS | 31578 | RSS: Main: 185483264 09:25:21 INFO - PROCESS | 31578 | 09:25:24 INFO - PROCESS | 31578 | Cycle 1(1): loaded http://localhost:38418/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38418/tests/kraken/driver.html?testName=audio-dft) 09:25:25 INFO - PROCESS | 31578 | RSS: Main: 175362048 09:25:25 INFO - PROCESS | 31578 | 09:25:27 INFO - PROCESS | 31578 | Cycle 1(1): loaded http://localhost:38418/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38418/tests/kraken/driver.html?testName=audio-fft) 09:25:28 INFO - PROCESS | 31578 | RSS: Main: 267628544 09:25:28 INFO - PROCESS | 31578 | 09:25:30 INFO - PROCESS | 31578 | Cycle 1(1): loaded http://localhost:38418/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38418/tests/kraken/driver.html?testName=audio-oscillator) 09:25:31 INFO - PROCESS | 31578 | RSS: Main: 174297088 09:25:31 INFO - PROCESS | 31578 | 09:25:33 INFO - PROCESS | 31578 | Cycle 1(1): loaded http://localhost:38418/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38418/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:25:33 INFO - PROCESS | 31578 | RSS: Main: 175300608 09:25:33 INFO - PROCESS | 31578 | 09:25:36 INFO - PROCESS | 31578 | Cycle 1(1): loaded http://localhost:38418/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38418/tests/kraken/driver.html?testName=imaging-darkroom) 09:25:37 INFO - PROCESS | 31578 | RSS: Main: 211193856 09:25:37 INFO - PROCESS | 31578 | 09:25:40 INFO - PROCESS | 31578 | Cycle 1(1): loaded http://localhost:38418/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38418/tests/kraken/driver.html?testName=imaging-desaturate) 09:25:41 INFO - PROCESS | 31578 | RSS: Main: 210227200 09:25:41 INFO - PROCESS | 31578 | 09:25:44 INFO - PROCESS | 31578 | Cycle 1(1): loaded http://localhost:38418/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38418/tests/kraken/driver.html?testName=json-parse-financial) 09:25:44 INFO - PROCESS | 31578 | RSS: Main: 209641472 09:25:44 INFO - PROCESS | 31578 | 09:25:46 INFO - PROCESS | 31578 | Cycle 1(1): loaded http://localhost:38418/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38418/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:25:46 INFO - PROCESS | 31578 | RSS: Main: 171950080 09:25:46 INFO - PROCESS | 31578 | 09:25:48 INFO - PROCESS | 31578 | Cycle 1(1): loaded http://localhost:38418/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38418/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:25:49 INFO - PROCESS | 31578 | RSS: Main: 179019776 09:25:49 INFO - PROCESS | 31578 | 09:25:50 INFO - PROCESS | 31578 | Cycle 1(1): loaded http://localhost:38418/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38418/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:25:51 INFO - PROCESS | 31578 | RSS: Main: 188743680 09:25:51 INFO - PROCESS | 31578 | 09:25:54 INFO - PROCESS | 31578 | Cycle 1(1): loaded http://localhost:38418/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38418/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:25:54 INFO - PROCESS | 31578 | RSS: Main: 197222400 09:25:54 INFO - PROCESS | 31578 | 09:25:57 INFO - PROCESS | 31578 | Cycle 1(1): loaded http://localhost:38418/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38418/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:25:58 INFO - PROCESS | 31578 | RSS: Main: 193556480 09:25:58 INFO - PROCESS | 31578 | 09:25:59 INFO - PROCESS | 31578 | Cycle 1(1): loaded http://localhost:38418/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38418/tests/kraken/driver.html?testName=ai-astar) 09:25:59 INFO - PROCESS | 31578 | RSS: Main: 244813824 09:25:59 INFO - PROCESS | 31578 | 09:25:59 INFO - PROCESS | 31578 | __start_tp_report 09:25:59 INFO - PROCESS | 31578 | _x_x_mozilla_page_load 09:25:59 INFO - PROCESS | 31578 | _x_x_mozilla_page_load_details 09:25:59 INFO - PROCESS | 31578 | |i|pagename|runs| 09:25:59 INFO - PROCESS | 31578 | |0;ai-astar;112;111;113;111;112;111;111;111;109;111 09:25:59 INFO - PROCESS | 31578 | |1;audio-beat-detection;153;120;122;156;119;125;118;118;148;118 09:25:59 INFO - PROCESS | 31578 | |2;audio-dft;159;160;159;160;159;161;160;161;160;161 09:25:59 INFO - PROCESS | 31578 | |3;audio-fft;74;77;75;109;75;76;75;76;103;74 09:25:59 INFO - PROCESS | 31578 | |4;audio-oscillator;82;81;112;82;82;82;100;82;81;108 09:25:59 INFO - PROCESS | 31578 | |5;imaging-gaussian-blur;117;119;117;117;116;117;117;117;116;117 09:25:59 INFO - PROCESS | 31578 | |6;imaging-darkroom;145;146;145;145;146;145;145;146;145;145 09:25:59 INFO - PROCESS | 31578 | |7;imaging-desaturate;95;94;95;96;95;95;94;96;95;94 09:25:59 INFO - PROCESS | 31578 | |8;json-parse-financial;52;51;51;50;49;50;50;51;50;50 09:25:59 INFO - PROCESS | 31578 | |9;json-stringify-tinderbox;59;59;60;59;59;60;59;59;59;59 09:25:59 INFO - PROCESS | 31578 | |10;stanford-crypto-aes;82;76;73;74;75;78;73;73;72;73 09:25:59 INFO - PROCESS | 31578 | |11;stanford-crypto-ccm;137;128;124;122;131;134;118;118;161;118 09:25:59 INFO - PROCESS | 31578 | |12;stanford-crypto-pbkdf2;161;156;153;153;155;152;151;151;151;150 09:25:59 INFO - PROCESS | 31578 | |13;stanford-crypto-sha256-iterative;63;62;62;61;62;63;61;65;61;60 09:25:59 INFO - PROCESS | 31578 | __end_tp_report 09:25:59 INFO - PROCESS | 31578 | __start_cc_report 09:25:59 INFO - PROCESS | 31578 | _x_x_mozilla_cycle_collect,1653 09:25:59 INFO - PROCESS | 31578 | __end_cc_report 09:25:59 INFO - PROCESS | 31578 | __startTimestamp1478885159940__endTimestamp 09:25:59 INFO - PROCESS | 31578 | 09:25:59 INFO - PROCESS | 31578 | ------- Summary: start ------- 09:25:59 INFO - PROCESS | 31578 | Number of tests: 14 09:25:59 INFO - PROCESS | 31578 | 09:25:59 INFO - PROCESS | 31578 | [#0] ai-astar Cycles:10 Average:111.20 Median:111.00 stddev:1.03 (0.9%) stddev-sans-first:1.05 09:25:59 INFO - PROCESS | 31578 | Values: 112.0 111.0 113.0 111.0 112.0 111.0 111.0 111.0 109.0 111.0 09:25:59 INFO - PROCESS | 31578 | 09:25:59 INFO - PROCESS | 31578 | [#1] audio-beat-detection Cycles:10 Average:129.70 Median:123.50 stddev:15.88 (12.9%) stddev-sans-first:14.43 09:25:59 INFO - PROCESS | 31578 | Values: 153.0 120.0 122.0 156.0 119.0 125.0 118.0 118.0 148.0 118.0 09:25:59 INFO - PROCESS | 31578 | 09:25:59 INFO - PROCESS | 31578 | [#2] audio-dft Cycles:10 Average:160.00 Median:160.00 stddev:0.82 (0.5%) stddev-sans-first:0.78 09:25:59 INFO - PROCESS | 31578 | Values: 159.0 160.0 159.0 160.0 159.0 161.0 160.0 161.0 160.0 161.0 09:25:59 INFO - PROCESS | 31578 | 09:25:59 INFO - PROCESS | 31578 | [#3] audio-fft Cycles:10 Average:81.40 Median:75.00 stddev:13.07 (17.4%) stddev-sans-first:13.59 09:25:59 INFO - PROCESS | 31578 | Values: 74.0 77.0 75.0 109.0 75.0 76.0 75.0 76.0 103.0 74.0 09:25:59 INFO - PROCESS | 31578 | 09:25:59 INFO - PROCESS | 31578 | [#4] audio-oscillator Cycles:10 Average:89.20 Median:82.00 stddev:12.40 (15.1%) stddev-sans-first:12.87 09:25:59 INFO - PROCESS | 31578 | Values: 82.0 81.0 112.0 82.0 82.0 82.0 100.0 82.0 81.0 108.0 09:25:59 INFO - PROCESS | 31578 | 09:25:59 INFO - PROCESS | 31578 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.82 (0.7%) stddev-sans-first:0.87 09:25:59 INFO - PROCESS | 31578 | Values: 117.0 119.0 117.0 117.0 116.0 117.0 117.0 117.0 116.0 117.0 09:25:59 INFO - PROCESS | 31578 | 09:25:59 INFO - PROCESS | 31578 | [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 09:25:59 INFO - PROCESS | 31578 | Values: 145.0 146.0 145.0 145.0 146.0 145.0 145.0 146.0 145.0 145.0 09:25:59 INFO - PROCESS | 31578 | 09:25:59 INFO - PROCESS | 31578 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 09:25:59 INFO - PROCESS | 31578 | Values: 95.0 94.0 95.0 96.0 95.0 95.0 94.0 96.0 95.0 94.0 09:25:59 INFO - PROCESS | 31578 | 09:25:59 INFO - PROCESS | 31578 | [#8] json-parse-financial Cycles:10 Average:50.40 Median:50.50 stddev:0.84 (1.7%) stddev-sans-first:0.67 09:25:59 INFO - PROCESS | 31578 | Values: 52.0 51.0 51.0 50.0 49.0 50.0 50.0 51.0 50.0 50.0 09:25:59 INFO - PROCESS | 31578 | 09:25:59 INFO - PROCESS | 31578 | [#9] json-stringify-tinderbox Cycles:10 Average:59.20 Median:59.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 09:25:59 INFO - PROCESS | 31578 | Values: 59.0 59.0 60.0 59.0 59.0 60.0 59.0 59.0 59.0 59.0 09:25:59 INFO - PROCESS | 31578 | 09:25:59 INFO - PROCESS | 31578 | [#10] stanford-crypto-aes Cycles:10 Average:74.90 Median:74.50 stddev:3.07 (4.1%) stddev-sans-first:1.90 09:25:59 INFO - PROCESS | 31578 | Values: 82.0 76.0 73.0 74.0 75.0 78.0 73.0 73.0 72.0 73.0 09:25:59 INFO - PROCESS | 31578 | 09:25:59 INFO - PROCESS | 31578 | [#11] stanford-crypto-ccm Cycles:10 Average:129.10 Median:129.50 stddev:13.14 (10.1%) stddev-sans-first:13.63 09:25:59 INFO - PROCESS | 31578 | Values: 137.0 128.0 124.0 122.0 131.0 134.0 118.0 118.0 161.0 118.0 09:25:59 INFO - PROCESS | 31578 | 09:25:59 INFO - PROCESS | 31578 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.30 Median:153.00 stddev:3.30 (2.2%) stddev-sans-first:2.01 09:25:59 INFO - PROCESS | 31578 | Values: 161.0 156.0 153.0 153.0 155.0 152.0 151.0 151.0 151.0 150.0 09:25:59 INFO - PROCESS | 31578 | 09:25:59 INFO - PROCESS | 31578 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.00 Median:62.00 stddev:1.41 (2.3%) stddev-sans-first:1.45 09:25:59 INFO - PROCESS | 31578 | Values: 63.0 62.0 62.0 61.0 62.0 63.0 61.0 65.0 61.0 60.0 09:25:59 INFO - PROCESS | 31578 | -------- Summary: end -------- 09:25:59 INFO - PROCESS | 31578 | 09:26:01 INFO - TEST-INFO | 31578: exit 0 09:26:01 INFO - TEST-OK | kraken | took 59873ms 09:26:01 INFO - SUITE-END | took 1173s 09:26:01 INFO - Completed test suite (00:19:34) 09:26:01 INFO - javascript benchmark 09:26:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3170.3183815479674, 3208.9358212835746, 3235.838208089595, 3254.772165948382, 3274.1487213324526, 5657.688827114507, 5729.484346408823, 5747.885042299156, 5750.079987202049, 5764.7982320618785, 5711.200431939521, 5769.249997500319, 5770.798022069229, 5780.82657520274, 5784.1902133701315, 5197.272381866542, 5204.063268611649, 5225.477452254782, 5239.423663397023, 5296.682199068068, 5031.974840125794, 5053.898922021557, 5296.3379577552805, 5303.098473259555, 5338.359396872381, 5078.26365177051, 5106.897862042757, 5120.4623514530995, 5140.126178549637, 5179.792808287664, 5163.896722065557, 5241.86895327616, 5249.238860365244, 5250.264962905197, 5285.180796976475, 4982.90034199314, 5074.619403544749, 5143.974280128613, 5184.7148406837605, 5212.50481204285, 5497.752601132958, 5503.944960550383, 5523.337199536081, 5526.944730552683, 5576.191452239443, 5953.166556682068, 5992.31088424828, 6082.847928801815, 6089.543284253698, 6091.360407157229, 5901.3508514063415, 6111.052786818051, 6112.327643959161, 6125.264968203799, 6132.969335153341, 5552.472515111058, 5670.6030577859565, 5741.540676745892, 5744.626599271118, 5789.247397838254, 6336.049592561153, 6357.332480089616, 6365.363463653597, 6366.395192456709, 6368.968155159195, 5759.740811663485, 6098.908516372258, 6101.572889897753, 6110.29731580865, 6110.602810817282, 5895.970520147458, 6058.242719660043, 6123.510119190454, 6131.264248290234, 6237.625742455467, 6189.288231853305, 6193.6903154842075, 6199.070139479024, 6200.038993955944, 6204.937950620526, 6153.84615384619, 6220.564560480813, 6221.06683997404, 6224.968875155686, 6226.128342032119, 4514.255147900625, 4547.658925580595, 4596.908061838744, 4609.492955774862, 4625.468071171791, 4156.418101465797, 4157.043879907638, 4177.248095342868, 4190.8533201337805, 4192.329227323614, 5806.04200306953, 5818.5345172386105, 5824.446677565625, 5825.417458254141, 5830.358660547421, 5919.822405327847, 5981.970090149608, 6083.756649733961, 6186.969065154645, 6207.286162091417, 5778.508826749689, 5833.4749872511675, 5845.649261044351, 5873.119032145212, 5879.176915231871, 5262.842114736564, 5279.815206467755, 5292.285541451854, 5293.735313234323, 5293.814716484904, 6087.086936959491, 6134.4172303631085, 6342.714577844008, 6408.775692850728, 6410.0705397717065, 6044.455998960115, 6094.695265236809, 6098.176746139215, 6184.041473571604, 6189.35011823752, 6344.460720838781, 6359.90460143089, 6360.077788720702, 6369.363063693593, 6370.044493326038, 5801.448862358238, 6052.788152414644, 6052.969735151296, 6057.061155520902, 6066.272047354346, 6020.157177995172, 6025.909611355745, 6055.6063855849225, 6068.302145253176, 6135.814547850547, 6034.064719968412, 6038.8792224154395, 6041.879162416815, 6045.848853778515, 6062.5453091019235, 6015.729292181775, 6021.578489505692, 6024.5481588881885, 6026.096085587197, 6026.397360263939, 5688.345840228261, 5824.854378640568, 5905.881882362414, 5952.8809423812145, 5981.551383646157, 6038.909416358846, 6084.951795445132, 6086.169334381299, 6092.421219984094, 6122.632642041492, 5598.608097433141, 5950.761969521171, 5961.195238642727, 5963.701814909324, 5975.581709280309], "value": 5631.752620169788, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [591.8224532640207, 616.3713012727019, 631.9589226700264, 642.5855323316463, 651.3161180760201, 34474.13814654632, 46637.533624663745, 46733.0, 46794.0, 46870.76564617181, 48031.0, 48544.0, 48549.757251213785, 48619.0, 48639.75680121603, 8512.659493620278, 9210.677626283064, 9467.195288400495, 9478.905210947905, 9479.099485548897, 8389.496630202177, 8651.913480865174, 8659.393842431033, 10957.232993690444, 11238.494267757971, 11374.0, 11431.714207144805, 11433.485493152786, 11435.028022618088, 11437.370944598044, 11227.719307017309, 11276.548938042468, 11292.661220163325, 11310.547578096866, 11311.943440282828, 11102.111831053497, 11146.996770290632, 11176.161787865862, 11179.273347232403, 11182.384968826711, 47449.0, 47717.76141119307, 47802.5219747805, 47837.28244076342, 47965.0, 3712.7586706863967, 3757.6993840492696, 3814.5231846019246, 3892.6107389260847, 4030.8186131624147, 35061.82469087664, 35234.8238258807, 35280.29439411223, 35370.29259414823, 35518.0, 4677.4620918594455, 4704.905901881977, 4746.406699162605, 4759.5002524734755, 4833.685810422347, 251488.0, 253142.0, 253225.0, 253459.0, 253716.0, 66461.0, 66721.0, 66894.66552667302, 66921.33078669151, 66962.66518667375, 41593.58406415958, 42161.57838421638, 42168.578314216466, 43541.12917741691, 70676.293237068, 18665.0, 18732.719009214874, 18753.624927501372, 18818.623627527373, 18862.339818106302, 15687.764683529527, 15813.762793557875, 15824.0, 15825.971311864692, 15829.208539573207, 18843.246270149313, 19275.4217373479, 20798.688019679717, 21251.574968500543, 21282.78717212808, 7515.248475152441, 7712.344450721639, 7721.884171737428, 7783.688652453951, 7864.174261702553, 997.0826839307854, 1096.1669131460148, 1115.9944200278946, 1143.6912033750841, 1180.6694125644833, 35597.644023559435, 36022.45966310507, 36147.819260903525, 36162.0, 36179.63820361763, 1153.405995912106, 1157.89578937896, 1183.893449589541, 1200.1778781534676, 1230.3110258255406, 17779.377721779936, 18729.662762580607, 18994.24023039063, 19057.90471047636, 19064.14211360492, 611417.0, 613073.0, 617689.0, 618548.0, 622110.0, 64785.0, 65498.0, 65551.67224163849, 65562.67218663877, 65718.34281657219, 1348.6830594810212, 1395.232622057864, 1402.382951501336, 1459.846716094816, 1475.4688312207597, 29576.704232957396, 35571.0, 35996.0, 38360.616393835706, 39155.0, 62200.0, 62774.37225627685, 62845.371546283954, 62895.0, 62920.0, 488548.0, 492537.0, 494529.0, 496185.0, 496619.0, 28878.133655990354, 28966.27584310325, 28997.0, 31770.0, 32343.514847276838, 48904.0, 49445.033298002236, 49608.75195623999, 49862.252066219764, 50276.49723502865, 41380.58619413767, 42292.36561451642, 42412.0, 42547.36178957256, 43449.34825977676, 41337.79331103325, 42060.0, 42483.57516424796, 42875.35686964761, 44260.33609495796, 42743.572564273956, 43050.0, 43680.344794828736, 44330.335044973704, 44496.0, 35564.466533001505, 37272.627273728, 38196.236075278895, 38367.42448863325, 38715.0, 28718.71281287244, 29388.0, 29497.410051799274, 29642.258943526584, 29690.0], "value": 21769.534595004832, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.0522509041024, 407.69830325559093, 424.64117820441726, 427.22885192228017, 438.6162108155364, 14522.201278929668, 16307.918460407696, 19993.0, 20541.178352865903, 21045.473863153427, 19381.127849246783, 19950.30173943912, 20344.59310813783, 20548.17807287707, 20823.27118550851, 5763.1355296705415, 5828.0, 5869.442402971711, 5925.940740592582, 5927.0, 4362.1275744851, 5792.478676919077, 5868.149118377854, 5876.177335173079, 5876.382979787104, 4400.7579583122915, 6446.5165112616505, 6469.061986012025, 6499.122618446497, 6526.510511711641, 6434.292227854909, 6516.543841931067, 6528.902066469007, 6535.15043044406, 6549.770758023494, 5671.092625179951, 5772.682502462363, 5804.59367844251, 5809.128630705386, 5810.622309549865, 23787.405314867094, 23793.0, 23852.16517421882, 24023.519529609486, 24267.393315167086, 2566.1275166443497, 2585.896564137432, 2714.226445463053, 2916.737493625563, 3240.5139229115584, 14938.178400187986, 14995.700085998218, 15051.096934183985, 15064.472743453927, 15111.622209444742, 1832.3220408448826, 1900.125942066651, 2011.1955217912953, 2060.6084843879767, 2091.0799248330686, 63375.366246337406, 63526.0, 70648.29351706468, 74976.99999999946, 85652.0, 10957.671269861918, 11102.888971110347, 11291.49188286529, 11298.265612735146, 11481.598144064918, 16264.349426023064, 16292.103934283492, 16297.429589964295, 16309.0, 16310.592235193977, 8852.601632926533, 8883.067277935723, 9094.136057074567, 9311.0, 9332.113449222314, 8938.240249578728, 8957.193852553302, 9005.954970225239, 9128.22410095136, 9172.954135229282, 6855.5543889647015, 6911.101556797584, 6939.004472773654, 6954.9304506955295, 7127.89308160378, 2002.6195022945594, 2024.331970449748, 2059.8764074155297, 2062.391594479632, 2082.1775398717723, 955.47926380123, 1046.5918291866178, 1066.434789561518, 1108.3959242213014, 1135.0262312740508, 20632.896835515727, 21122.683159752614, 21190.47023824386, 21341.46646333823, 21546.569068618854, 1048.4128887822692, 1083.4041277297456, 1088.1403691084113, 1089.2212068370993, 1128.785530749155, 11150.888491114985, 12071.0, 12204.145709800228, 12218.633440996784, 12250.754984900252, 369102.0, 376366.0, 378128.0, 505367.0, 507715.0, 91128.54435727911, 97751.0, 98829.0, 101724.0, 103032.48483757683, 1066.802641511323, 1067.066316972649, 1104.3263609198382, 1107.894749998749, 1118.0999295566985, 18208.36270730518, 18363.54091147732, 18613.348532801287, 18648.254069837596, 18925.716114258572, 39668.0, 43433.3484997719, 44344.0, 44693.329600055375, 44813.32780008237, 60336.0, 61069.0, 62214.68892655508, 62495.0, 62767.0, 8866.246369058445, 8931.0, 8952.14954579314, 9244.214241789254, 9289.117533834275, 10130.594776209164, 13444.327783610976, 13794.0, 14058.789118163031, 14341.211233382264, 12196.57311994076, 12481.750364992467, 12552.874471255536, 12629.684257893627, 13095.083344165816, 12166.45250963691, 12272.693182670148, 12493.0, 12591.055670824542, 12753.872461275269, 12070.75858482843, 12093.334866582423, 12603.117781755556, 12663.113582049167, 12735.87264127384, 9182.632694692138, 9411.529423528933, 9451.149396554343, 9637.855432168382, 9644.987276335884, 10839.783204335712, 11564.306141631529, 12205.633830984729, 12422.503099875903, 12560.12079154487], "value": 9872.46448238885, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1450.2821103553729, 1570.8743300535955, 1588.6425554250293, 1682.9663406731866, 1820.0262859370239, 8533.91466085339, 9321.860172097415, 9578.281628877827, 9591.23270138389, 9868.210543156549, 9643.566039528221, 9760.804783904317, 9767.413955162696, 9769.462679552622, 9777.364471309378, 2568.948621027578, 2596.5196438658813, 2601.1676263595655, 2602.622619720149, 2603.947921041578, 2526.886290116949, 2551.7703406693395, 2556.2586849813533, 2557.6802899637546, 2561.449288402993, 2471.1351027140536, 2488.029668429314, 2544.6819147606548, 2558.731333210014, 2563.74362563744, 2415.1788391946734, 2431.2463136427764, 2437.6465412515263, 2494.550980823451, 2495.5508008558454, 2403.4592216751266, 2419.758024197584, 2424.6484259782233, 2427.0, 2448.192096608115, 9380.812383752354, 9550.61797528098, 9631.951840240754, 9631.951840240825, 9747.463889486075, 5791.681457519835, 5959.851003724898, 5966.642001479925, 5968.045112781978, 5970.373110823344, 3734.663880250763, 3737.8131093445218, 3777.112378591029, 3839.308924393608, 3856.402257650069, 1324.543032653733, 1331.5938638715188, 1334.833145856768, 1354.1536539662711, 1362.1554636125666, 13712.65718357049, 13726.176429414267, 13767.518136865161, 13913.791293130611, 13915.095518791246, 4910.453207239708, 4955.900881982412, 4978.676386034968, 5011.674241174312, 5116.181410974226, 10840.945795271131, 10856.077233435088, 10859.674209773719, 10862.293950893325, 10867.78264434707, 5795.710214489259, 5806.129080637938, 5806.709664516758, 5807.390224026501, 5813.360530341659, 4718.41019872516, 4769.785359658824, 5190.948090519122, 5287.259783630295, 5411.02601531728, 3818.2936156810524, 3898.1813819098306, 3930.9213815723524, 3946.6842652587725, 3993.800309984489, 1070.710908054821, 1080.7730376620998, 1091.8362245663213, 1096.52849274811, 1098.1489345756943, 957.9281553883486, 1029.2177944762034, 1061.4745700878116, 1063.1176123817368, 1066.7013236293851, 6635.933640663531, 6716.059751634775, 6761.898571521431, 6794.830129246809, 6872.931270687229, 516.094254583204, 530.2391068816261, 535.9222912677718, 536.8094326514109, 541.1990254423475, 4628.421447319085, 4645.744484053342, 4646.976765116153, 4651.348811166439, 4674.906501869944, 11705.648830535098, 12076.396180190955, 12115.939420302842, 12116.575919842764, 12123.515059397701, 11343.432828358715, 11826.290422574673, 11827.467763950643, 12059.577914773115, 12061.035117190604, 8962.955185224033, 9045.909540904508, 9097.817283753202, 9181.770455738526, 9190.40262382943, 6602.382416308035, 6717.865642687217, 6786.728530858711, 6877.828054298682, 6959.19969203548, 10683.94658026705, 11133.443327833738, 11149.944250278697, 11288.2662626929, 11470.541178353105, 11287.097032237238, 11411.54353825871, 11429.771404572028, 11445.599404020819, 11544.307341559534, 7682.193369696101, 7898.526088434713, 8350.33197344199, 8604.741857744277, 8835.911640883765, 9395.107464790834, 9716.757081072747, 9740.269479788902, 9747.9025209747, 9759.707208783748, 8384.161583841811, 8425.452345597516, 8477.576121193792, 8504.872426913726, 8523.872141918, 8300.792480188044, 8307.127751585997, 8316.043654979754, 8351.540665263468, 8603.956980215058, 7776.88334674969, 7796.883046754417, 7805.297523222937, 7862.253085956825, 7873.685052597832, 5380.811671591476, 5626.0, 5646.971765141148, 5704.429557044263, 5730.083186690276, 5689.516391106637, 5797.449242321973, 5801.82594522165, 5852.912206316823, 5902.763889444546], "value": 4964.675693403732, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.11421104605347, 353.9380608393532, 357.2622534466326, 358.3531725235948, 363.99818000909977, 5514.972425137874, 5522.723863806808, 5546.584006199531, 5551.222828803966, 5585.273914391134, 5465.890682186354, 5469.480399362064, 5475.151351540518, 5481.890362192754, 5483.314585676791, 3784.375578029622, 4115.279826030448, 4253.914921701564, 4324.84863029794, 4351.65186785058, 4338.609525142737, 4342.500612429563, 4345.587169218935, 4345.69580129391, 4351.6518678505645, 4309.461317335333, 4321.611055005049, 4328.718633288826, 4332.1119170569955, 4344.45694288214, 4050.18996200761, 4130.215259100792, 4186.769727664977, 4337.696361254713, 4348.804303806321, 3858.2862170498265, 3865.594112618191, 3873.7288389812725, 3877.243937432202, 3892.260470510594, 5293.470652934714, 5304.36347638282, 5305.965336759334, 5311.4065780266155, 5362.2224777407055, 4787.449443314029, 4921.335619691332, 4957.0, 4965.925511117336, 4977.950220497785, 5693.459121383504, 5697.6866272355, 5697.886042279131, 5707.0, 5717.885642287172, 4232.407462955189, 4236.343366778155, 4255.829766809324, 4263.850765223233, 4269.231538323101, 5573.749181286852, 5680.403557626431, 5756.395578464243, 5852.941470585325, 5897.4102589741115, 4204.684648651363, 4319.805608747551, 4323.286657701507, 4323.783810809447, 4329.112531930947, 4385.864107163394, 4386.385905973167, 4387.517373088957, 4391.297392417198, 4392.780360982002, 4771.878608526993, 4791.873909631234, 4921.434035085939, 4943.307936888838, 4955.529224723646, 5647.519960803295, 5675.631083979611, 5677.637367031883, 5684.147377893349, 5695.202671625976, 1495.53638372105, 1510.8715759160596, 1515.7120147172002, 1517.7419838627459, 1520.4526370506608, 920.60987908259, 967.8403063494444, 982.1995074014656, 983.5672304186136, 991.9702408927744, 4860.6111511079025, 4871.878203044952, 4881.4630390657, 4882.778335857177, 4887.95112048882, 5123.513266239701, 5131.435542090367, 5151.922721159186, 5175.894463321977, 5178.326817513699, 4538.704984176018, 4862.562369386772, 4870.733609261618, 4891.26631005352, 4897.314375987365, 2720.1231150506087, 2723.945521089567, 2724.931876703098, 2739.2330147558714, 2742.6023226632424, 5270.973645131749, 5272.841814745564, 5275.261463395128, 5276.153346328754, 5277.2875661785165, 4858.489858564871, 5188.9740551297, 5200.947990520046, 5206.3752349717515, 5225.790968361299, 5232.659877107976, 5242.554382877422, 5248.396434410091, 5263.34208223972, 5275.050490911673, 5087.720175390389, 5093.337866077534, 5106.217111674072, 5114.5652619527755, 5117.692938423706, 4734.573888349927, 5014.5737612303365, 5064.797408103635, 5065.265536497261, 5067.366579177603, 5261.421573528003, 5803.361630220588, 5883.911741323798, 5951.464368206882, 6109.0, 4322.610965013164, 4354.716943398669, 4363.498197707304, 4380.956190438055, 4399.318105693623, 5663.4053424391495, 5875.383084776037, 5894.671604716027, 5897.64614123154, 5945.672987985702, 5703.714814259353, 5721.942780572141, 5742.799002035076, 5746.9712651436475, 5751.424857514216, 5680.2047713321, 5713.942860571341, 5726.885462290647, 5729.0, 5731.770729170787, 5414.377346605191, 5669.54643628517, 5681.630694004876, 5682.715864206856, 5713.314402271754, 5707.400722924033, 5719.968416739135, 5722.742476588647, 5724.370319264961, 5758.769649214152, 5613.635113717595, 5618.915716264341, 5646.745896434588, 5663.5469162467725, 5665.858353541194], "value": 4247.453972967395, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [781.6365390093605, 816.738643634037, 824.7113510271402, 845.4504572028181, 877.2411863737868, 16499.257533410997, 18249.81750182498, 18253.90873045633, 18274.08629568521, 18328.175232114554, 16213.270402831871, 16376.0, 16415.58961025975, 16500.17499125042, 16517.174141292948, 732.5787672088544, 747.6411322565172, 875.2210532625984, 880.8458519759049, 908.550267617527, 879.3536750488386, 880.7798050487378, 881.4799268431624, 881.7266647339345, 882.2148288023664, 930.0002497315379, 932.1703683721494, 933.0669330669331, 933.3186773655236, 933.3373304953491, 770.6108415250319, 790.0401012769483, 913.1690161952591, 925.3291363761286, 930.4696323095839, 681.2234053179379, 683.1187767779559, 683.1460674157304, 683.8119517132828, 683.9281875403061, 16451.177441127897, 16466.0, 16493.6701265974, 16500.092494912777, 16536.338546458246, 15598.376064957389, 15605.45380911674, 15783.210839457981, 15998.280077396545, 16052.839471605252, 14065.296735163303, 14095.22476263805, 14319.212443315613, 14354.0, 14381.822987577125, 4676.134915040694, 4708.764561771898, 4727.101850648366, 4757.857264282077, 4871.8782030449165, 84242.57878710628, 85810.57094714549, 85816.57091714564, 87416.0, 89607.0, 12467.189632673848, 12482.625521234377, 12570.182938108994, 12614.180078295065, 12851.742965140646, 23954.40113997164, 24040.639390409156, 24083.879580601984, 24102.758972410404, 24480.755192447847, 8114.066882308492, 8601.655933762704, 8644.740657780276, 8806.251468625238, 8892.421992570462, 4868.123737727243, 5014.222795466636, 5039.924401133986, 5114.28400023997, 5217.0, 3741.8877433677035, 3883.941740873889, 3892.980535097363, 3894.0, 3894.084890050836, 650.5641220382355, 657.6152950523901, 661.9805499530765, 667.7562689618254, 670.735059651435, 688.5868478912612, 727.8689835829496, 730.1967835381145, 817.8999246014101, 845.0662018469626, 10391.84412233817, 10476.947615262028, 10692.09117225029, 11314.603988860516, 11388.829167562493, 779.2091027606983, 796.6335485676653, 811.0267678785481, 818.2390376949494, 819.2954059508818, 4988.950110498921, 5130.7947682093045, 5161.174212126042, 5232.816851410183, 5272.762725677354, 128342.0, 131349.0, 131449.0, 133211.0, 133346.33326833497, 111189.0, 115188.0, 116001.4199928995, 117170.0, 118868.40565797116, 82622.58688706638, 100732.49633751785, 101471.0, 102148.48925755324, 103034.48482757538, 9877.587579617662, 10391.116755075904, 10463.476826158814, 10536.894631053592, 10662.520186591777, 56213.43786562082, 57614.423855760906, 57894.710526447096, 58356.708216458646, 58853.41146588479, 76310.23689763031, 81835.18164818276, 84638.57680711558, 84767.5761621188, 88899.55550222208, 13586.728265434438, 13660.453581856615, 13892.791608126088, 13945.721085578434, 13987.0, 16305.347786088423, 16395.0, 16660.58348541296, 17634.82365176332, 17660.0, 13545.864541354855, 13567.674113008947, 13655.726885462434, 14001.719965600427, 14080.014398991972, 12883.677908052374, 13160.934195328962, 13413.86586134126, 13704.588862334145, 14000.0199986, 13625.114367566077, 14086.22525761093, 14601.0, 14765.630859228175, 15500.224988750742, 11780.293182409083, 12126.939365303117, 12315.137940344448, 12335.198212116184, 12663.493460261487, 12741.235525868478, 12748.171368860994, 12918.93540532329, 13191.538296159588, 13282.601521954357], "value": 8315.243537741244, "unit": "score"}], "name": "dromaeo_css", "value": 7723.4215749579525, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 113.0, 111.0, 112.0, 111.0, 111.0, 111.0, 109.0, 111.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 120.0, 122.0, 156.0, 119.0, 125.0, 118.0, 118.0, 148.0, 118.0], "value": 129.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 160.0, 159.0, 160.0, 159.0, 161.0, 160.0, 161.0, 160.0, 161.0], "value": 160.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 75.0, 109.0, 75.0, 76.0, 75.0, 76.0, 103.0, 74.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 112.0, 82.0, 82.0, 82.0, 100.0, 82.0, 81.0, 108.0], "value": 89.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 119.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 145.0, 146.0, 145.0, 145.0, 146.0, 145.0, 145.0], "value": 145.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 96.0, 95.0, 95.0, 94.0, 96.0, 95.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 51.0, 50.0, 49.0, 50.0, 50.0, 51.0, 50.0, 50.0], "value": 50.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 60.0, 59.0, 59.0, 60.0, 59.0, 59.0, 59.0, 59.0], "value": 59.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 76.0, 73.0, 74.0, 75.0, 78.0, 73.0, 73.0, 72.0, 73.0], "value": 74.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 128.0, 124.0, 122.0, 131.0, 134.0, 118.0, 118.0, 161.0, 118.0], "value": 129.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 156.0, 153.0, 153.0, 155.0, 152.0, 151.0, 151.0, 151.0, 150.0], "value": 153.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 62.0, 61.0, 62.0, 63.0, 61.0, 65.0, 61.0, 60.0], "value": 62.0, "unit": "score"}], "name": "kraken", "value": 1457.6, "alertThreshold": 2.0}]} 09:26:01 INFO - Return code: 0 09:26:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:26:01 INFO - # TBPL SUCCESS # 09:26:01 INFO - Running post-action listener: _resource_record_post_action 09:26:01 INFO - [mozharness: 2016-11-11 17:26:01.763828Z] Finished run-tests step (success) 09:26:01 INFO - Running post-run listener: _resource_record_post_run 09:26:01 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 1998848; Write bytes: 360206336; Read time: 100; Write time: 267636 09:26:01 INFO - TinderboxPrint: CPU usage
13.0% 09:26:01 INFO - TinderboxPrint: I/O read bytes / time
1,998,848 / 100 09:26:01 INFO - TinderboxPrint: I/O write bytes / time
360,206,336 / 267,636 09:26:01 INFO - TinderboxPrint: CPU idle
8,261.6 (87.0%) 09:26:01 INFO - TinderboxPrint: CPU user
1,190.9 (12.5%) 09:26:01 INFO - TinderboxPrint: Swap in / out
0 / 0 09:26:01 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 110501888; Read time: 0; Write time: 70784 09:26:01 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 262144; Write bytes: 249704448; Read time: 12; Write time: 196852 09:26:02 INFO - Running post-run listener: _upload_blobber_files 09:26:02 INFO - Blob upload gear active. 09:26:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:26:02 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:26:02 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'] 09:26:02 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 09:26:02 INFO - (blobuploader) - INFO - Open directory for files ... 09:26:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:26:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:26:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:26:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:26:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:26:03 INFO - (blobuploader) - INFO - Done attempting. 09:26:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:26:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:26:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:26:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:26:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:26:04 INFO - (blobuploader) - INFO - Done attempting. 09:26:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:26:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:26:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:26:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:26:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:26:07 INFO - (blobuploader) - INFO - Done attempting. 09:26:07 INFO - (blobuploader) - INFO - Iteration through files over. 09:26:07 INFO - Return code: 0 09:26:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:26:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:26:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/26ea592a2dd9460dee4fc8513566c4afa68a80edde922e1bfce60d95c3bf5d0dbfe8dd23fbb12288bb90a0793d2aa8c91e2cbf47c3a59aea7e826caceef31f97", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/471d4fb1c264160e5094cd712096d7ed5dd2a861fa08df80bf3bf627bcd52ad41af777f4c5e694be5ed60d18e16bc90f0fdb7748d5c1b2f84962d3c438417570", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/879f7d8d594666fb122e48c10a102a35b410f5dbdb854be71e278e2f0f3335cf83732ae215bc3c15ce2e1305d2640ebb20d08464552c880f29c0f8ead03f6685"} 09:26:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:26:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:26:07 INFO - Contents: 09:26:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/26ea592a2dd9460dee4fc8513566c4afa68a80edde922e1bfce60d95c3bf5d0dbfe8dd23fbb12288bb90a0793d2aa8c91e2cbf47c3a59aea7e826caceef31f97", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/471d4fb1c264160e5094cd712096d7ed5dd2a861fa08df80bf3bf627bcd52ad41af777f4c5e694be5ed60d18e16bc90f0fdb7748d5c1b2f84962d3c438417570", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/879f7d8d594666fb122e48c10a102a35b410f5dbdb854be71e278e2f0f3335cf83732ae215bc3c15ce2e1305d2640ebb20d08464552c880f29c0f8ead03f6685"} 09:26:07 INFO - Running post-run listener: copy_logs_to_upload_dir 09:26:07 INFO - Copying logs to upload dir... 09:26:07 INFO - mkdir: /builds/slave/test/build/upload/logs 09:26:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.486393 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-11-11 09:26:07.699927) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-11 09:26:07.703333) ========= 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=c7c495d6f76fb52e1ad9f659000001da-1478883932.753165-1788267099 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/26ea592a2dd9460dee4fc8513566c4afa68a80edde922e1bfce60d95c3bf5d0dbfe8dd23fbb12288bb90a0793d2aa8c91e2cbf47c3a59aea7e826caceef31f97", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/471d4fb1c264160e5094cd712096d7ed5dd2a861fa08df80bf3bf627bcd52ad41af777f4c5e694be5ed60d18e16bc90f0fdb7748d5c1b2f84962d3c438417570", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/879f7d8d594666fb122e48c10a102a35b410f5dbdb854be71e278e2f0f3335cf83732ae215bc3c15ce2e1305d2640ebb20d08464552c880f29c0f8ead03f6685"} build_url:https://queue.taskcluster.net/v1/task/EM1W6ERWS_mJhpE3BbKGeg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011421 build_url: 'https://queue.taskcluster.net/v1/task/EM1W6ERWS_mJhpE3BbKGeg/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/26ea592a2dd9460dee4fc8513566c4afa68a80edde922e1bfce60d95c3bf5d0dbfe8dd23fbb12288bb90a0793d2aa8c91e2cbf47c3a59aea7e826caceef31f97", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/471d4fb1c264160e5094cd712096d7ed5dd2a861fa08df80bf3bf627bcd52ad41af777f4c5e694be5ed60d18e16bc90f0fdb7748d5c1b2f84962d3c438417570", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/879f7d8d594666fb122e48c10a102a35b410f5dbdb854be71e278e2f0f3335cf83732ae215bc3c15ce2e1305d2640ebb20d08464552c880f29c0f8ead03f6685"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-11 09:26:07.728178) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 09:26:07.728460) ========= 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=c7c495d6f76fb52e1ad9f659000001da-1478883932.753165-1788267099 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004555 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-11 09:26:07.813685) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-11 09:26:07.813993) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-11 09:26:07.814264) ========= ========= Total master_lag: 0.16 =========