builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-013 starttime: 1475645081.83 results: success (0) buildid: 20161004193600 builduid: 902b92fe5b1a4d02afbd3fa4d1a0c957 revision: af5a59737002cc4e8bb0f69acddb2583a9d952c6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-04 22:24:41.834022) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-04 22:24:41.834444) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-04 22:24:41.835015) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-04 22:24:41.967069) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 22:24:41.967338) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1475644553.93747-1976780682 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-04 22:24:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 220M=0s 2016-10-04 22:24:42 (220 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.128418 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 22:24:42.118168) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 22:24:42.118467) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1475644553.93747-1976780682 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020863 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 22:24:42.181140) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 22:24:42.181475) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev af5a59737002cc4e8bb0f69acddb2583a9d952c6 --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 af5a59737002cc4e8bb0f69acddb2583a9d952c6 --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=9c3a94c35cc88655b05d2c08000001b2-1475644553.93747-1976780682 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-04 22:24:42,223 truncating revision to first 12 chars 2016-10-04 22:24:42,223 Setting DEBUG logging. 2016-10-04 22:24:42,223 attempt 1/10 2016-10-04 22:24:42,223 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/af5a59737002?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-04 22:24:42,525 unpacking tar archive at: mozilla-aurora-af5a59737002/testing/mozharness/ program finished with exit code 0 elapsedTime=0.632621 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 22:24:42.834518) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-04 22:24:42.834800) ========= script_repo_revision: af5a59737002cc4e8bb0f69acddb2583a9d952c6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-04 22:24:42.835377) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-04 22:24:42.835822) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-04 22:24:42.853570) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-10-04 22:24:42.853884) ========= /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=9c3a94c35cc88655b05d2c08000001b2-1475644553.93747-1976780682 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:24:42 INFO - MultiFileLogger online at 20161004 22:24:42 in /builds/slave/test 22:24:42 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 22:24:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:24:42 INFO - {'append_to_log': False, 22:24:42 INFO - 'base_work_dir': '/builds/slave/test', 22:24:42 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 22:24:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:24:42 INFO - 'branch': 'Mozilla-Aurora', 22:24:42 INFO - 'buildbot_json_path': 'buildprops.json', 22:24:42 INFO - 'config_files': ('talos/linux_config.py',), 22:24:42 INFO - 'default_actions': ('clobber', 22:24:42 INFO - 'read-buildbot-config', 22:24:42 INFO - 'download-and-extract', 22:24:42 INFO - 'populate-webroot', 22:24:42 INFO - 'create-virtualenv', 22:24:42 INFO - 'install', 22:24:42 INFO - 'run-tests'), 22:24:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:24:42 INFO - 'download_minidump_stackwalk': True, 22:24:42 INFO - 'download_symbols': 'ondemand', 22:24:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:24:42 INFO - 'tooltool.py': '/tools/tooltool.py', 22:24:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:24:42 INFO - '/tools/misc-python/virtualenv.py')}, 22:24:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:24:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:24:42 INFO - 'installer_path': 'installer.exe', 22:24:42 INFO - 'log_level': 'info', 22:24:42 INFO - 'log_name': 'talos', 22:24:42 INFO - 'log_to_console': True, 22:24:42 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:24:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:24:42 INFO - 'opt_config_files': (), 22:24:42 INFO - 'pip_index': False, 22:24:42 INFO - 'sps_profile': False, 22:24:42 INFO - 'sps_profile_interval': 0, 22:24:42 INFO - 'suite': 'dromaeojs', 22:24:42 INFO - 'system_bits': '32', 22:24:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:24:42 INFO - 'title': 'talos-linux64-ix-013', 22:24:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:24:42 INFO - 'use_talos_json': True, 22:24:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:24:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:24:42 INFO - 'work_dir': 'build'} 22:24:42 INFO - [mozharness: 2016-10-05 05:24:42.987046Z] Running clobber step. 22:24:42 INFO - Running pre-action listener: _resource_record_pre_action 22:24:42 INFO - Running main action method: clobber 22:24:42 INFO - rmtree: /builds/slave/test/build 22:24:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:24:43 INFO - Running post-action listener: _resource_record_post_action 22:24:43 INFO - [mozharness: 2016-10-05 05:24:43.448533Z] Finished clobber step (success) 22:24:43 INFO - [mozharness: 2016-10-05 05:24:43.448635Z] Running read-buildbot-config step. 22:24:43 INFO - Running pre-action listener: _resource_record_pre_action 22:24:43 INFO - Running main action method: read_buildbot_config 22:24:43 INFO - Using buildbot properties: 22:24:43 INFO - { 22:24:43 INFO - "project": "", 22:24:43 INFO - "product": "firefox", 22:24:43 INFO - "script_repo_revision": "production", 22:24:43 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 22:24:43 INFO - "repository": "", 22:24:43 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 22:24:43 INFO - "buildid": "20161004193600", 22:24:43 INFO - "pgo_build": "True", 22:24:43 INFO - "basedir": "/builds/slave/test", 22:24:43 INFO - "buildnumber": 550, 22:24:43 INFO - "slavename": "talos-linux64-ix-013", 22:24:43 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:24:43 INFO - "platform": "ubuntu64_hw", 22:24:43 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 22:24:43 INFO - "branch": "mozilla-aurora", 22:24:43 INFO - "repo_path": "releases/mozilla-aurora", 22:24:43 INFO - "revision": "af5a59737002cc4e8bb0f69acddb2583a9d952c6", 22:24:43 INFO - "stage_platform": "linux64-pgo", 22:24:43 INFO - "builduid": "902b92fe5b1a4d02afbd3fa4d1a0c957", 22:24:43 INFO - "slavebuilddir": "test" 22:24:43 INFO - } 22:24:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/a-3TBdmfSZ-yqsVMBgjvzg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 22:24:43 INFO - Running post-action listener: _resource_record_post_action 22:24:43 INFO - [mozharness: 2016-10-05 05:24:43.451393Z] Finished read-buildbot-config step (success) 22:24:43 INFO - [mozharness: 2016-10-05 05:24:43.451483Z] Running download-and-extract step. 22:24:43 INFO - Running pre-action listener: _resource_record_pre_action 22:24:43 INFO - Running main action method: download_and_extract 22:24:43 INFO - mkdir: /builds/slave/test/build/tests 22:24:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:24:43 INFO - trying https://queue.taskcluster.net/v1/task/a-3TBdmfSZ-yqsVMBgjvzg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 22:24:43 INFO - Downloading https://queue.taskcluster.net/v1/task/a-3TBdmfSZ-yqsVMBgjvzg/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 22:24:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a-3TBdmfSZ-yqsVMBgjvzg/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 22:24:44 INFO - Downloaded 1361 bytes. 22:24:44 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 22:24:44 INFO - Using the following test package requirements: 22:24:44 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 22:24:44 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 22:24:44 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 22:24:44 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 22:24:44 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 22:24:44 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 22:24:44 INFO - u'jsshell-linux-x86_64.zip'], 22:24:44 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 22:24:44 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 22:24:44 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 22:24:44 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 22:24:44 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 22:24:44 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 22:24:44 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 22:24:44 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 22:24:44 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 22:24:44 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 22:24:44 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 22:24:44 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:24:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a-3TBdmfSZ-yqsVMBgjvzg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 22:24:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a-3TBdmfSZ-yqsVMBgjvzg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:24:44 INFO - Fetch https://queue.taskcluster.net/v1/task/a-3TBdmfSZ-yqsVMBgjvzg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 22:24:44 INFO - Expected file size: 21906314 22:24:48 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 22:24:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a-3TBdmfSZ-yqsVMBgjvzg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 22:24:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a-3TBdmfSZ-yqsVMBgjvzg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:24:48 INFO - Fetch https://queue.taskcluster.net/v1/task/a-3TBdmfSZ-yqsVMBgjvzg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 22:24:49 INFO - Expected file size: 21906314 22:24:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a-3TBdmfSZ-yqsVMBgjvzg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 22:24:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a-3TBdmfSZ-yqsVMBgjvzg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:24:54 INFO - Fetch https://queue.taskcluster.net/v1/task/a-3TBdmfSZ-yqsVMBgjvzg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 22:24:54 INFO - Expected file size: 14414059 22:24:55 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')]} 22:24:55 INFO - trying https://queue.taskcluster.net/v1/task/a-3TBdmfSZ-yqsVMBgjvzg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 22:24:55 INFO - Downloading https://queue.taskcluster.net/v1/task/a-3TBdmfSZ-yqsVMBgjvzg/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 22:24:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a-3TBdmfSZ-yqsVMBgjvzg/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 22:24:57 INFO - Downloaded 63941245 bytes. 22:24:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/a-3TBdmfSZ-yqsVMBgjvzg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 22:24:57 INFO - mkdir: /builds/slave/test/properties 22:24:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:24:57 INFO - Writing to file /builds/slave/test/properties/build_url 22:24:57 INFO - Contents: 22:24:57 INFO - build_url:https://queue.taskcluster.net/v1/task/a-3TBdmfSZ-yqsVMBgjvzg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 22:24:57 INFO - Running post-action listener: _resource_record_post_action 22:24:57 INFO - Running post-action listener: set_extra_try_arguments 22:24:57 INFO - [mozharness: 2016-10-05 05:24:57.929675Z] Finished download-and-extract step (success) 22:24:57 INFO - [mozharness: 2016-10-05 05:24:57.929904Z] Running populate-webroot step. 22:24:57 INFO - Running pre-action listener: _resource_record_pre_action 22:24:57 INFO - Running main action method: populate_webroot 22:24:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:24:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:24:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:24:57 INFO - '--tppagecycles', 22:24:57 INFO - '7'], 22:24:57 INFO - 'tests': ['tsvgm']}}, 22:24:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:24:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:24:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:24:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:24:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:24:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:24:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:24:57 INFO - 'talos_options': ['--disable-e10s'], 22:24:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:24:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:24:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:24:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:24:57 INFO - 'talos_options': ['--disable-e10s'], 22:24:57 INFO - 'tests': ['damp', 'tps']}, 22:24:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:24:57 INFO - 'tests': ['damp', 'tps']}, 22:24:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:24:57 INFO - 'tests': ['dromaeo_dom']}, 22:24:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:24:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:24:57 INFO - 'tests': ['basic_compositor_video']}, 22:24:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:24:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:24:57 INFO - 'tests': ['a11yr', 22:24:57 INFO - 'ts_paint', 22:24:57 INFO - 'tpaint', 22:24:57 INFO - 'sessionrestore', 22:24:57 INFO - 'sessionrestore_no_auto_restore', 22:24:57 INFO - 'tabpaint']}, 22:24:57 INFO - 'other-e10s': {'tests': ['a11yr', 22:24:57 INFO - 'ts_paint', 22:24:57 INFO - 'tpaint', 22:24:57 INFO - 'sessionrestore', 22:24:57 INFO - 'sessionrestore_no_auto_restore', 22:24:57 INFO - 'tabpaint']}, 22:24:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:24:57 INFO - 'ts_paint', 22:24:57 INFO - 'tpaint', 22:24:57 INFO - 'sessionrestore', 22:24:57 INFO - 'sessionrestore_no_auto_restore', 22:24:57 INFO - 'tabpaint']}, 22:24:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:24:57 INFO - 'ts_paint', 22:24:57 INFO - 'tpaint', 22:24:57 INFO - 'sessionrestore', 22:24:57 INFO - 'sessionrestore_no_auto_restore', 22:24:57 INFO - 'tabpaint']}, 22:24:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:24:57 INFO - 'tests': ['a11yr', 22:24:57 INFO - 'ts_paint', 22:24:57 INFO - 'tpaint', 22:24:57 INFO - 'sessionrestore', 22:24:57 INFO - 'sessionrestore_no_auto_restore', 22:24:57 INFO - 'tabpaint']}, 22:24:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:24:57 INFO - 'tests': ['a11yr', 22:24:57 INFO - 'ts_paint', 22:24:57 INFO - 'tpaint', 22:24:57 INFO - 'sessionrestore', 22:24:57 INFO - 'sessionrestore_no_auto_restore', 22:24:57 INFO - 'tabpaint']}, 22:24:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:24:57 INFO - 'tests': ['tsvgx', 22:24:57 INFO - 'tsvgr_opacity', 22:24:57 INFO - 'tart', 22:24:57 INFO - 'tscrollx', 22:24:57 INFO - 'cart']}, 22:24:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:24:57 INFO - 'tsvgr_opacity', 22:24:57 INFO - 'tart', 22:24:57 INFO - 'tscrollx', 22:24:57 INFO - 'cart']}, 22:24:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:24:57 INFO - 'talos_options': ['--disable-e10s'], 22:24:57 INFO - 'tests': ['tp5o']}, 22:24:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:24:57 INFO - 'tests': ['tp5o']}, 22:24:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:24:57 INFO - 'talos_options': ['--disable-e10s', 22:24:57 INFO - '--xperf_path', 22:24:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:24:57 INFO - 'tests': ['tp5n']}, 22:24:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:24:57 INFO - 'talos_options': ['--xperf_path', 22:24:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:24:57 INFO - 'tests': ['tp5n']}}, 22:24:57 INFO - 'talos.zip': {'path': '', 22:24:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:24:57 INFO - Running post-action listener: _resource_record_post_action 22:24:57 INFO - [mozharness: 2016-10-05 05:24:57.945544Z] Finished populate-webroot step (success) 22:24:57 INFO - [mozharness: 2016-10-05 05:24:57.945632Z] Running create-virtualenv step. 22:24:57 INFO - Running pre-action listener: _resource_record_pre_action 22:24:57 INFO - Running main action method: create_virtualenv 22:24:57 INFO - Creating virtualenv /builds/slave/test/build/venv 22:24:57 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 22:24:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:24:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:24:58 INFO - Using real prefix '/usr' 22:24:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:24:58 INFO - Installing distribute.............................................................................................................................................................................................done. 22:25:00 INFO - Installing pip.................done. 22:25:00 INFO - Return code: 0 22:25:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:25:00 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')]} 22:25:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:25:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:25:00 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')]} 22:25:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:25:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:25:00 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 0x7f5c4237ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d52ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4ff60>, '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 0x1d50d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d511d0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1475644553.93747-1976780682', '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 22:25:00 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 22:25:00 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 22:25:00 INFO - Using env: {'DISPLAY': ':0', 22:25:00 INFO - 'HOME': '/home/cltbld', 22:25:00 INFO - 'LANG': 'en_US.UTF-8', 22:25:00 INFO - 'LANGUAGE': 'en_US:en', 22:25:00 INFO - 'LOGNAME': 'cltbld', 22:25:00 INFO - 'MAIL': '/var/mail/cltbld', 22:25:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:25:00 INFO - 'MOZ_NO_REMOTE': '1', 22:25:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:25:00 INFO - 'NO_EM_RESTART': '1', 22:25:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:25:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:25:00 INFO - 'PWD': '/builds/slave/test', 22:25:00 INFO - 'SHELL': '/bin/bash', 22:25:00 INFO - 'SHLVL': '1', 22:25:00 INFO - 'TERM': 'linux', 22:25:00 INFO - 'TMOUT': '86400', 22:25:00 INFO - 'USER': 'cltbld', 22:25:00 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1475644553.93747-1976780682', 22:25:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:25:00 INFO - '_': '/tools/buildbot/bin/python'} 22:25:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:25:00 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 22:25:00 INFO - Cleaning up... 22:25:00 INFO - Return code: 0 22:25:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:25:00 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')]} 22:25:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:25:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:25:00 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')]} 22:25:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:25:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:25:00 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 0x7f5c4237ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d52ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4ff60>, '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 0x1d50d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d511d0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1475644553.93747-1976780682', '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 22:25:00 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 22:25:00 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 22:25:00 INFO - Using env: {'DISPLAY': ':0', 22:25:00 INFO - 'HOME': '/home/cltbld', 22:25:00 INFO - 'LANG': 'en_US.UTF-8', 22:25:00 INFO - 'LANGUAGE': 'en_US:en', 22:25:00 INFO - 'LOGNAME': 'cltbld', 22:25:00 INFO - 'MAIL': '/var/mail/cltbld', 22:25:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:25:00 INFO - 'MOZ_NO_REMOTE': '1', 22:25:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:25:00 INFO - 'NO_EM_RESTART': '1', 22:25:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:25:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:25:00 INFO - 'PWD': '/builds/slave/test', 22:25:00 INFO - 'SHELL': '/bin/bash', 22:25:00 INFO - 'SHLVL': '1', 22:25:00 INFO - 'TERM': 'linux', 22:25:00 INFO - 'TMOUT': '86400', 22:25:00 INFO - 'USER': 'cltbld', 22:25:00 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1475644553.93747-1976780682', 22:25:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:25:00 INFO - '_': '/tools/buildbot/bin/python'} 22:25:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:25:00 INFO - Downloading/unpacking psutil>=3.1.1 22:25:00 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 22:25:00 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 22:25:00 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 22:25:00 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 22:25:00 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 22:25:00 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 22:25:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:25:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:25:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:25:02 INFO - Installing collected packages: psutil 22:25:02 INFO - Running setup.py install for psutil 22:25:03 INFO - building 'psutil._psutil_linux' extension 22:25:03 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 22:25:03 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 22:25:03 INFO - building 'psutil._psutil_posix' extension 22:25:03 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 22:25:03 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 22:25:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:25:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:25:03 INFO - Successfully installed psutil 22:25:03 INFO - Cleaning up... 22:25:03 INFO - Return code: 0 22:25:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:25: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')]} 22:25:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:25:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:25: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')]} 22:25:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:25:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:25:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5c4237ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d52ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4ff60>, '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 0x1d50d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d511d0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1475644553.93747-1976780682', '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 22:25:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:25:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:25:03 INFO - Using env: {'DISPLAY': ':0', 22:25:03 INFO - 'HOME': '/home/cltbld', 22:25:03 INFO - 'LANG': 'en_US.UTF-8', 22:25:03 INFO - 'LANGUAGE': 'en_US:en', 22:25:03 INFO - 'LOGNAME': 'cltbld', 22:25:03 INFO - 'MAIL': '/var/mail/cltbld', 22:25:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:25:03 INFO - 'MOZ_NO_REMOTE': '1', 22:25:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:25:03 INFO - 'NO_EM_RESTART': '1', 22:25:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:25:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:25:03 INFO - 'PWD': '/builds/slave/test', 22:25:03 INFO - 'SHELL': '/bin/bash', 22:25:03 INFO - 'SHLVL': '1', 22:25:03 INFO - 'TERM': 'linux', 22:25:03 INFO - 'TMOUT': '86400', 22:25:03 INFO - 'USER': 'cltbld', 22:25:03 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1475644553.93747-1976780682', 22:25:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:25:03 INFO - '_': '/tools/buildbot/bin/python'} 22:25:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:25:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:25:03 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 22:25:03 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 22:25:03 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 22:25:03 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 22:25:03 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 22:25:03 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 22:25:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:25:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:25:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:25:05 INFO - Installing collected packages: mozsystemmonitor 22:25:05 INFO - Running setup.py install for mozsystemmonitor 22:25:05 INFO - Successfully installed mozsystemmonitor 22:25:05 INFO - Cleaning up... 22:25:05 INFO - Return code: 0 22:25:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:25:05 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')]} 22:25:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:25:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:25:05 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')]} 22:25:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:25:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:25:05 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 0x7f5c4237ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d52ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4ff60>, '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 0x1d50d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d511d0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1475644553.93747-1976780682', '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 22:25:05 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 22:25:05 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 22:25:05 INFO - Using env: {'DISPLAY': ':0', 22:25:05 INFO - 'HOME': '/home/cltbld', 22:25:05 INFO - 'LANG': 'en_US.UTF-8', 22:25:05 INFO - 'LANGUAGE': 'en_US:en', 22:25:05 INFO - 'LOGNAME': 'cltbld', 22:25:05 INFO - 'MAIL': '/var/mail/cltbld', 22:25:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:25:05 INFO - 'MOZ_NO_REMOTE': '1', 22:25:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:25:05 INFO - 'NO_EM_RESTART': '1', 22:25:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:25:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:25:05 INFO - 'PWD': '/builds/slave/test', 22:25:05 INFO - 'SHELL': '/bin/bash', 22:25:05 INFO - 'SHLVL': '1', 22:25:05 INFO - 'TERM': 'linux', 22:25:05 INFO - 'TMOUT': '86400', 22:25:05 INFO - 'USER': 'cltbld', 22:25:05 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1475644553.93747-1976780682', 22:25:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:25:05 INFO - '_': '/tools/buildbot/bin/python'} 22:25:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:25:06 INFO - Downloading/unpacking jsonschema==2.5.1 22:25:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:25:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:25:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:25:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:25:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:25:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:25:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:25:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:25: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 22:25: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 22:25: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 22:25: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 22:25: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 22:25: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 22:25:08 INFO - Downloading functools32-3.2.3-2.tar.gz 22:25:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:25:08 INFO - warning: no files found matching '*.txt' 22:25:08 INFO - no previously-included directories found matching 'build' 22:25:08 INFO - no previously-included directories found matching 'dist' 22:25:08 INFO - no previously-included directories found matching '.git*' 22:25:08 INFO - Installing collected packages: jsonschema, functools32 22:25:08 INFO - Running setup.py install for functools32 22:25:08 INFO - warning: no files found matching '*.txt' 22:25:08 INFO - no previously-included directories found matching 'build' 22:25:08 INFO - no previously-included directories found matching 'dist' 22:25:08 INFO - no previously-included directories found matching '.git*' 22:25:08 INFO - Successfully installed jsonschema functools32 22:25:08 INFO - Cleaning up... 22:25:08 INFO - Return code: 0 22:25:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:25:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:25:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:25:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:25:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:25:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:25:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:25:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f5c4237ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d52ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4ff60>, '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 0x1d50d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d511d0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1475644553.93747-1976780682', '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 22:25:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 22:25:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 22:25:08 INFO - Using env: {'DISPLAY': ':0', 22:25:08 INFO - 'HOME': '/home/cltbld', 22:25:08 INFO - 'LANG': 'en_US.UTF-8', 22:25:08 INFO - 'LANGUAGE': 'en_US:en', 22:25:08 INFO - 'LOGNAME': 'cltbld', 22:25:08 INFO - 'MAIL': '/var/mail/cltbld', 22:25:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:25:08 INFO - 'MOZ_NO_REMOTE': '1', 22:25:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:25:08 INFO - 'NO_EM_RESTART': '1', 22:25:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:25:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:25:08 INFO - 'PWD': '/builds/slave/test', 22:25:08 INFO - 'SHELL': '/bin/bash', 22:25:08 INFO - 'SHLVL': '1', 22:25:08 INFO - 'TERM': 'linux', 22:25:08 INFO - 'TMOUT': '86400', 22:25:08 INFO - 'USER': 'cltbld', 22:25:08 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1475644553.93747-1976780682', 22:25:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:25:08 INFO - '_': '/tools/buildbot/bin/python'} 22:25:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:25:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:25:08 INFO - Cleaning up... 22:25:08 INFO - Return code: 0 22:25:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:25:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:25:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:25:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:25:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:25:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:25:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:25:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f5c4237ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d52ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4ff60>, '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 0x1d50d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d511d0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1475644553.93747-1976780682', '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 22:25:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:25:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:25:08 INFO - Using env: {'DISPLAY': ':0', 22:25:08 INFO - 'HOME': '/home/cltbld', 22:25:08 INFO - 'LANG': 'en_US.UTF-8', 22:25:08 INFO - 'LANGUAGE': 'en_US:en', 22:25:08 INFO - 'LOGNAME': 'cltbld', 22:25:08 INFO - 'MAIL': '/var/mail/cltbld', 22:25:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:25:08 INFO - 'MOZ_NO_REMOTE': '1', 22:25:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:25:08 INFO - 'NO_EM_RESTART': '1', 22:25:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:25:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:25:08 INFO - 'PWD': '/builds/slave/test', 22:25:08 INFO - 'SHELL': '/bin/bash', 22:25:08 INFO - 'SHLVL': '1', 22:25:08 INFO - 'TERM': 'linux', 22:25:08 INFO - 'TMOUT': '86400', 22:25:08 INFO - 'USER': 'cltbld', 22:25:08 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1475644553.93747-1976780682', 22:25:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:25:08 INFO - '_': '/tools/buildbot/bin/python'} 22:25:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:25:08 INFO - Downloading/unpacking blobuploader==1.2.4 22:25:08 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 22:25:08 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 22:25:08 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 22:25:08 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 22:25:08 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 22:25:08 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 22:25:10 INFO - Downloading blobuploader-1.2.4.tar.gz 22:25:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:25:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:25:10 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 22:25:10 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 22:25:10 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 22:25:10 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 22:25:10 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 22:25:10 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 22:25:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:25:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:25:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:25:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:25:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:25:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:25:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:25:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:25:11 INFO - Downloading docopt-0.6.1.tar.gz 22:25:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:25:11 INFO - Installing collected packages: blobuploader, requests, docopt 22:25:11 INFO - Running setup.py install for blobuploader 22:25:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:25:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:25:11 INFO - Running setup.py install for requests 22:25:11 INFO - Running setup.py install for docopt 22:25:12 INFO - Successfully installed blobuploader requests docopt 22:25:12 INFO - Cleaning up... 22:25:12 INFO - Return code: 0 22:25:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:25:12 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')]} 22:25:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:25:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:25:12 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')]} 22:25:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:25:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:25:12 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 0x7f5c4237ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d52ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4ff60>, '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 0x1d50d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d511d0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1475644553.93747-1976780682', '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 22:25:12 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 22:25:12 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 22:25:12 INFO - Using env: {'DISPLAY': ':0', 22:25:12 INFO - 'HOME': '/home/cltbld', 22:25:12 INFO - 'LANG': 'en_US.UTF-8', 22:25:12 INFO - 'LANGUAGE': 'en_US:en', 22:25:12 INFO - 'LOGNAME': 'cltbld', 22:25:12 INFO - 'MAIL': '/var/mail/cltbld', 22:25:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:25:12 INFO - 'MOZ_NO_REMOTE': '1', 22:25:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:25:12 INFO - 'NO_EM_RESTART': '1', 22:25:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:25:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:25:12 INFO - 'PWD': '/builds/slave/test', 22:25:12 INFO - 'SHELL': '/bin/bash', 22:25:12 INFO - 'SHLVL': '1', 22:25:12 INFO - 'TERM': 'linux', 22:25:12 INFO - 'TMOUT': '86400', 22:25:12 INFO - 'USER': 'cltbld', 22:25:12 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1475644553.93747-1976780682', 22:25:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:25:12 INFO - '_': '/tools/buildbot/bin/python'} 22:25:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:25:12 INFO - Running setup.py (path:/tmp/pip-5iA9qZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:25:12 INFO - Running setup.py (path:/tmp/pip-a07drb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:25:12 INFO - Running setup.py (path:/tmp/pip-zlXVek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:25:12 INFO - Running setup.py (path:/tmp/pip-WPJP0o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:25:12 INFO - Running setup.py (path:/tmp/pip-2ZBQvi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:25:12 INFO - Running setup.py (path:/tmp/pip-w_Ib85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:25:12 INFO - Running setup.py (path:/tmp/pip-NJV72g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:25:12 INFO - Running setup.py (path:/tmp/pip-9SuORo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:25:12 INFO - Running setup.py (path:/tmp/pip-mnTj14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:25:12 INFO - Running setup.py (path:/tmp/pip-Nm6AFp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:25:13 INFO - Running setup.py (path:/tmp/pip-1lfjq1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:25:13 INFO - Running setup.py (path:/tmp/pip-R7hKB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:25:13 INFO - Running setup.py (path:/tmp/pip-kgj6iy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:25:13 INFO - Running setup.py (path:/tmp/pip-HFGeaF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:25:13 INFO - Running setup.py (path:/tmp/pip-ZNfq3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:25:13 INFO - Running setup.py (path:/tmp/pip-wYDcH1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:25:13 INFO - Running setup.py (path:/tmp/pip-rwt9AH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:25:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:25:13 INFO - Running setup.py install for manifestparser 22:25:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:25:13 INFO - Running setup.py install for mozcrash 22:25:13 INFO - Running setup.py install for mozdebug 22:25:13 INFO - Running setup.py install for mozdevice 22:25:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:25:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:25:13 INFO - Running setup.py install for mozfile 22:25:14 INFO - Running setup.py install for mozhttpd 22:25:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:25:14 INFO - Running setup.py install for mozinfo 22:25:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:25:14 INFO - Running setup.py install for mozInstall 22:25:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:25:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:25:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:25:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:25:14 INFO - Running setup.py install for mozleak 22:25:14 INFO - Running setup.py install for mozlog 22:25:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:25:14 INFO - Running setup.py install for moznetwork 22:25:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:25:14 INFO - Running setup.py install for mozprocess 22:25:14 INFO - Running setup.py install for mozprofile 22:25:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:25:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:25:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:25:14 INFO - Running setup.py install for mozrunner 22:25:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:25:14 INFO - Running setup.py install for mozscreenshot 22:25:15 INFO - Running setup.py install for moztest 22:25:15 INFO - Running setup.py install for mozversion 22:25:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:25:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:25:15 INFO - Cleaning up... 22:25:15 INFO - Return code: 0 22:25:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:25:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:25:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:25:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:25:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:25:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:25:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:25:15 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 0x7f5c4237ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d52ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4ff60>, '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 0x1d50d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d511d0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1475644553.93747-1976780682', '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 22:25:15 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 22:25:15 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 22:25:15 INFO - Using env: {'DISPLAY': ':0', 22:25:15 INFO - 'HOME': '/home/cltbld', 22:25:15 INFO - 'LANG': 'en_US.UTF-8', 22:25:15 INFO - 'LANGUAGE': 'en_US:en', 22:25:15 INFO - 'LOGNAME': 'cltbld', 22:25:15 INFO - 'MAIL': '/var/mail/cltbld', 22:25:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:25:15 INFO - 'MOZ_NO_REMOTE': '1', 22:25:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:25:15 INFO - 'NO_EM_RESTART': '1', 22:25:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:25:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:25:15 INFO - 'PWD': '/builds/slave/test', 22:25:15 INFO - 'SHELL': '/bin/bash', 22:25:15 INFO - 'SHLVL': '1', 22:25:15 INFO - 'TERM': 'linux', 22:25:15 INFO - 'TMOUT': '86400', 22:25:15 INFO - 'USER': 'cltbld', 22:25:15 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1475644553.93747-1976780682', 22:25:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:25:15 INFO - '_': '/tools/buildbot/bin/python'} 22:25:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:25:15 INFO - Running setup.py (path:/tmp/pip-hzGh2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:25:15 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)) 22:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:25:15 INFO - Running setup.py (path:/tmp/pip-Xmo0st-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:25:15 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)) 22:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:25:15 INFO - Running setup.py (path:/tmp/pip-0UYIRt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:25:15 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)) 22:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:25:15 INFO - Running setup.py (path:/tmp/pip-un_fzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:25:15 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)) 22:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:25:15 INFO - Running setup.py (path:/tmp/pip-csN3sM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:25:15 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)) 22:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:25:15 INFO - Running setup.py (path:/tmp/pip-hZ1io1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:25:15 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)) 22:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:25:15 INFO - Running setup.py (path:/tmp/pip-6vfx4l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:25:16 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)) 22:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:25:16 INFO - Running setup.py (path:/tmp/pip-XOaiST-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:25:16 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)) 22:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:25:16 INFO - Running setup.py (path:/tmp/pip-mwqc38-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:25:16 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)) 22:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:25:16 INFO - Running setup.py (path:/tmp/pip-UPyIMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:25:16 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)) 22:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:25:16 INFO - Running setup.py (path:/tmp/pip-rXOX6s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:25:16 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)) 22:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:25:16 INFO - Running setup.py (path:/tmp/pip-zUY_wY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:25:16 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)) 22:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:25:16 INFO - Running setup.py (path:/tmp/pip-fidpzS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:25:16 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)) 22:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:25:16 INFO - Running setup.py (path:/tmp/pip-69R44t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:25:16 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)) 22:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:25:16 INFO - Running setup.py (path:/tmp/pip-fIdkQE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:25:16 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)) 22:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:25:16 INFO - Running setup.py (path:/tmp/pip-4BZkbW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:25:16 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)) 22:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:25:16 INFO - Running setup.py (path:/tmp/pip-CMhMoC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:25:16 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)) 22:25:16 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)) 22:25:16 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)) 22:25:16 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)) 22:25:16 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)) 22:25:16 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)) 22:25:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:25:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:25:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:25:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:25:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:25:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:25:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:25:18 INFO - Downloading blessings-1.6.tar.gz 22:25:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:25:18 INFO - Installing collected packages: blessings 22:25:18 INFO - Running setup.py install for blessings 22:25:18 INFO - Successfully installed blessings 22:25:18 INFO - Cleaning up... 22:25:18 INFO - Return code: 0 22:25:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:25:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:25:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:25:19 INFO - Reading from file tmpfile_stdout 22:25:19 INFO - Current package versions: 22:25:19 INFO - argparse == 1.2.1 22:25:19 INFO - blessings == 1.6 22:25:19 INFO - blobuploader == 1.2.4 22:25:19 INFO - docopt == 0.6.1 22:25:19 INFO - functools32 == 3.2.3-2 22:25:19 INFO - jsonschema == 2.5.1 22:25:19 INFO - manifestparser == 1.1 22:25:19 INFO - mozInstall == 1.12 22:25:19 INFO - mozcrash == 0.17 22:25:19 INFO - mozdebug == 0.1 22:25:19 INFO - mozdevice == 0.48 22:25:19 INFO - mozfile == 1.2 22:25:19 INFO - mozhttpd == 0.7 22:25:19 INFO - mozinfo == 0.9 22:25:19 INFO - mozleak == 0.1 22:25:19 INFO - mozlog == 3.4 22:25:19 INFO - moznetwork == 0.27 22:25:19 INFO - mozprocess == 0.23 22:25:19 INFO - mozprofile == 0.28 22:25:19 INFO - mozrunner == 6.12 22:25:19 INFO - mozscreenshot == 0.1 22:25:19 INFO - mozsystemmonitor == 0.3 22:25:19 INFO - moztest == 0.7 22:25:19 INFO - mozversion == 1.4 22:25:19 INFO - psutil == 3.1.1 22:25:19 INFO - requests == 1.2.3 22:25:19 INFO - wsgiref == 0.1.2 22:25:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:25:19 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')]} 22:25:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:25:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:25:19 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')]} 22:25:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:25:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:25:19 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 0x7f5c4237ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d52ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4ff60>, '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 0x1d50d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d511d0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1475644553.93747-1976780682', '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 22:25:19 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 22:25:19 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 22:25:19 INFO - Using env: {'DISPLAY': ':0', 22:25:19 INFO - 'HOME': '/home/cltbld', 22:25:19 INFO - 'LANG': 'en_US.UTF-8', 22:25:19 INFO - 'LANGUAGE': 'en_US:en', 22:25:19 INFO - 'LOGNAME': 'cltbld', 22:25:19 INFO - 'MAIL': '/var/mail/cltbld', 22:25:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:25:19 INFO - 'MOZ_NO_REMOTE': '1', 22:25:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:25:19 INFO - 'NO_EM_RESTART': '1', 22:25:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:25:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:25:19 INFO - 'PWD': '/builds/slave/test', 22:25:19 INFO - 'SHELL': '/bin/bash', 22:25:19 INFO - 'SHLVL': '1', 22:25:19 INFO - 'TERM': 'linux', 22:25:19 INFO - 'TMOUT': '86400', 22:25:19 INFO - 'USER': 'cltbld', 22:25:19 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1475644553.93747-1976780682', 22:25:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:25:19 INFO - '_': '/tools/buildbot/bin/python'} 22:25:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:25:19 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)) 22:25:19 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)) 22:25:19 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)) 22:25:19 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)) 22:25:19 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)) 22:25:19 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)) 22:25:19 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)) 22:25:19 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)) 22:25:19 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)) 22:25:19 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)) 22:25:19 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)) 22:25:19 INFO - Cleaning up... 22:25:19 INFO - Return code: 0 22:25:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:25:19 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')]} 22:25:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:25:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:25:19 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')]} 22:25:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:25:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:25:19 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 0x7f5c4237ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d52ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d4ff60>, '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 0x1d50d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d511d0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1475644553.93747-1976780682', '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 22:25:19 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 22:25:19 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 22:25:19 INFO - Using env: {'DISPLAY': ':0', 22:25:19 INFO - 'HOME': '/home/cltbld', 22:25:19 INFO - 'LANG': 'en_US.UTF-8', 22:25:19 INFO - 'LANGUAGE': 'en_US:en', 22:25:19 INFO - 'LOGNAME': 'cltbld', 22:25:19 INFO - 'MAIL': '/var/mail/cltbld', 22:25:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:25:19 INFO - 'MOZ_NO_REMOTE': '1', 22:25:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:25:19 INFO - 'NO_EM_RESTART': '1', 22:25:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:25:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:25:19 INFO - 'PWD': '/builds/slave/test', 22:25:19 INFO - 'SHELL': '/bin/bash', 22:25:19 INFO - 'SHLVL': '1', 22:25:19 INFO - 'TERM': 'linux', 22:25:19 INFO - 'TMOUT': '86400', 22:25:19 INFO - 'USER': 'cltbld', 22:25:19 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1475644553.93747-1976780682', 22:25:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:25:19 INFO - '_': '/tools/buildbot/bin/python'} 22:25:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:25:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:25:19 INFO - Cleaning up... 22:25:19 INFO - Return code: 0 22:25:19 INFO - Running post-action listener: _resource_record_post_action 22:25:19 INFO - Running post-action listener: _start_resource_monitoring 22:25:19 INFO - Starting resource monitoring. 22:25:19 INFO - [mozharness: 2016-10-05 05:25:19.597083Z] Finished create-virtualenv step (success) 22:25:19 INFO - [mozharness: 2016-10-05 05:25:19.597596Z] Running install step. 22:25:19 INFO - Running pre-action listener: _resource_record_pre_action 22:25:19 INFO - Running main action method: install 22:25:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:25:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:25:19 INFO - Reading from file tmpfile_stdout 22:25:19 INFO - Detecting whether we're running mozinstall >=1.0... 22:25:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:25:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:25:19 INFO - Reading from file tmpfile_stdout 22:25:19 INFO - Output received: 22:25:19 INFO - Usage: mozinstall [options] installer 22:25:19 INFO - Options: 22:25:19 INFO - -h, --help show this help message and exit 22:25:19 INFO - -d DEST, --destination=DEST 22:25:19 INFO - Directory to install application into. [default: 22:25:19 INFO - "/builds/slave/test"] 22:25:19 INFO - --app=APP Application being installed. [default: firefox] 22:25:19 INFO - mkdir: /builds/slave/test/build/application 22:25:19 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'] 22:25:19 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 22:25:35 INFO - Reading from file tmpfile_stdout 22:25:35 INFO - Output received: 22:25:35 INFO - /builds/slave/test/build/application/firefox/firefox 22:25:35 INFO - Running post-action listener: _resource_record_post_action 22:25:35 INFO - [mozharness: 2016-10-05 05:25:35.831765Z] Finished install step (success) 22:25:35 INFO - [mozharness: 2016-10-05 05:25:35.832011Z] Running run-tests step. 22:25:35 INFO - Running pre-action listener: _resource_record_pre_action 22:25:35 INFO - Running main action method: run_tests 22:25:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:25:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:25:35 INFO - Python 2.7.3 22:25:35 INFO - Return code: 0 22:25:35 INFO - grabbing minidump binary from tooltool 22:25: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')]} 22:25:35 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 0x1d4ff60>, '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 0x1d50d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d511d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:25:35 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 22:25:35 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 22:25:35 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 22:25:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:25:35 INFO - Return code: 0 22:25:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:25:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:25:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:25:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:25:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:25:35 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/a-3TBdmfSZ-yqsVMBgjvzg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--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 22:25:35 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/a-3TBdmfSZ-yqsVMBgjvzg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-013 --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 22:25:35 INFO - Using env: {'DISPLAY': ':0', 22:25:35 INFO - 'HOME': '/home/cltbld', 22:25:35 INFO - 'LANG': 'en_US.UTF-8', 22:25:35 INFO - 'LANGUAGE': 'en_US:en', 22:25:35 INFO - 'LOGNAME': 'cltbld', 22:25:35 INFO - 'MAIL': '/var/mail/cltbld', 22:25:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:25:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:25:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:25:35 INFO - 'MOZ_NO_REMOTE': '1', 22:25:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:25:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:25:35 INFO - 'NO_EM_RESTART': '1', 22:25:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:25:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:25:35 INFO - 'PWD': '/builds/slave/test', 22:25:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:25:35 INFO - 'SHELL': '/bin/bash', 22:25:35 INFO - 'SHLVL': '1', 22:25:35 INFO - 'TERM': 'linux', 22:25:35 INFO - 'TMOUT': '86400', 22:25:35 INFO - 'USER': 'cltbld', 22:25:35 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1475644553.93747-1976780682', 22:25:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:25:35 INFO - '_': '/tools/buildbot/bin/python'} 22:25:35 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/a-3TBdmfSZ-yqsVMBgjvzg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--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 22:25:36 INFO - mozversion application_buildid: 20161004193600 22:25:36 INFO - mozversion application_changeset: af5a59737002cc4e8bb0f69acddb2583a9d952c6 22:25:36 INFO - mozversion application_display_name: FirefoxDeveloperEdition 22:25:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:25:36 INFO - mozversion application_name: Firefox 22:25:36 INFO - mozversion application_remotingname: firefox-dev 22:25:36 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:25:36 INFO - mozversion application_vendor: Mozilla 22:25:36 INFO - mozversion application_version: 51.0a2 22:25:36 INFO - mozversion platform_buildid: 20161004193600 22:25:36 INFO - mozversion platform_changeset: af5a59737002cc4e8bb0f69acddb2583a9d952c6 22:25:36 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 22:25:36 INFO - mozversion platform_version: 51.0a2 22:25:36 INFO - using testdate: 1475645136 22:25:36 INFO - actual date: 1475645136 22:25:36 INFO - starting webserver on 'localhost:40469' 22:25:36 INFO - SUITE-START | Running 2 tests 22:25:36 INFO - TEST-START | dromaeo_css 22:25:36 INFO - operating with platform_type : linux_ 22:25:36 INFO - Initialising browser for dromaeo_css test... 22:25:36 INFO - TEST-INFO | started process 9382 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkYSNUK/profile http://localhost:40469/getInfo.html) 22:25:40 INFO - PROCESS | 9382 | __metrics Screen width/height:1600/1200 22:25:40 INFO - PROCESS | 9382 | colorDepth:24 22:25:40 INFO - PROCESS | 9382 | Browser inner width/height: 1024/697 22:25:40 INFO - PROCESS | 9382 | __metrics 22:25:40 INFO - PROCESS | 9382 | 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" 22:25:40 INFO - PROCESS | 9382 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:25:41 INFO - PROCESS | 9382 | console.error: PushService: 22:25:41 INFO - PROCESS | 9382 | stateChangeProcessEnqueue: Error transitioning state 22:25:41 INFO - PROCESS | 9382 | UnknownError 22:25:41 INFO - TEST-INFO | 9382: exit 0 22:25:41 INFO - Browser initialized. 22:25:41 INFO - Running cycle 1/1 for dromaeo_css test... 22:25:41 INFO - TEST-INFO | started process 9472 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkYSNUK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:25:53 INFO - PROCESS | 9472 | RSS: Main: 143953920 22:25:53 INFO - PROCESS | 9472 | 22:26:44 INFO - PROCESS | 9472 | 1475645204699 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] 22:28:54 INFO - PROCESS | 9472 | Cycle 1(1): loaded http://localhost:40469/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40469/tests/dromaeo/cssquery-ext.html) 22:28:55 INFO - PROCESS | 9472 | RSS: Main: 242958336 22:28:55 INFO - PROCESS | 9472 | 22:31:57 INFO - PROCESS | 9472 | Cycle 1(1): loaded http://localhost:40469/tests/dromaeo/cssquery-ext.html (next: http://localhost:40469/tests/dromaeo/cssquery-jquery.html) 22:31:58 INFO - PROCESS | 9472 | RSS: Main: 225259520 22:31:58 INFO - PROCESS | 9472 | 22:34:59 INFO - PROCESS | 9472 | Cycle 1(1): loaded http://localhost:40469/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40469/tests/dromaeo/cssquery-mootools.html) 22:35:00 INFO - PROCESS | 9472 | RSS: Main: 174702592 22:35:00 INFO - PROCESS | 9472 | 22:38:02 INFO - PROCESS | 9472 | Cycle 1(1): loaded http://localhost:40469/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40469/tests/dromaeo/cssquery-prototype.html) 22:38:03 INFO - PROCESS | 9472 | RSS: Main: 216739840 22:38:03 INFO - PROCESS | 9472 | 22:41:05 INFO - PROCESS | 9472 | Cycle 1(1): loaded http://localhost:40469/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40469/tests/dromaeo/cssquery-yui.html) 22:41:06 INFO - PROCESS | 9472 | RSS: Main: 176852992 22:41:06 INFO - PROCESS | 9472 | 22:44:08 INFO - PROCESS | 9472 | Cycle 1(1): loaded http://localhost:40469/tests/dromaeo/cssquery-yui.html (next: http://localhost:40469/tests/dromaeo/cssquery-dojo.html) 22:44:09 INFO - PROCESS | 9472 | RSS: Main: 264212480 22:44:09 INFO - PROCESS | 9472 | 22:44:09 INFO - PROCESS | 9472 | __start_tp_report 22:44:09 INFO - PROCESS | 9472 | _x_x_mozilla_page_load 22:44:09 INFO - PROCESS | 9472 | _x_x_mozilla_page_load_details 22:44:09 INFO - PROCESS | 9472 | |i|pagename|runs| 22:44:09 INFO - PROCESS | 9472 | |0;dojo.html;3051.893183738569;3101.379724055188;3111.331063821278;3114.641816191139;3145.260863697032;5425.701586412751;5518;5536.75084621192;5543.722813859311;5545.944540554593;5540.008395466443;5608.327000759904;5611.438856114387;5616.3260408751;5622.746976386063;5055.317532133161;5076.6700164489375;5107.514786095315;5122.743862806863;5177.171652535595;4926.261060840867;5108.667936584129;5166.199239117943;5166.483351664841;5168.121419358718;4899.779509922062;4959.528844759742;5010.749462526877;5015.473375295596;5018.723970181639;5044.82343117991;5070.898582028357;5071.18860982241;5163.586913046985;5185.222216667492;4996.3504744383;5056.393232812086;5108.055009823194;5115.488451154892;5124.052050370656;5310.60170487215;5353.304070470853;5378.784848606051;5403.540699040586;5420.783168673248;5813.593048486608;5830.650160990312;5837.88324233513;5930.021546444831;5935.198748168985;5925.170476133345;5955.672438015908;5966.254218222723;5972.761089556412;5995.7901473448655;5367.624266301361;5411.242426060355;5469.753861076221;5498.092814685612;5509.47659972302;6013.9699301503215;6052.909206361908;6058.212432383762;6058.394160583906;6064.727087281083;5825.592208545361;5830.329512106162;5831.650100993869;5834.8541286467325;5835.912461313083;5676.375598684142;5747.885042299215;5758.424157584292;5841.561882858802;5852.502537284294;5892.94107058924;5895.587308888423;5897.144913987448;5898;5918.644881307136;5817.243758311477;5912.3496415394275;5914.408559144051;5916.9645312070215;5937.109433584997;4377.080813029236;4413.426254586947;4460.710053846555;4507.211238033331;4563.931541026887;3976.8210430530694;4023.979880100639;4041.474608300961;4044.2315959967514;4056.6957478189256;5603.943960560342;5604.551635869121;5607.467290607386;5607.635503692329;5609.747561359749;5747.3677895431465;5782.45066718661;5798.072308430631;5891;5898.8820223595285;5530.170474428787;5587.38538760736;5588.497035266845;5624.521915637135;5627.43725627434;4939.90120197594;4954.380702412199;4958.975205123951;4962.206047032458;4986.55121039101;5819.0689489681445;5831.620944638585;6043.456088952015;6054.152418661391;6059.333473317932;5843.532517398513;5866.090755932837;5874.118882167709;5903.970480147572;5919.792807251725;6142.631442113308;6143;6152.938470615237;6157.9384206157365;6158.938410615837;5568.415316391721;5853.2976042875125;5864.06175011996;5864.120381942743;5864.179014937827;5699.6865172414255;5856.853578660568;5879.617824841543;5916;6001.939980600138;5810.6513609183585;5829.883402331845;5837.153612726046;5838.970805145947;5843.590948633725;5724.0555308375315;5847.06446968483;5851;5853.678047707417;5854.0340843760305;5453.7818487260065;5507.972460137674;5677.148427735872;5681.602287839812;5722.513586345207;5841.240638716939;5847.093700476433;5853.9121913172185;5855.063189889597;5877.0302900021015;5508.807191748269;5685.374608792947;5698.629589076697;5712.143178523254;5714.371419143974 22:44:09 INFO - PROCESS | 9472 | |1;ext.html;594.447164137352;638.0811631250998;652.7911068458095;653.3042309939914;659.7822718502892;34657.480137797924;37752.81123594382;37917.43123853141;47873.28190077143;50255;47966.52033479664;48676.2698559521;48699.26951095736;48735.51264487354;48807.755961220144;9345.345825792214;9502.429854208734;9503.287253455985;9509.239260859114;9509.714708558755;8235.258826705594;8506.532140732257;9288.582013809395;11046.281991670556;11328.546858125665;11486.885131148707;11546.884531154665;11549.480273387719;11568.363739994296;11577.479013444416;11268.154888383404;11285.210035297532;11337.659870203906;11342.035926946219;11345.319280843176;11137.276077055047;11169.273997190237;11179.60871369498;11191.832122518168;11195.104391648649;45836.54163458356;46051.76974115108;46167;46287.537124628994;46415.767921160514;3549.911252218715;3621.022323972539;3701.1857391373856;3819.1406933439753;4243.639290660298;35118.122046948774;35175.4723679145;35316;35329.64670353315;35720.10699732501;4685.601723853477;4733.763311834429;4735.473968261807;4776.853555146775;4803.807847686088;368019;368346;370305;370453;371569;65288.34711652918;65843.34156658468;65887;65917.67041164763;66024.33975660279;40614;42089;42287;44208.557914421086;44239;17731;17899.910500447415;17900.820991790177;17909.820901791078;17916;11011.504482298316;13436.126651767603;13537.120087194498;13539.59381218565;13549;18214.089295535436;20488.89755551213;21145.577088458143;21372.251971180933;21847.453813654785;7421.591812450259;7524.811879702942;7557.206493318233;7643.541387516767;7662.578558179353;1004.5931397783909;1010.5959150545156;1068.3429690740138;1109.0351394287022;1119.6641007697658;35529.8223508886;35957.482976191415;36103.63896361055;36223.4566481503;36288.45567316492;1063.4895250279908;1127.1940562497853;1130.46867972053;1169.1582060916128;1179.5871444994184;18296.451106466826;18378.908105459388;18380.264789408273;18496.630067398575;18773.1552080154;569029;577448;577582;578642;580673;65932.34067659358;66301;66595.33404665989;66720;66972.66513667401;1293.489071816627;1342.5032737887045;1400.9229492378017;1420.971580568383;1451.4629587052857;31593.21016974502;36455;36869.63130368662;37208.81395593005;37330.81334593309;56414.71792641011;60472;63166.368336316045;63230.683846580476;69506;493910;494087;494346;494477;494738;31508.36983260368;32235.194120147185;32383.35233295368;33951.320973579896;48501.27248091211;53633;53821.730891345294;53839.46160538501;55457;55527.72236138793;44520;44861;45051.774741126086;45282.32076518789;46113.76943115263;44448.77775611101;45334.546654533035;45867.541324586324;46535.76732116318;46573.30140047834;42575.574244257165;45105.774471127435;45913;46332.7683361581;46479;37913.81043094767;39694;39960.60039399685;40192.59807401888;40811.59188408078;29595.556066659447;29955;30046.84976575103;30220.395592088476;30543.54184687276 22:44:09 INFO - PROCESS | 9472 | |2;jquery.html;385.54313138930354;411.98558050468256;420.94738157730285;424.12629982236587;430.7824548602957;14355.64110897228;16040.358385664575;19703.802961970378;20536.589268214626;20680;19747.605047899033;20268.29060982866;20334.186632534682;20334.779913205217;20510.2821401251;5756.251687280668;5790.392008839085;5882.6764527950945;5922.496587790043;6001.549883758714;4367.781610919457;4567.451905771312;5850.619709718854;5927.051671732524;5945.197398351211;6374.26695929967;6600.834979125512;6636.800895973129;6730.0577919091365;6735.158105236846;6465.903011454832;6611.603303801763;6641.93358066418;6652.234992975821;6697.19633643961;5761.654300741927;5911.586188966774;5913.526917846562;5914.2902851657645;5919.5264378850015;23609.76390236093;23921.28236152918;23937;24123.758762412326;24345.756542434527;2184.4429670434097;2227.732672079345;2424.200013995395;2786.122371452986;3094.5977022986867;14836;15236.314365853563;15339.846601533953;15529;15704.136272505008;1993.292381204666;2045.6011077839828;2058.6809044598112;2069.5240094778287;2109.4620871677776;82020;83873;83908;84798;86783;10332.450132480188;10534.315269507457;10813.62152324665;11414.086873050135;11457.828132578017;15950;16038.198090095448;16044.438444654614;16053.839461605234;16054.197290135446;8194.877076843852;8415.70545030921;8466.703665371682;8492.872606910902;8559.828803423896;7922.405819563556;8023.518588884685;8030.799230019296;8236.423450358538;8295.585220738938;6853.383195512428;6860.5540639858245;6933.653317334113;6980.650967451708;6994.125734283215;1990.2337600024096;2016.6672499037788;2051.979480205209;2089.6865470179596;2105.5683584865064;916.326500022483;953.6471505542855;990.4255531791543;1012.2256473797551;1071.1109778883508;20309;20756.58486830255;20977.68533471999;21063;21088.578228435344;1018.4805749068029;1022.4683164754434;1043.9582416703247;1083.8807731149568;1089.809283375422;10919.454027298603;11366.147538934447;11401.885981140082;11428.657140285804;12037.337946412856;519944;527626;528990;534969;537489;89863;96243;100179.49910250402;100969;102603.48698256462;1014.5789497358513;1055.9683209503728;1104.4808939798281;1134.8921852424007;1136.9317840929405;18173.909130454264;18300.90849545744;18337.724934126265;18767.34314298993;18786.06069696537;42157.57842421659;42396.364054538586;42764.78617606892;43645.781771090944;43992.78003609961;59464.70267648634;60197.69901150641;61093.38906610877;61149.08276375947;61605;8754;8756.474611523328;8805.955970220108;8904.465732056098;8991.730248092568;12767.93616031914;12795.296258705861;12939.676508087374;13353.132046416951;13485.325733713078;12319.815202772144;12327.50689972391;12404.75190496167;12582.433790479266;12782.68043298925;11874.703132421759;11977.161598688357;12094.153409261618;12508.374581271082;12655.303958282382;12273.263604183778;12666;12697.238165710087;12760.617181484571;12773.61679149627;8031.83936321282;8529.530875801891;8568.143185681382;8864.911350886408;8985.056569060154;11813.881861181622;11862.466189021343;11974.64076077719;12131.45408456604;12463.626091217278 22:44:09 INFO - PROCESS | 9472 | |3;mootools.html;1367.6717587778935;1455.1269238456928;1517.4916403004995;1663.5674724571609;1835.9173837177318;7489.325960663539;8300.501969881803;8580.270676992448;8589.269912057483;8824.029356770767;8620.827583448327;8704.564771761417;8715.041345452011;8717.346199035066;8720.956395218014;2406.651035599841;2422.2975337152293;2428.931270241097;2446.6085426331792;2447.90208391665;2372.5610762009082;2398.916037938664;2400.6879105716234;2402.5915594349003;2424;2393.664886915833;2401.939951501218;2407.8194135439826;2419.0082066352797;2423.9757602423924;2319.3505818370713;2333.404981729665;2335.2293743064834;2336.941576460585;2347.3779448446003;2306.5386922615376;2339.660749191358;2346.1905642553293;2348.788609025196;2349.5183487385043;8519.062903080656;8650.22148006676;8684.13158684133;8755.168259015447;8781.473111613324;5224.73876306187;5369.060414427459;5376.166694162411;5377.381601115843;5381.919271210935;3467.7225821934185;3524.2951409718157;3528.894133176009;3530.135116896351;3613.3857244268274;1123.1183520936108;1164.604034628222;1165.7726743285064;1191.839101721274;1208.1784386617096;11589.478473468715;11666.475008624633;11685.824712629232;11768.823467647991;11791.764164716744;4369.2790689536505;4484.775761211927;4599.839005634787;4642.930356044662;4648.604868586141;9433.481158536346;9434.433933963985;9438.764030899145;9477.857832132524;9500.049995000583;5311.229871668586;5313.229581710631;5315.3621565412395;5316.3354580677415;5326.0945639240645;4359.411479450234;4480.4175457191;4813.542713442217;4910.852674419773;5001;4003.199360127986;4019.196160767858;4089.734167279117;4118.91762164755;4119.032027473541;1245.0973044542634;1259.4899065878335;1266.4934026389333;1275.8341415616096;1290.4193113098954;958.7890664054037;963.9566219520138;981.9018098190123;989.4706332112286;1009.5557954499997;5765.942340576625;5807.477327040587;5944.64332140073;6015.0977353396465;6045.304789949212;477.6632474105817;477.84708893153856;478.7486569550944;485.1316144102018;493.0779442442655;4114.403411505315;4164.3961625564125;4210.221109094828;4217.957820421818;4282.400463935051;10437.530311136017;10680.62617808373;10795.730106747236;10803.621873234557;10804.729881753019;9928.205743540499;10647.627333043461;10651.946740266249;10705.732356691145;10711.357318560911;7478.775636730907;7673.808154796063;7674.040744906887;7678.347340476011;7680.769576912701;5740.770369185186;5764.19301297827;5884.617499862496;5886.38192989747;6021.006533921854;9882.752931176778;9929.702108936743;10001.749956251151;10004.699859004242;10012.549435275285;9614.9038509614;9925.702228933143;10110.646127385506;10206.846897296398;10229.641962531276;7010.088688470671;7152.213256541881;7455.962720186364;7655.732049378245;7794.766157015299;8458.830823383374;8467.618957147066;8469.364797640079;8469.57652117404;8471.237588617054;7508.962455187908;7625.51302496008;7696.422768292288;7784.377249819888;7957.283844454027;7263.418926485974;7335.486515943832;7429.257074292527;7630.465867389229;7631.465797394129;6852.931470685229;6947.44420446353;6949.30506949301;6953.895691564731;6962.303769622998;5057.544820966131;5074.264231686311;5107.693538387709;5137.640365174402;5228.581713462838;4964.156093464047;4979.153543897473;5195;5300.3639563252655;5381.73091345439 22:44:09 INFO - PROCESS | 9472 | |4;prototype.html;330.50919384713694;333.4647890136331;338.29296769751215;342.17706416069353;347.05427709491653;5302.628815982873;5452.700101494416;5462.918056229155;5531.557475401968;5551.639848237178;5242.659227150232;5364.356277246736;5408.91886621701;5438.184272359148;5444;4275.764832934187;4286.035641980545;4295.205387003399;4296.228932338639;4296.806643701026;4193.895152621179;4260.72305300156;4269.701120921537;4272.401863739079;4280.357946308063;4242.427272318244;4249.51130619978;4253.1493701259715;4261.870604289866;4264.55222201669;4237.643953934742;4256.765877876715;4258.957410425887;4264.33902745075;4277.422547956017;3673.4489826526233;3769.378052621314;3772.9622703772884;3773.226488569864;3777.8288730493637;5311.282976798121;5314.0700377433795;5320.228566857784;5321.733913304358;5324.760385782611;4876.756162191911;4886.75566221691;4888.364512613338;4894.583960363338;4913.2384480405235;5453.481919217668;5700.857478563028;5710.77156913723;5713.6000479966415;5718.628289161191;4084.5711200323835;4103.671706263492;4111.239420707178;4113.4035564843225;4126.195391898581;5863.677497737623;5866.2960444746905;5867.207926929896;5900.436384358149;5965.970170149121;4116.620931987818;4224.324108142682;4226.387173859773;4240.088380998101;4278.358246263086;4281.357796330575;4309.396684464177;4323.746113627076;4330.501992270866;4332.783360832009;4890.559849613552;4892.755362231874;4893.339399181065;4898.828541001048;4899.902001959941;5583.246261754693;5679.914801277984;5736.196932429463;5742.655440673573;5743.483086522233;1490.053815826936;1511.3425659838006;1512.9243537823286;1514.4775052606833;1516.9608817959734;923.7667713602282;957.3442192098435;960.3085778239656;962.9374090684083;965.5510187762724;4894.853154405374;4906.55840974314;4909.877253068702;4910.656254062182;4921.729304888266;5100.744962751847;5123.231515272739;5172.456892026358;5186.247994040843;5197.636165468457;4757.977034937436;4917.795140190676;4925.310456536088;4925.7783399747095;4965.783383071099;2744.8353098814137;2748.6151938728594;2751.614773931651;2754.986225068862;2758.4896794092756;5248.527632513016;5249.947500524946;5279.894402111936;5283.550898173698;5290.576753859682;4908.8772780680765;5220.712860792617;5234.048293538431;5264.815731449457;5269.919666468365;5259.8422047338645;5267.629566695003;5304.124819404755;5308.23030660552;5315.388730295988;5101.540861322499;5108.642395032312;5115.411727651368;5148.510891465455;5161.845144645667;5065.594752419724;5412.944475827176;5821.049948267378;5875;5876.147958546072;6044.697765111815;6049.485793707408;6051.243594550681;6062.454379105901;6066.18106555627;3930.194310166466;4026.375911733686;4077.6737860970456;4206;4212.389203565527;5837.854053648693;5858.326292476419;5865.354810970704;5901.7639294427745;5905.586608937333;5626.85932851682;5735.3977832327;5743.971280143573;5761.596688231783;5762.798302059407;5697.515711164598;5736.225609542658;5748.885022299447;5751.48236658686;5781.913271300849;5684.8010319638615;5685.971570142123;5688.232088668143;5696.829095127153;5700.914486282792;5590.720463976866;5728.398518155534;5739.253896993364;5777.62445441045;5785.247917770663;5596.4123767003875;5597.972010139923;5625.568403273918;5628;5636.408177141341 22:44:09 INFO - PROCESS | 9472 | |5;yui.html;782.816038231016;816.2042009041186;875.916787905149;885.2829208341244;905.5200743605891;16128.677426451479;16978;17087.914560427213;17091.743623845636;17135.400260990868;14467.421303147888;15122.546323610282;15558.377664893418;15642.765358519631;15673.686526269468;878.7715194049529;888.8888888888889;889.3330002498126;889.7419748272993;889.8442772514817;732.5934106570851;746.947713660044;854.2909385210291;887.2813021452612;894.2577660541734;922.395830730869;922.5525620074259;922.8892532896028;923.3905622289291;923.5243849417556;919.14978644754;919.9954000230023;920.0753242990752;920.4155361345561;921.3504479342081;550.7081246939129;565.1071307334402;660.6828722213364;675.5710124071201;676.4149011105359;15577.766333505006;15699.215039248105;15720;15736.92131539335;15739;14698.33857476416;15113.924430377778;15326.616834579114;15711;15713.13577753223;13515.459381624723;13525.797113043312;13558.93220533891;13578.388972496261;13807.309634518233;4388.144311859189;4468.82124715011;4569.771511424448;4598.356230127785;4611.515790841947;79997.60001199957;80726.59636701837;81068.59465702633;83319.58340208321;83911;12309.630711078682;12444.253344799341;12563.685907852194;12609.810852837032;12678.746425071447;23163.189288374826;23327.066917323456;23372.76627233706;23378.18176363819;23389.181378651665;8575.356848236408;8629.39594228398;8783.121687831166;8835.867461988075;8839.248663863644;4934.087193869074;4987.700737955734;4997.200447928314;5037.420696619935;5064.797408103635;3466.9826650866585;3584.4981702561663;3599.4960705501253;3603.747737658339;3606.549181352331;540.2706346432285;610.0422336930976;611.6452457574596;612.3325575123138;616.8766246750578;613.3253421236604;641.7625478572958;720.4560556779597;735.4300417176623;755.8299382638972;9838.803223935482;9928;9973.850392244121;10066.899331006744;10337.37975721459;634.6002018728171;695.6765104226558;726.8910059616954;728.2680905689748;740.1303468424579;4572.177008138567;4862.5380588844;4867;4867.610591152699;4938.111139994764;73925;74116.62941685256;74161;121174.78825211812;124226;85047;108285.4585727082;108580;110418;112809.43595281971;85890.57054714687;88177.55911220532;89302.55348723214;91986;92047.5397623021;9693.321467497208;9899.564563138161;10095.44475053879;10148.543315550965;10378.636747714096;43585;46540.30189547227;48260.27609585929;49540.50459495503;53633.73183134059;67232;67898.3210167892;69099.30900690929;75092;76135.23864761282;13539.661508462368;13647.044706870822;14032.649183770487;14721.822254219422;14756.926215369283;13670.726585468035;13709;13951;16292.266847991632;16441.17794110266;12849.871501284868;13062.738745224851;13682.042257041912;14702.77945830792;14823.629409264855;13263.535776247785;14543.272836358352;14705.485308014167;15248.618784530032;15588;14800.925995370386;15250.084994899898;15610.063396196265;15841.445549405715;15974.8402515978;11776.823347649963;12492.25046497213;12606.432710528232;13098.410571524113;13233.338333083115;12176.57381991626;12453.564125255572;12622.55821046259;12685.175463595206;12964.935175324063 22:44:09 INFO - PROCESS | 9472 | __end_tp_report 22:44:09 INFO - PROCESS | 9472 | __start_cc_report 22:44:09 INFO - PROCESS | 9472 | _x_x_mozilla_cycle_collect,913 22:44:09 INFO - PROCESS | 9472 | __end_cc_report 22:44:09 INFO - PROCESS | 9472 | __startTimestamp1475646249107__endTimestamp 22:44:09 INFO - PROCESS | 9472 | 22:44:09 INFO - PROCESS | 9472 | ------- Summary: start ------- 22:44:09 INFO - PROCESS | 9472 | Number of tests: 6 22:44:09 INFO - PROCESS | 9472 | 22:44:09 INFO - PROCESS | 9472 | [#0] dojo.html Cycles:165 Average:5474.07 Median:5685.37 stddev:628.57 (11.1%) stddev-sans-first:601.09 22:44:09 INFO - PROCESS | 9472 | Values: 3051.9 3101.4 3111.3 3114.6 3145.3 5425.7 5518.0 5536.8 5543.7 5545.9 5540.0 5608.3 5611.4 5616.3 5622.7 5055.3 5076.7 5107.5 5122.7 5177.2 4926.3 5108.7 5166.2 5166.5 5168.1 4899.8 4959.5 5010.7 5015.5 5018.7 5044.8 5070.9 5071.2 5163.6 5185.2 4996.4 5056.4 5108.1 5115.5 5124.1 5310.6 5353.3 5378.8 5403.5 5420.8 5813.6 5830.7 5837.9 5930.0 5935.2 5925.2 5955.7 5966.3 5972.8 5995.8 5367.6 5411.2 5469.8 5498.1 5509.5 6014.0 6052.9 6058.2 6058.4 6064.7 5825.6 5830.3 5831.7 5834.9 5835.9 5676.4 5747.9 5758.4 5841.6 5852.5 5892.9 5895.6 5897.1 5898.0 5918.6 5817.2 5912.3 5914.4 5917.0 5937.1 4377.1 4413.4 4460.7 4507.2 4563.9 3976.8 4024.0 4041.5 4044.2 4056.7 5603.9 5604.6 5607.5 5607.6 5609.7 5747.4 5782.5 5798.1 5891.0 5898.9 5530.2 5587.4 5588.5 5624.5 5627.4 4939.9 4954.4 4959.0 4962.2 4986.6 5819.1 5831.6 6043.5 6054.2 6059.3 5843.5 5866.1 5874.1 5904.0 5919.8 6142.6 6143.0 6152.9 6157.9 6158.9 5568.4 5853.3 5864.1 5864.1 5864.2 5699.7 5856.9 5879.6 5916.0 6001.9 5810.7 5829.9 5837.2 5839.0 5843.6 5724.1 5847.1 5851.0 5853.7 5854.0 5453.8 5508.0 5677.1 5681.6 5722.5 5841.2 5847.1 5853.9 5855.1 5877.0 5508.8 5685.4 5698.6 5712.1 5714.4 22:44:09 INFO - PROCESS | 9472 | 22:44:09 INFO - PROCESS | 9472 | [#1] ext.html Cycles:180 Average:65598.84 Median:40076.60 stddev:129265.13 (322.5%) stddev-sans-first:129535.61 22:44:09 INFO - PROCESS | 9472 | Values: 594.4 638.1 652.8 653.3 659.8 34657.5 37752.8 37917.4 47873.3 50255.0 47966.5 48676.3 48699.3 48735.5 48807.8 9345.3 9502.4 9503.3 9509.2 9509.7 8235.3 8506.5 9288.6 11046.3 11328.5 11486.9 11546.9 11549.5 11568.4 11577.5 11268.2 11285.2 11337.7 11342.0 11345.3 11137.3 11169.3 11179.6 11191.8 11195.1 45836.5 46051.8 46167.0 46287.5 46415.8 3549.9 3621.0 3701.2 3819.1 4243.6 35118.1 35175.5 35316.0 35329.6 35720.1 4685.6 4733.8 4735.5 4776.9 4803.8 368019.0 368346.0 370305.0 370453.0 371569.0 65288.3 65843.3 65887.0 65917.7 66024.3 40614.0 42089.0 42287.0 44208.6 44239.0 17731.0 17899.9 17900.8 17909.8 17916.0 11011.5 13436.1 13537.1 13539.6 13549.0 18214.1 20488.9 21145.6 21372.3 21847.5 7421.6 7524.8 7557.2 7643.5 7662.6 1004.6 1010.6 1068.3 1109.0 1119.7 35529.8 35957.5 36103.6 36223.5 36288.5 1063.5 1127.2 1130.5 1169.2 1179.6 18296.5 18378.9 18380.3 18496.6 18773.2 569029.0 577448.0 577582.0 578642.0 580673.0 65932.3 66301.0 66595.3 66720.0 66972.7 1293.5 1342.5 1400.9 1421.0 1451.5 31593.2 36455.0 36869.6 37208.8 37330.8 56414.7 60472.0 63166.4 63230.7 69506.0 493910.0 494087.0 494346.0 494477.0 494738.0 31508.4 32235.2 32383.4 33951.3 48501.3 53633.0 53821.7 53839.5 55457.0 55527.7 44520.0 44861.0 45051.8 45282.3 46113.8 44448.8 45334.5 45867.5 46535.8 46573.3 42575.6 45105.8 45913.0 46332.8 46479.0 37913.8 39694.0 39960.6 40192.6 40811.6 29595.6 29955.0 30046.8 30220.4 30543.5 22:44:09 INFO - PROCESS | 9472 | 22:44:09 INFO - PROCESS | 9472 | [#2] jquery.html Cycles:180 Average:30975.07 Median:2206.09 stddev:87263.22 (3955.6%) stddev-sans-first:87477.79 22:44:09 INFO - PROCESS | 9472 | Values: 385.5 412.0 420.9 424.1 430.8 14355.6 16040.4 19703.8 20536.6 20680.0 19747.6 20268.3 20334.2 20334.8 20510.3 5756.3 5790.4 5882.7 5922.5 6001.5 4367.8 4567.5 5850.6 5927.1 5945.2 6374.3 6600.8 6636.8 6730.1 6735.2 6465.9 6611.6 6641.9 6652.2 6697.2 5761.7 5911.6 5913.5 5914.3 5919.5 23609.8 23921.3 23937.0 24123.8 24345.8 2184.4 2227.7 2424.2 2786.1 3094.6 14836.0 15236.3 15339.8 15529.0 15704.1 1993.3 2045.6 2058.7 2069.5 2109.5 82020.0 83873.0 83908.0 84798.0 86783.0 10332.5 10534.3 10813.6 11414.1 11457.8 15950.0 16038.2 16044.4 16053.8 16054.2 8194.9 8415.7 8466.7 8492.9 8559.8 7922.4 8023.5 8030.8 8236.4 8295.6 6853.4 6860.6 6933.7 6980.7 6994.1 1990.2 2016.7 2052.0 2089.7 2105.6 916.3 953.6 990.4 1012.2 1071.1 20309.0 20756.6 20977.7 21063.0 21088.6 1018.5 1022.5 1044.0 1083.9 1089.8 10919.5 11366.1 11401.9 11428.7 12037.3 519944.0 527626.0 528990.0 534969.0 537489.0 89863.0 96243.0 100179.5 100969.0 102603.5 1014.6 1056.0 1104.5 1134.9 1136.9 18173.9 18300.9 18337.7 18767.3 18786.1 42157.6 42396.4 42764.8 43645.8 43992.8 59464.7 60197.7 61093.4 61149.1 61605.0 8754.0 8756.5 8806.0 8904.5 8991.7 12767.9 12795.3 12939.7 13353.1 13485.3 12319.8 12327.5 12404.8 12582.4 12782.7 11874.7 11977.2 12094.2 12508.4 12655.3 12273.3 12666.0 12697.2 12760.6 12773.6 8031.8 8529.5 8568.1 8864.9 8985.1 11813.9 11862.5 11974.6 12131.5 12463.6 22:44:09 INFO - PROCESS | 9472 | 22:44:09 INFO - PROCESS | 9472 | [#3] mootools.html Cycles:180 Average:5691.56 Median:4971.65 stddev:3164.89 (63.7%) stddev-sans-first:3157.09 22:44:09 INFO - PROCESS | 9472 | Values: 1367.7 1455.1 1517.5 1663.6 1835.9 7489.3 8300.5 8580.3 8589.3 8824.0 8620.8 8704.6 8715.0 8717.3 8721.0 2406.7 2422.3 2428.9 2446.6 2447.9 2372.6 2398.9 2400.7 2402.6 2424.0 2393.7 2401.9 2407.8 2419.0 2424.0 2319.4 2333.4 2335.2 2336.9 2347.4 2306.5 2339.7 2346.2 2348.8 2349.5 8519.1 8650.2 8684.1 8755.2 8781.5 5224.7 5369.1 5376.2 5377.4 5381.9 3467.7 3524.3 3528.9 3530.1 3613.4 1123.1 1164.6 1165.8 1191.8 1208.2 11589.5 11666.5 11685.8 11768.8 11791.8 4369.3 4484.8 4599.8 4642.9 4648.6 9433.5 9434.4 9438.8 9477.9 9500.0 5311.2 5313.2 5315.4 5316.3 5326.1 4359.4 4480.4 4813.5 4910.9 5001.0 4003.2 4019.2 4089.7 4118.9 4119.0 1245.1 1259.5 1266.5 1275.8 1290.4 958.8 964.0 981.9 989.5 1009.6 5765.9 5807.5 5944.6 6015.1 6045.3 477.7 477.8 478.7 485.1 493.1 4114.4 4164.4 4210.2 4218.0 4282.4 10437.5 10680.6 10795.7 10803.6 10804.7 9928.2 10647.6 10651.9 10705.7 10711.4 7478.8 7673.8 7674.0 7678.3 7680.8 5740.8 5764.2 5884.6 5886.4 6021.0 9882.8 9929.7 10001.7 10004.7 10012.5 9614.9 9925.7 10110.6 10206.8 10229.6 7010.1 7152.2 7456.0 7655.7 7794.8 8458.8 8467.6 8469.4 8469.6 8471.2 7509.0 7625.5 7696.4 7784.4 7957.3 7263.4 7335.5 7429.3 7630.5 7631.5 6852.9 6947.4 6949.3 6953.9 6962.3 5057.5 5074.3 5107.7 5137.6 5228.6 4964.2 4979.2 5195.0 5300.4 5381.7 22:44:09 INFO - PROCESS | 9472 | 22:44:09 INFO - PROCESS | 9472 | [#4] prototype.html Cycles:180 Average:4671.20 Median:5191.94 stddev:1349.72 (26.0%) stddev-sans-first:1313.60 22:44:09 INFO - PROCESS | 9472 | Values: 330.5 333.5 338.3 342.2 347.1 5302.6 5452.7 5462.9 5531.6 5551.6 5242.7 5364.4 5408.9 5438.2 5444.0 4275.8 4286.0 4295.2 4296.2 4296.8 4193.9 4260.7 4269.7 4272.4 4280.4 4242.4 4249.5 4253.1 4261.9 4264.6 4237.6 4256.8 4259.0 4264.3 4277.4 3673.4 3769.4 3773.0 3773.2 3777.8 5311.3 5314.1 5320.2 5321.7 5324.8 4876.8 4886.8 4888.4 4894.6 4913.2 5453.5 5700.9 5710.8 5713.6 5718.6 4084.6 4103.7 4111.2 4113.4 4126.2 5863.7 5866.3 5867.2 5900.4 5966.0 4116.6 4224.3 4226.4 4240.1 4278.4 4281.4 4309.4 4323.7 4330.5 4332.8 4890.6 4892.8 4893.3 4898.8 4899.9 5583.2 5679.9 5736.2 5742.7 5743.5 1490.1 1511.3 1512.9 1514.5 1517.0 923.8 957.3 960.3 962.9 965.6 4894.9 4906.6 4909.9 4910.7 4921.7 5100.7 5123.2 5172.5 5186.2 5197.6 4758.0 4917.8 4925.3 4925.8 4965.8 2744.8 2748.6 2751.6 2755.0 2758.5 5248.5 5249.9 5279.9 5283.6 5290.6 4908.9 5220.7 5234.0 5264.8 5269.9 5259.8 5267.6 5304.1 5308.2 5315.4 5101.5 5108.6 5115.4 5148.5 5161.8 5065.6 5412.9 5821.0 5875.0 5876.1 6044.7 6049.5 6051.2 6062.5 6066.2 3930.2 4026.4 4077.7 4206.0 4212.4 5837.9 5858.3 5865.4 5901.8 5905.6 5626.9 5735.4 5744.0 5761.6 5762.8 5697.5 5736.2 5748.9 5751.5 5781.9 5684.8 5686.0 5688.2 5696.8 5700.9 5590.7 5728.4 5739.3 5777.6 5785.2 5596.4 5598.0 5625.6 5628.0 5636.4 22:44:09 INFO - PROCESS | 9472 | 22:44:09 INFO - PROCESS | 9472 | [#5] yui.html Cycles:180 Average:20938.60 Median:4585.27 stddev:29151.48 (635.8%) stddev-sans-first:29193.97 22:44:09 INFO - PROCESS | 9472 | Values: 782.8 816.2 875.9 885.3 905.5 16128.7 16978.0 17087.9 17091.7 17135.4 14467.4 15122.5 15558.4 15642.8 15673.7 878.8 888.9 889.3 889.7 889.8 732.6 746.9 854.3 887.3 894.3 922.4 922.6 922.9 923.4 923.5 919.1 920.0 920.1 920.4 921.4 550.7 565.1 660.7 675.6 676.4 15577.8 15699.2 15720.0 15736.9 15739.0 14698.3 15113.9 15326.6 15711.0 15713.1 13515.5 13525.8 13558.9 13578.4 13807.3 4388.1 4468.8 4569.8 4598.4 4611.5 79997.6 80726.6 81068.6 83319.6 83911.0 12309.6 12444.3 12563.7 12609.8 12678.7 23163.2 23327.1 23372.8 23378.2 23389.2 8575.4 8629.4 8783.1 8835.9 8839.2 4934.1 4987.7 4997.2 5037.4 5064.8 3467.0 3584.5 3599.5 3603.7 3606.5 540.3 610.0 611.6 612.3 616.9 613.3 641.8 720.5 735.4 755.8 9838.8 9928.0 9973.9 10066.9 10337.4 634.6 695.7 726.9 728.3 740.1 4572.2 4862.5 4867.0 4867.6 4938.1 73925.0 74116.6 74161.0 121174.8 124226.0 85047.0 108285.5 108580.0 110418.0 112809.4 85890.6 88177.6 89302.6 91986.0 92047.5 9693.3 9899.6 10095.4 10148.5 10378.6 43585.0 46540.3 48260.3 49540.5 53633.7 67232.0 67898.3 69099.3 75092.0 76135.2 13539.7 13647.0 14032.6 14721.8 14756.9 13670.7 13709.0 13951.0 16292.3 16441.2 12849.9 13062.7 13682.0 14702.8 14823.6 13263.5 14543.3 14705.5 15248.6 15588.0 14800.9 15250.1 15610.1 15841.4 15974.8 11776.8 12492.3 12606.4 13098.4 13233.3 12176.6 12453.6 12622.6 12685.2 12964.9 22:44:09 INFO - PROCESS | 9472 | -------- Summary: end -------- 22:44:09 INFO - PROCESS | 9472 | 22:44:09 INFO - TEST-INFO | 9472: exit 0 22:44:09 INFO - TEST-OK | dromaeo_css | took 1113760ms 22:44:09 INFO - TEST-START | kraken 22:44:09 INFO - operating with platform_type : linux_ 22:44:09 INFO - Initialising browser for kraken test... 22:44:09 INFO - TEST-INFO | started process 9789 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPbrLSZ/profile http://localhost:40469/getInfo.html) 22:44:13 INFO - PROCESS | 9789 | __metrics Screen width/height:1600/1200 22:44:13 INFO - PROCESS | 9789 | colorDepth:24 22:44:13 INFO - PROCESS | 9789 | Browser inner width/height: 1024/697 22:44:13 INFO - PROCESS | 9789 | __metrics 22:44:14 INFO - PROCESS | 9789 | 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" 22:44:14 INFO - PROCESS | 9789 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:44:14 INFO - PROCESS | 9789 | console.error: PushService: 22:44:14 INFO - PROCESS | 9789 | stateChangeProcessEnqueue: Error transitioning state 22:44:14 INFO - PROCESS | 9789 | UnknownError 22:44:15 INFO - TEST-INFO | 9789: exit 0 22:44:15 INFO - Browser initialized. 22:44:15 INFO - Running cycle 1/1 for kraken test... 22:44:15 INFO - TEST-INFO | started process 9885 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPbrLSZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:44:27 INFO - PROCESS | 9885 | RSS: Main: 144519168 22:44:27 INFO - PROCESS | 9885 | 22:44:29 INFO - PROCESS | 9885 | Cycle 1(1): loaded http://localhost:40469/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40469/tests/kraken/driver.html?testName=audio-beat-detection) 22:44:30 INFO - PROCESS | 9885 | RSS: Main: 186134528 22:44:30 INFO - PROCESS | 9885 | 22:44:33 INFO - PROCESS | 9885 | Cycle 1(1): loaded http://localhost:40469/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40469/tests/kraken/driver.html?testName=audio-dft) 22:44:33 INFO - PROCESS | 9885 | RSS: Main: 175243264 22:44:33 INFO - PROCESS | 9885 | 22:44:36 INFO - PROCESS | 9885 | Cycle 1(1): loaded http://localhost:40469/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40469/tests/kraken/driver.html?testName=audio-fft) 22:44:37 INFO - PROCESS | 9885 | RSS: Main: 267321344 22:44:37 INFO - PROCESS | 9885 | 22:44:39 INFO - PROCESS | 9885 | Cycle 1(1): loaded http://localhost:40469/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40469/tests/kraken/driver.html?testName=audio-oscillator) 22:44:39 INFO - PROCESS | 9885 | RSS: Main: 173203456 22:44:39 INFO - PROCESS | 9885 | 22:44:41 INFO - PROCESS | 9885 | Cycle 1(1): loaded http://localhost:40469/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40469/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:44:42 INFO - PROCESS | 9885 | RSS: Main: 175271936 22:44:42 INFO - PROCESS | 9885 | 22:44:45 INFO - PROCESS | 9885 | Cycle 1(1): loaded http://localhost:40469/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40469/tests/kraken/driver.html?testName=imaging-darkroom) 22:44:46 INFO - PROCESS | 9885 | RSS: Main: 211046400 22:44:46 INFO - PROCESS | 9885 | 22:44:49 INFO - PROCESS | 9885 | Cycle 1(1): loaded http://localhost:40469/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40469/tests/kraken/driver.html?testName=imaging-desaturate) 22:44:50 INFO - PROCESS | 9885 | RSS: Main: 209408000 22:44:50 INFO - PROCESS | 9885 | 22:44:52 INFO - PROCESS | 9885 | Cycle 1(1): loaded http://localhost:40469/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40469/tests/kraken/driver.html?testName=json-parse-financial) 22:44:53 INFO - PROCESS | 9885 | RSS: Main: 209145856 22:44:53 INFO - PROCESS | 9885 | 22:44:54 INFO - PROCESS | 9885 | Cycle 1(1): loaded http://localhost:40469/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40469/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:44:55 INFO - PROCESS | 9885 | RSS: Main: 171556864 22:44:55 INFO - PROCESS | 9885 | 22:44:57 INFO - PROCESS | 9885 | Cycle 1(1): loaded http://localhost:40469/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40469/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:44:57 INFO - PROCESS | 9885 | RSS: Main: 178757632 22:44:57 INFO - PROCESS | 9885 | 22:44:59 INFO - PROCESS | 9885 | Cycle 1(1): loaded http://localhost:40469/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40469/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:45:00 INFO - PROCESS | 9885 | RSS: Main: 188461056 22:45:00 INFO - PROCESS | 9885 | 22:45:02 INFO - PROCESS | 9885 | Cycle 1(1): loaded http://localhost:40469/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40469/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:45:03 INFO - PROCESS | 9885 | RSS: Main: 196177920 22:45:03 INFO - PROCESS | 9885 | 22:45:06 INFO - PROCESS | 9885 | Cycle 1(1): loaded http://localhost:40469/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40469/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:45:06 INFO - PROCESS | 9885 | RSS: Main: 193544192 22:45:06 INFO - PROCESS | 9885 | 22:45:08 INFO - PROCESS | 9885 | Cycle 1(1): loaded http://localhost:40469/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40469/tests/kraken/driver.html?testName=ai-astar) 22:45:08 INFO - PROCESS | 9885 | RSS: Main: 245145600 22:45:08 INFO - PROCESS | 9885 | 22:45:08 INFO - PROCESS | 9885 | __start_tp_report 22:45:08 INFO - PROCESS | 9885 | _x_x_mozilla_page_load 22:45:08 INFO - PROCESS | 9885 | _x_x_mozilla_page_load_details 22:45:08 INFO - PROCESS | 9885 | |i|pagename|runs| 22:45:08 INFO - PROCESS | 9885 | |0;ai-astar;109;112;110;113;110;110;111;110;109;110 22:45:08 INFO - PROCESS | 9885 | |1;audio-beat-detection;147;121;116;153;125;123;122;123;147;123 22:45:08 INFO - PROCESS | 9885 | |2;audio-dft;160;159;163;162;160;159;160;159;158;166 22:45:08 INFO - PROCESS | 9885 | |3;audio-fft;74;76;76;109;76;76;75;76;95;75 22:45:08 INFO - PROCESS | 9885 | |4;audio-oscillator;81;82;125;82;82;82;104;82;82;106 22:45:08 INFO - PROCESS | 9885 | |5;imaging-gaussian-blur;117;117;116;117;117;117;116;117;116;117 22:45:08 INFO - PROCESS | 9885 | |6;imaging-darkroom;146;147;146;145;145;145;146;147;145;145 22:45:08 INFO - PROCESS | 9885 | |7;imaging-desaturate;96;97;95;95;95;95;95;95;95;95 22:45:08 INFO - PROCESS | 9885 | |8;json-parse-financial;49;52;51;49;49;50;52;51;50;52 22:45:08 INFO - PROCESS | 9885 | |9;json-stringify-tinderbox;58;58;59;58;58;58;57;57;58;59 22:45:08 INFO - PROCESS | 9885 | |10;stanford-crypto-aes;73;73;72;79;72;73;73;72;73;73 22:45:08 INFO - PROCESS | 9885 | |11;stanford-crypto-ccm;121;121;121;125;126;122;120;122;164;121 22:45:08 INFO - PROCESS | 9885 | |12;stanford-crypto-pbkdf2;151;157;150;153;152;151;152;154;156;152 22:45:08 INFO - PROCESS | 9885 | |13;stanford-crypto-sha256-iterative;62;64;62;61;62;63;61;67;60;60 22:45:08 INFO - PROCESS | 9885 | __end_tp_report 22:45:08 INFO - PROCESS | 9885 | __start_cc_report 22:45:08 INFO - PROCESS | 9885 | _x_x_mozilla_cycle_collect,1577 22:45:08 INFO - PROCESS | 9885 | __end_cc_report 22:45:08 INFO - PROCESS | 9885 | __startTimestamp1475646308558__endTimestamp 22:45:08 INFO - PROCESS | 9885 | 22:45:08 INFO - PROCESS | 9885 | ------- Summary: start ------- 22:45:08 INFO - PROCESS | 9885 | Number of tests: 14 22:45:08 INFO - PROCESS | 9885 | 22:45:08 INFO - PROCESS | 9885 | [#0] ai-astar Cycles:10 Average:110.40 Median:110.00 stddev:1.26 (1.1%) stddev-sans-first:1.24 22:45:08 INFO - PROCESS | 9885 | Values: 109.0 112.0 110.0 113.0 110.0 110.0 111.0 110.0 109.0 110.0 22:45:08 INFO - PROCESS | 9885 | 22:45:08 INFO - PROCESS | 9885 | [#1] audio-beat-detection Cycles:10 Average:130.00 Median:124.00 stddev:13.42 (10.8%) stddev-sans-first:12.74 22:45:08 INFO - PROCESS | 9885 | Values: 147.0 121.0 116.0 153.0 125.0 123.0 122.0 123.0 147.0 123.0 22:45:08 INFO - PROCESS | 9885 | 22:45:08 INFO - PROCESS | 9885 | [#2] audio-dft Cycles:10 Average:160.60 Median:160.00 stddev:2.41 (1.5%) stddev-sans-first:2.55 22:45:08 INFO - PROCESS | 9885 | Values: 160.0 159.0 163.0 162.0 160.0 159.0 160.0 159.0 158.0 166.0 22:45:08 INFO - PROCESS | 9885 | 22:45:08 INFO - PROCESS | 9885 | [#3] audio-fft Cycles:10 Average:80.80 Median:76.00 stddev:11.67 (15.4%) stddev-sans-first:12.12 22:45:08 INFO - PROCESS | 9885 | Values: 74.0 76.0 76.0 109.0 76.0 76.0 75.0 76.0 95.0 75.0 22:45:08 INFO - PROCESS | 9885 | 22:45:08 INFO - PROCESS | 9885 | [#4] audio-oscillator Cycles:10 Average:90.80 Median:82.00 stddev:15.40 (18.8%) stddev-sans-first:15.93 22:45:08 INFO - PROCESS | 9885 | Values: 81.0 82.0 125.0 82.0 82.0 82.0 104.0 82.0 82.0 106.0 22:45:08 INFO - PROCESS | 9885 | 22:45:08 INFO - PROCESS | 9885 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 22:45:08 INFO - PROCESS | 9885 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 116.0 117.0 116.0 117.0 22:45:08 INFO - PROCESS | 9885 | 22:45:08 INFO - PROCESS | 9885 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 22:45:08 INFO - PROCESS | 9885 | Values: 146.0 147.0 146.0 145.0 145.0 145.0 146.0 147.0 145.0 145.0 22:45:08 INFO - PROCESS | 9885 | 22:45:08 INFO - PROCESS | 9885 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.67 22:45:08 INFO - PROCESS | 9885 | Values: 96.0 97.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 22:45:08 INFO - PROCESS | 9885 | 22:45:08 INFO - PROCESS | 9885 | [#8] json-parse-financial Cycles:10 Average:50.50 Median:51.00 stddev:1.27 (2.5%) stddev-sans-first:1.22 22:45:08 INFO - PROCESS | 9885 | Values: 49.0 52.0 51.0 49.0 49.0 50.0 52.0 51.0 50.0 52.0 22:45:08 INFO - PROCESS | 9885 | 22:45:08 INFO - PROCESS | 9885 | [#9] json-stringify-tinderbox Cycles:10 Average:58.00 Median:58.00 stddev:0.67 (1.1%) stddev-sans-first:0.71 22:45:08 INFO - PROCESS | 9885 | Values: 58.0 58.0 59.0 58.0 58.0 58.0 57.0 57.0 58.0 59.0 22:45:08 INFO - PROCESS | 9885 | 22:45:08 INFO - PROCESS | 9885 | [#10] stanford-crypto-aes Cycles:10 Average:73.30 Median:73.00 stddev:2.06 (2.8%) stddev-sans-first:2.18 22:45:08 INFO - PROCESS | 9885 | Values: 73.0 73.0 72.0 79.0 72.0 73.0 73.0 72.0 73.0 73.0 22:45:08 INFO - PROCESS | 9885 | 22:45:08 INFO - PROCESS | 9885 | [#11] stanford-crypto-ccm Cycles:10 Average:126.30 Median:122.00 stddev:13.38 (11.0%) stddev-sans-first:14.06 22:45:08 INFO - PROCESS | 9885 | Values: 121.0 121.0 121.0 125.0 126.0 122.0 120.0 122.0 164.0 121.0 22:45:08 INFO - PROCESS | 9885 | 22:45:08 INFO - PROCESS | 9885 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.80 Median:152.50 stddev:2.25 (1.5%) stddev-sans-first:2.29 22:45:08 INFO - PROCESS | 9885 | Values: 151.0 157.0 150.0 153.0 152.0 151.0 152.0 154.0 156.0 152.0 22:45:08 INFO - PROCESS | 9885 | 22:45:08 INFO - PROCESS | 9885 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.20 Median:62.00 stddev:2.10 (3.4%) stddev-sans-first:2.22 22:45:08 INFO - PROCESS | 9885 | Values: 62.0 64.0 62.0 61.0 62.0 63.0 61.0 67.0 60.0 60.0 22:45:08 INFO - PROCESS | 9885 | -------- Summary: end -------- 22:45:08 INFO - PROCESS | 9885 | 22:45:09 INFO - TEST-INFO | 9885: exit 0 22:45:09 INFO - TEST-OK | kraken | took 59899ms 22:45:09 INFO - SUITE-END | took 1173s 22:45:10 INFO - Completed test suite (00:19:34) 22:45:10 INFO - javascript benchmark 22:45:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3051.893183738569, 3101.379724055188, 3111.331063821278, 3114.641816191139, 3145.260863697032, 5425.701586412751, 5518.0, 5536.75084621192, 5543.722813859311, 5545.944540554593, 5540.008395466443, 5608.327000759904, 5611.438856114387, 5616.3260408751, 5622.746976386063, 5055.317532133161, 5076.6700164489375, 5107.514786095315, 5122.743862806863, 5177.171652535595, 4926.261060840867, 5108.667936584129, 5166.199239117943, 5166.483351664841, 5168.121419358718, 4899.779509922062, 4959.528844759742, 5010.749462526877, 5015.473375295596, 5018.723970181639, 5044.82343117991, 5070.898582028357, 5071.18860982241, 5163.586913046985, 5185.222216667492, 4996.3504744383, 5056.393232812086, 5108.055009823194, 5115.488451154892, 5124.052050370656, 5310.60170487215, 5353.304070470853, 5378.784848606051, 5403.540699040586, 5420.783168673248, 5813.593048486608, 5830.650160990312, 5837.88324233513, 5930.021546444831, 5935.198748168985, 5925.170476133345, 5955.672438015908, 5966.254218222723, 5972.761089556412, 5995.7901473448655, 5367.624266301361, 5411.242426060355, 5469.753861076221, 5498.092814685612, 5509.47659972302, 6013.9699301503215, 6052.909206361908, 6058.212432383762, 6058.394160583906, 6064.727087281083, 5825.592208545361, 5830.329512106162, 5831.650100993869, 5834.8541286467325, 5835.912461313083, 5676.375598684142, 5747.885042299215, 5758.424157584292, 5841.561882858802, 5852.502537284294, 5892.94107058924, 5895.587308888423, 5897.144913987448, 5898.0, 5918.644881307136, 5817.243758311477, 5912.3496415394275, 5914.408559144051, 5916.9645312070215, 5937.109433584997, 4377.080813029236, 4413.426254586947, 4460.710053846555, 4507.211238033331, 4563.931541026887, 3976.8210430530694, 4023.979880100639, 4041.474608300961, 4044.2315959967514, 4056.6957478189256, 5603.943960560342, 5604.551635869121, 5607.467290607386, 5607.635503692329, 5609.747561359749, 5747.3677895431465, 5782.45066718661, 5798.072308430631, 5891.0, 5898.8820223595285, 5530.170474428787, 5587.38538760736, 5588.497035266845, 5624.521915637135, 5627.43725627434, 4939.90120197594, 4954.380702412199, 4958.975205123951, 4962.206047032458, 4986.55121039101, 5819.0689489681445, 5831.620944638585, 6043.456088952015, 6054.152418661391, 6059.333473317932, 5843.532517398513, 5866.090755932837, 5874.118882167709, 5903.970480147572, 5919.792807251725, 6142.631442113308, 6143.0, 6152.938470615237, 6157.9384206157365, 6158.938410615837, 5568.415316391721, 5853.2976042875125, 5864.06175011996, 5864.120381942743, 5864.179014937827, 5699.6865172414255, 5856.853578660568, 5879.617824841543, 5916.0, 6001.939980600138, 5810.6513609183585, 5829.883402331845, 5837.153612726046, 5838.970805145947, 5843.590948633725, 5724.0555308375315, 5847.06446968483, 5851.0, 5853.678047707417, 5854.0340843760305, 5453.7818487260065, 5507.972460137674, 5677.148427735872, 5681.602287839812, 5722.513586345207, 5841.240638716939, 5847.093700476433, 5853.9121913172185, 5855.063189889597, 5877.0302900021015, 5508.807191748269, 5685.374608792947, 5698.629589076697, 5712.143178523254, 5714.371419143974], "value": 5430.531065967079, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [594.447164137352, 638.0811631250998, 652.7911068458095, 653.3042309939914, 659.7822718502892, 34657.480137797924, 37752.81123594382, 37917.43123853141, 47873.28190077143, 50255.0, 47966.52033479664, 48676.2698559521, 48699.26951095736, 48735.51264487354, 48807.755961220144, 9345.345825792214, 9502.429854208734, 9503.287253455985, 9509.239260859114, 9509.714708558755, 8235.258826705594, 8506.532140732257, 9288.582013809395, 11046.281991670556, 11328.546858125665, 11486.885131148707, 11546.884531154665, 11549.480273387719, 11568.363739994296, 11577.479013444416, 11268.154888383404, 11285.210035297532, 11337.659870203906, 11342.035926946219, 11345.319280843176, 11137.276077055047, 11169.273997190237, 11179.60871369498, 11191.832122518168, 11195.104391648649, 45836.54163458356, 46051.76974115108, 46167.0, 46287.537124628994, 46415.767921160514, 3549.911252218715, 3621.022323972539, 3701.1857391373856, 3819.1406933439753, 4243.639290660298, 35118.122046948774, 35175.4723679145, 35316.0, 35329.64670353315, 35720.10699732501, 4685.601723853477, 4733.763311834429, 4735.473968261807, 4776.853555146775, 4803.807847686088, 368019.0, 368346.0, 370305.0, 370453.0, 371569.0, 65288.34711652918, 65843.34156658468, 65887.0, 65917.67041164763, 66024.33975660279, 40614.0, 42089.0, 42287.0, 44208.557914421086, 44239.0, 17731.0, 17899.910500447415, 17900.820991790177, 17909.820901791078, 17916.0, 11011.504482298316, 13436.126651767603, 13537.120087194498, 13539.59381218565, 13549.0, 18214.089295535436, 20488.89755551213, 21145.577088458143, 21372.251971180933, 21847.453813654785, 7421.591812450259, 7524.811879702942, 7557.206493318233, 7643.541387516767, 7662.578558179353, 1004.5931397783909, 1010.5959150545156, 1068.3429690740138, 1109.0351394287022, 1119.6641007697658, 35529.8223508886, 35957.482976191415, 36103.63896361055, 36223.4566481503, 36288.45567316492, 1063.4895250279908, 1127.1940562497853, 1130.46867972053, 1169.1582060916128, 1179.5871444994184, 18296.451106466826, 18378.908105459388, 18380.264789408273, 18496.630067398575, 18773.1552080154, 569029.0, 577448.0, 577582.0, 578642.0, 580673.0, 65932.34067659358, 66301.0, 66595.33404665989, 66720.0, 66972.66513667401, 1293.489071816627, 1342.5032737887045, 1400.9229492378017, 1420.971580568383, 1451.4629587052857, 31593.21016974502, 36455.0, 36869.63130368662, 37208.81395593005, 37330.81334593309, 56414.71792641011, 60472.0, 63166.368336316045, 63230.683846580476, 69506.0, 493910.0, 494087.0, 494346.0, 494477.0, 494738.0, 31508.36983260368, 32235.194120147185, 32383.35233295368, 33951.320973579896, 48501.27248091211, 53633.0, 53821.730891345294, 53839.46160538501, 55457.0, 55527.72236138793, 44520.0, 44861.0, 45051.774741126086, 45282.32076518789, 46113.76943115263, 44448.77775611101, 45334.546654533035, 45867.541324586324, 46535.76732116318, 46573.30140047834, 42575.574244257165, 45105.774471127435, 45913.0, 46332.7683361581, 46479.0, 37913.81043094767, 39694.0, 39960.60039399685, 40192.59807401888, 40811.59188408078, 29595.556066659447, 29955.0, 30046.84976575103, 30220.395592088476, 30543.54184687276], "value": 21953.831811286673, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.54313138930354, 411.98558050468256, 420.94738157730285, 424.12629982236587, 430.7824548602957, 14355.64110897228, 16040.358385664575, 19703.802961970378, 20536.589268214626, 20680.0, 19747.605047899033, 20268.29060982866, 20334.186632534682, 20334.779913205217, 20510.2821401251, 5756.251687280668, 5790.392008839085, 5882.6764527950945, 5922.496587790043, 6001.549883758714, 4367.781610919457, 4567.451905771312, 5850.619709718854, 5927.051671732524, 5945.197398351211, 6374.26695929967, 6600.834979125512, 6636.800895973129, 6730.0577919091365, 6735.158105236846, 6465.903011454832, 6611.603303801763, 6641.93358066418, 6652.234992975821, 6697.19633643961, 5761.654300741927, 5911.586188966774, 5913.526917846562, 5914.2902851657645, 5919.5264378850015, 23609.76390236093, 23921.28236152918, 23937.0, 24123.758762412326, 24345.756542434527, 2184.4429670434097, 2227.732672079345, 2424.200013995395, 2786.122371452986, 3094.5977022986867, 14836.0, 15236.314365853563, 15339.846601533953, 15529.0, 15704.136272505008, 1993.292381204666, 2045.6011077839828, 2058.6809044598112, 2069.5240094778287, 2109.4620871677776, 82020.0, 83873.0, 83908.0, 84798.0, 86783.0, 10332.450132480188, 10534.315269507457, 10813.62152324665, 11414.086873050135, 11457.828132578017, 15950.0, 16038.198090095448, 16044.438444654614, 16053.839461605234, 16054.197290135446, 8194.877076843852, 8415.70545030921, 8466.703665371682, 8492.872606910902, 8559.828803423896, 7922.405819563556, 8023.518588884685, 8030.799230019296, 8236.423450358538, 8295.585220738938, 6853.383195512428, 6860.5540639858245, 6933.653317334113, 6980.650967451708, 6994.125734283215, 1990.2337600024096, 2016.6672499037788, 2051.979480205209, 2089.6865470179596, 2105.5683584865064, 916.326500022483, 953.6471505542855, 990.4255531791543, 1012.2256473797551, 1071.1109778883508, 20309.0, 20756.58486830255, 20977.68533471999, 21063.0, 21088.578228435344, 1018.4805749068029, 1022.4683164754434, 1043.9582416703247, 1083.8807731149568, 1089.809283375422, 10919.454027298603, 11366.147538934447, 11401.885981140082, 11428.657140285804, 12037.337946412856, 519944.0, 527626.0, 528990.0, 534969.0, 537489.0, 89863.0, 96243.0, 100179.49910250402, 100969.0, 102603.48698256462, 1014.5789497358513, 1055.9683209503728, 1104.4808939798281, 1134.8921852424007, 1136.9317840929405, 18173.909130454264, 18300.90849545744, 18337.724934126265, 18767.34314298993, 18786.06069696537, 42157.57842421659, 42396.364054538586, 42764.78617606892, 43645.781771090944, 43992.78003609961, 59464.70267648634, 60197.69901150641, 61093.38906610877, 61149.08276375947, 61605.0, 8754.0, 8756.474611523328, 8805.955970220108, 8904.465732056098, 8991.730248092568, 12767.93616031914, 12795.296258705861, 12939.676508087374, 13353.132046416951, 13485.325733713078, 12319.815202772144, 12327.50689972391, 12404.75190496167, 12582.433790479266, 12782.68043298925, 11874.703132421759, 11977.161598688357, 12094.153409261618, 12508.374581271082, 12655.303958282382, 12273.263604183778, 12666.0, 12697.238165710087, 12760.617181484571, 12773.61679149627, 8031.83936321282, 8529.530875801891, 8568.143185681382, 8864.911350886408, 8985.056569060154, 11813.881861181622, 11862.466189021343, 11974.64076077719, 12131.45408456604, 12463.626091217278], "value": 9825.861986738253, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1367.6717587778935, 1455.1269238456928, 1517.4916403004995, 1663.5674724571609, 1835.9173837177318, 7489.325960663539, 8300.501969881803, 8580.270676992448, 8589.269912057483, 8824.029356770767, 8620.827583448327, 8704.564771761417, 8715.041345452011, 8717.346199035066, 8720.956395218014, 2406.651035599841, 2422.2975337152293, 2428.931270241097, 2446.6085426331792, 2447.90208391665, 2372.5610762009082, 2398.916037938664, 2400.6879105716234, 2402.5915594349003, 2424.0, 2393.664886915833, 2401.939951501218, 2407.8194135439826, 2419.0082066352797, 2423.9757602423924, 2319.3505818370713, 2333.404981729665, 2335.2293743064834, 2336.941576460585, 2347.3779448446003, 2306.5386922615376, 2339.660749191358, 2346.1905642553293, 2348.788609025196, 2349.5183487385043, 8519.062903080656, 8650.22148006676, 8684.13158684133, 8755.168259015447, 8781.473111613324, 5224.73876306187, 5369.060414427459, 5376.166694162411, 5377.381601115843, 5381.919271210935, 3467.7225821934185, 3524.2951409718157, 3528.894133176009, 3530.135116896351, 3613.3857244268274, 1123.1183520936108, 1164.604034628222, 1165.7726743285064, 1191.839101721274, 1208.1784386617096, 11589.478473468715, 11666.475008624633, 11685.824712629232, 11768.823467647991, 11791.764164716744, 4369.2790689536505, 4484.775761211927, 4599.839005634787, 4642.930356044662, 4648.604868586141, 9433.481158536346, 9434.433933963985, 9438.764030899145, 9477.857832132524, 9500.049995000583, 5311.229871668586, 5313.229581710631, 5315.3621565412395, 5316.3354580677415, 5326.0945639240645, 4359.411479450234, 4480.4175457191, 4813.542713442217, 4910.852674419773, 5001.0, 4003.199360127986, 4019.196160767858, 4089.734167279117, 4118.91762164755, 4119.032027473541, 1245.0973044542634, 1259.4899065878335, 1266.4934026389333, 1275.8341415616096, 1290.4193113098954, 958.7890664054037, 963.9566219520138, 981.9018098190123, 989.4706332112286, 1009.5557954499997, 5765.942340576625, 5807.477327040587, 5944.64332140073, 6015.0977353396465, 6045.304789949212, 477.6632474105817, 477.84708893153856, 478.7486569550944, 485.1316144102018, 493.0779442442655, 4114.403411505315, 4164.3961625564125, 4210.221109094828, 4217.957820421818, 4282.400463935051, 10437.530311136017, 10680.62617808373, 10795.730106747236, 10803.621873234557, 10804.729881753019, 9928.205743540499, 10647.627333043461, 10651.946740266249, 10705.732356691145, 10711.357318560911, 7478.775636730907, 7673.808154796063, 7674.040744906887, 7678.347340476011, 7680.769576912701, 5740.770369185186, 5764.19301297827, 5884.617499862496, 5886.38192989747, 6021.006533921854, 9882.752931176778, 9929.702108936743, 10001.749956251151, 10004.699859004242, 10012.549435275285, 9614.9038509614, 9925.702228933143, 10110.646127385506, 10206.846897296398, 10229.641962531276, 7010.088688470671, 7152.213256541881, 7455.962720186364, 7655.732049378245, 7794.766157015299, 8458.830823383374, 8467.618957147066, 8469.364797640079, 8469.57652117404, 8471.237588617054, 7508.962455187908, 7625.51302496008, 7696.422768292288, 7784.377249819888, 7957.283844454027, 7263.418926485974, 7335.486515943832, 7429.257074292527, 7630.465867389229, 7631.465797394129, 6852.931470685229, 6947.44420446353, 6949.30506949301, 6953.895691564731, 6962.303769622998, 5057.544820966131, 5074.264231686311, 5107.693538387709, 5137.640365174402, 5228.581713462838, 4964.156093464047, 4979.153543897473, 5195.0, 5300.3639563252655, 5381.73091345439], "value": 4533.752976280689, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.50919384713694, 333.4647890136331, 338.29296769751215, 342.17706416069353, 347.05427709491653, 5302.628815982873, 5452.700101494416, 5462.918056229155, 5531.557475401968, 5551.639848237178, 5242.659227150232, 5364.356277246736, 5408.91886621701, 5438.184272359148, 5444.0, 4275.764832934187, 4286.035641980545, 4295.205387003399, 4296.228932338639, 4296.806643701026, 4193.895152621179, 4260.72305300156, 4269.701120921537, 4272.401863739079, 4280.357946308063, 4242.427272318244, 4249.51130619978, 4253.1493701259715, 4261.870604289866, 4264.55222201669, 4237.643953934742, 4256.765877876715, 4258.957410425887, 4264.33902745075, 4277.422547956017, 3673.4489826526233, 3769.378052621314, 3772.9622703772884, 3773.226488569864, 3777.8288730493637, 5311.282976798121, 5314.0700377433795, 5320.228566857784, 5321.733913304358, 5324.760385782611, 4876.756162191911, 4886.75566221691, 4888.364512613338, 4894.583960363338, 4913.2384480405235, 5453.481919217668, 5700.857478563028, 5710.77156913723, 5713.6000479966415, 5718.628289161191, 4084.5711200323835, 4103.671706263492, 4111.239420707178, 4113.4035564843225, 4126.195391898581, 5863.677497737623, 5866.2960444746905, 5867.207926929896, 5900.436384358149, 5965.970170149121, 4116.620931987818, 4224.324108142682, 4226.387173859773, 4240.088380998101, 4278.358246263086, 4281.357796330575, 4309.396684464177, 4323.746113627076, 4330.501992270866, 4332.783360832009, 4890.559849613552, 4892.755362231874, 4893.339399181065, 4898.828541001048, 4899.902001959941, 5583.246261754693, 5679.914801277984, 5736.196932429463, 5742.655440673573, 5743.483086522233, 1490.053815826936, 1511.3425659838006, 1512.9243537823286, 1514.4775052606833, 1516.9608817959734, 923.7667713602282, 957.3442192098435, 960.3085778239656, 962.9374090684083, 965.5510187762724, 4894.853154405374, 4906.55840974314, 4909.877253068702, 4910.656254062182, 4921.729304888266, 5100.744962751847, 5123.231515272739, 5172.456892026358, 5186.247994040843, 5197.636165468457, 4757.977034937436, 4917.795140190676, 4925.310456536088, 4925.7783399747095, 4965.783383071099, 2744.8353098814137, 2748.6151938728594, 2751.614773931651, 2754.986225068862, 2758.4896794092756, 5248.527632513016, 5249.947500524946, 5279.894402111936, 5283.550898173698, 5290.576753859682, 4908.8772780680765, 5220.712860792617, 5234.048293538431, 5264.815731449457, 5269.919666468365, 5259.8422047338645, 5267.629566695003, 5304.124819404755, 5308.23030660552, 5315.388730295988, 5101.540861322499, 5108.642395032312, 5115.411727651368, 5148.510891465455, 5161.845144645667, 5065.594752419724, 5412.944475827176, 5821.049948267378, 5875.0, 5876.147958546072, 6044.697765111815, 6049.485793707408, 6051.243594550681, 6062.454379105901, 6066.18106555627, 3930.194310166466, 4026.375911733686, 4077.6737860970456, 4206.0, 4212.389203565527, 5837.854053648693, 5858.326292476419, 5865.354810970704, 5901.7639294427745, 5905.586608937333, 5626.85932851682, 5735.3977832327, 5743.971280143573, 5761.596688231783, 5762.798302059407, 5697.515711164598, 5736.225609542658, 5748.885022299447, 5751.48236658686, 5781.913271300849, 5684.8010319638615, 5685.971570142123, 5688.232088668143, 5696.829095127153, 5700.914486282792, 5590.720463976866, 5728.398518155534, 5739.253896993364, 5777.62445441045, 5785.247917770663, 5596.4123767003875, 5597.972010139923, 5625.568403273918, 5628.0, 5636.408177141341], "value": 4244.557046875449, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [782.816038231016, 816.2042009041186, 875.916787905149, 885.2829208341244, 905.5200743605891, 16128.677426451479, 16978.0, 17087.914560427213, 17091.743623845636, 17135.400260990868, 14467.421303147888, 15122.546323610282, 15558.377664893418, 15642.765358519631, 15673.686526269468, 878.7715194049529, 888.8888888888889, 889.3330002498126, 889.7419748272993, 889.8442772514817, 732.5934106570851, 746.947713660044, 854.2909385210291, 887.2813021452612, 894.2577660541734, 922.395830730869, 922.5525620074259, 922.8892532896028, 923.3905622289291, 923.5243849417556, 919.14978644754, 919.9954000230023, 920.0753242990752, 920.4155361345561, 921.3504479342081, 550.7081246939129, 565.1071307334402, 660.6828722213364, 675.5710124071201, 676.4149011105359, 15577.766333505006, 15699.215039248105, 15720.0, 15736.92131539335, 15739.0, 14698.33857476416, 15113.924430377778, 15326.616834579114, 15711.0, 15713.13577753223, 13515.459381624723, 13525.797113043312, 13558.93220533891, 13578.388972496261, 13807.309634518233, 4388.144311859189, 4468.82124715011, 4569.771511424448, 4598.356230127785, 4611.515790841947, 79997.60001199957, 80726.59636701837, 81068.59465702633, 83319.58340208321, 83911.0, 12309.630711078682, 12444.253344799341, 12563.685907852194, 12609.810852837032, 12678.746425071447, 23163.189288374826, 23327.066917323456, 23372.76627233706, 23378.18176363819, 23389.181378651665, 8575.356848236408, 8629.39594228398, 8783.121687831166, 8835.867461988075, 8839.248663863644, 4934.087193869074, 4987.700737955734, 4997.200447928314, 5037.420696619935, 5064.797408103635, 3466.9826650866585, 3584.4981702561663, 3599.4960705501253, 3603.747737658339, 3606.549181352331, 540.2706346432285, 610.0422336930976, 611.6452457574596, 612.3325575123138, 616.8766246750578, 613.3253421236604, 641.7625478572958, 720.4560556779597, 735.4300417176623, 755.8299382638972, 9838.803223935482, 9928.0, 9973.850392244121, 10066.899331006744, 10337.37975721459, 634.6002018728171, 695.6765104226558, 726.8910059616954, 728.2680905689748, 740.1303468424579, 4572.177008138567, 4862.5380588844, 4867.0, 4867.610591152699, 4938.111139994764, 73925.0, 74116.62941685256, 74161.0, 121174.78825211812, 124226.0, 85047.0, 108285.4585727082, 108580.0, 110418.0, 112809.43595281971, 85890.57054714687, 88177.55911220532, 89302.55348723214, 91986.0, 92047.5397623021, 9693.321467497208, 9899.564563138161, 10095.44475053879, 10148.543315550965, 10378.636747714096, 43585.0, 46540.30189547227, 48260.27609585929, 49540.50459495503, 53633.73183134059, 67232.0, 67898.3210167892, 69099.30900690929, 75092.0, 76135.23864761282, 13539.661508462368, 13647.044706870822, 14032.649183770487, 14721.822254219422, 14756.926215369283, 13670.726585468035, 13709.0, 13951.0, 16292.266847991632, 16441.17794110266, 12849.871501284868, 13062.738745224851, 13682.042257041912, 14702.77945830792, 14823.629409264855, 13263.535776247785, 14543.272836358352, 14705.485308014167, 15248.618784530032, 15588.0, 14800.925995370386, 15250.084994899898, 15610.063396196265, 15841.445549405715, 15974.8402515978, 11776.823347649963, 12492.25046497213, 12606.432710528232, 13098.410571524113, 13233.338333083115, 12176.57381991626, 12453.564125255572, 12622.55821046259, 12685.175463595206, 12964.935175324063], "value": 7907.747148944096, "unit": "score"}], "name": "dromaeo_css", "value": 7502.154240013271, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 112.0, 110.0, 113.0, 110.0, 110.0, 111.0, 110.0, 109.0, 110.0], "value": 110.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 121.0, 116.0, 153.0, 125.0, 123.0, 122.0, 123.0, 147.0, 123.0], "value": 130.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 159.0, 163.0, 162.0, 160.0, 159.0, 160.0, 159.0, 158.0, 166.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 76.0, 109.0, 76.0, 76.0, 75.0, 76.0, 95.0, 75.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 125.0, 82.0, 82.0, 82.0, 104.0, 82.0, 82.0, 106.0], "value": 90.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 145.0, 145.0, 145.0, 146.0, 147.0, 145.0, 145.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 52.0, 51.0, 49.0, 49.0, 50.0, 52.0, 51.0, 50.0, 52.0], "value": 50.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 59.0, 58.0, 58.0, 58.0, 57.0, 57.0, 58.0, 59.0], "value": 58.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 72.0, 79.0, 72.0, 73.0, 73.0, 72.0, 73.0, 73.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 121.0, 121.0, 125.0, 126.0, 122.0, 120.0, 122.0, 164.0, 121.0], "value": 126.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 157.0, 150.0, 153.0, 152.0, 151.0, 152.0, 154.0, 156.0, 152.0], "value": 152.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 64.0, 62.0, 61.0, 62.0, 63.0, 61.0, 67.0, 60.0, 60.0], "value": 62.2, "unit": "score"}], "name": "kraken", "value": 1453.3999999999999, "alertThreshold": 2.0}]} 22:45:10 INFO - Return code: 0 22:45:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:45:10 INFO - # TBPL SUCCESS # 22:45:10 INFO - Running post-action listener: _resource_record_post_action 22:45:10 INFO - [mozharness: 2016-10-05 05:45:10.368813Z] Finished run-tests step (success) 22:45:10 INFO - Running post-run listener: _resource_record_post_run 22:45:10 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 2134016; Write bytes: 368386048; Read time: 464; Write time: 278624 22:45:10 INFO - TinderboxPrint: CPU usage
13.0% 22:45:10 INFO - TinderboxPrint: I/O read bytes / time
2,134,016 / 464 22:45:10 INFO - TinderboxPrint: I/O write bytes / time
368,386,048 / 278,624 22:45:10 INFO - TinderboxPrint: CPU idle
8,264.1 (87.0%) 22:45:10 INFO - TinderboxPrint: CPU user
1,187.9 (12.5%) 22:45:10 INFO - TinderboxPrint: Swap in / out
0 / 0 22:45:10 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 118743040; Read time: 0; Write time: 82020 22:45:10 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 262144; Write bytes: 249643008; Read time: 16; Write time: 196604 22:45:10 INFO - Running post-run listener: _upload_blobber_files 22:45:10 INFO - Blob upload gear active. 22:45:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:45:10 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 22:45:10 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'] 22:45:10 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 22:45:11 INFO - (blobuploader) - INFO - Open directory for files ... 22:45:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:45:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:45:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:45:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:45:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:45:12 INFO - (blobuploader) - INFO - Done attempting. 22:45:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:45:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:45:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:45:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:45:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:45:15 INFO - (blobuploader) - INFO - Done attempting. 22:45:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:45:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:45:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:45:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:45:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:45:16 INFO - (blobuploader) - INFO - Done attempting. 22:45:16 INFO - (blobuploader) - INFO - Iteration through files over. 22:45:16 INFO - Return code: 0 22:45:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:45:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:45:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/27be1d8d3c0e631583dab52293b8805d5963e6e43fd10d350dc15d14f3afcf247a3bee793f09f16538ab835e208fa214bf6c3aa8b1ab37eb8ac037205149f25e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aecbca11f2dbb16751f076af878f0b011894b0df80a967afc965d0107954421368510901eca5c3cee7592c23bd5bfc7f1798f6b7a4baf4771dcd2bada3442bd1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d01ee1ec7638b0d9e6f2e5e2a60e64b260c8bf1e11a503eec6053c40d2b9bfc7ab04edca64a7057d1073f78789305c9afe2b865ed1ac212979bbadcf0ebcde86"} 22:45:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:45:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:45:16 INFO - Contents: 22:45:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/27be1d8d3c0e631583dab52293b8805d5963e6e43fd10d350dc15d14f3afcf247a3bee793f09f16538ab835e208fa214bf6c3aa8b1ab37eb8ac037205149f25e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aecbca11f2dbb16751f076af878f0b011894b0df80a967afc965d0107954421368510901eca5c3cee7592c23bd5bfc7f1798f6b7a4baf4771dcd2bada3442bd1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d01ee1ec7638b0d9e6f2e5e2a60e64b260c8bf1e11a503eec6053c40d2b9bfc7ab04edca64a7057d1073f78789305c9afe2b865ed1ac212979bbadcf0ebcde86"} 22:45:16 INFO - Running post-run listener: copy_logs_to_upload_dir 22:45:16 INFO - Copying logs to upload dir... 22:45:16 INFO - mkdir: /builds/slave/test/build/upload/logs 22:45:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.362355 ========= master_lag: 0.69 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-10-04 22:45:16.907199) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-04 22:45:16.913536) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1475644553.93747-1976780682 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/27be1d8d3c0e631583dab52293b8805d5963e6e43fd10d350dc15d14f3afcf247a3bee793f09f16538ab835e208fa214bf6c3aa8b1ab37eb8ac037205149f25e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aecbca11f2dbb16751f076af878f0b011894b0df80a967afc965d0107954421368510901eca5c3cee7592c23bd5bfc7f1798f6b7a4baf4771dcd2bada3442bd1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d01ee1ec7638b0d9e6f2e5e2a60e64b260c8bf1e11a503eec6053c40d2b9bfc7ab04edca64a7057d1073f78789305c9afe2b865ed1ac212979bbadcf0ebcde86"} build_url:https://queue.taskcluster.net/v1/task/a-3TBdmfSZ-yqsVMBgjvzg/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011670 build_url: 'https://queue.taskcluster.net/v1/task/a-3TBdmfSZ-yqsVMBgjvzg/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/27be1d8d3c0e631583dab52293b8805d5963e6e43fd10d350dc15d14f3afcf247a3bee793f09f16538ab835e208fa214bf6c3aa8b1ab37eb8ac037205149f25e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aecbca11f2dbb16751f076af878f0b011894b0df80a967afc965d0107954421368510901eca5c3cee7592c23bd5bfc7f1798f6b7a4baf4771dcd2bada3442bd1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d01ee1ec7638b0d9e6f2e5e2a60e64b260c8bf1e11a503eec6053c40d2b9bfc7ab04edca64a7057d1073f78789305c9afe2b865ed1ac212979bbadcf0ebcde86"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-04 22:45:16.938880) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 22:45:16.939199) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1475644553.93747-1976780682 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004541 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-04 22:45:16.988375) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-04 22:45:16.991703) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-04 22:45:16.992016) ========= ========= Total master_lag: 0.83 =========